-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2023-03-30 16:14:49 UTC. -- Mar 30 16:13:09 scooter ntpd[614]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 16:13:29 scooter ntpd[614]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 16:13:39 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Mar 30 16:13:49 scooter ntpd[614]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 16:14:06 scooter dhcpcd[725]: wlan0: carrier lost Mar 30 16:14:06 scooter wpa_supplicant[713]: wlan0: Associated with 68:d7:9a:59:36:4a Mar 30 16:14:06 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-CONNECTED - Connection to 68:d7:9a:59:36:4a completed [id=0 id_str=] Mar 30 16:14:06 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 30 16:14:06 scooter avahi-daemon[446]: Withdrawing address record for 169.254.153.40 on wlan0. Mar 30 16:14:06 scooter avahi-daemon[446]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.153.40. Mar 30 16:14:06 scooter avahi-daemon[446]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 30 16:14:06 scooter dhcpcd[725]: wlan0: deleting route to 169.254.0.0/16 Mar 30 16:14:06 scooter dhcpcd[725]: wlan0: deleting default route Mar 30 16:14:06 scooter volumio[813]: info: Discovery: A device disappeared from network Mar 30 16:14:06 scooter dhcpcd[725]: wlan0: carrier acquired Mar 30 16:14:06 scooter dhcpcd[725]: wlan0: IAID eb:3b:78:6f Mar 30 16:14:07 scooter dhcpcd[725]: wlan0: soliciting an IPv6 router Mar 30 16:14:07 scooter dhcpcd[725]: wlan0: soliciting a DHCP lease Mar 30 16:14:08 scooter ntpd[614]: Deleting interface #3 wlan0, 169.254.153.40#123, interface stats: received=0, sent=0, dropped=0, active_time=1400 secs Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter ntpd[614]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 16:14:09 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 14 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 16 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 17 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 18 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 19 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 20 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 21 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 22 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 23 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 24 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 25 Mar 30 16:14:10 scooter volumio[813]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 26 Mar 30 16:14:12 scooter ntpd[614]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 16:14:12 scooter dhcpcd[725]: wlan0: probing for an IPv4LL address Mar 30 16:14:12 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-DISCONNECTED bssid=68:d7:9a:59:36:4a reason=2 Mar 30 16:14:12 scooter dhcpcd[725]: wlan0: carrier lost Mar 30 16:14:12 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Mar 30 16:14:12 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Mar 30 16:14:15 scooter wpa_supplicant[713]: wlan0: Trying to associate with SSID 'FZL_MM' Mar 30 16:14:22 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=18:e8:29:aa:f0:61 status_code=16 Mar 30 16:14:26 scooter wpa_supplicant[713]: wlan0: Trying to associate with SSID 'FZL_MM' Mar 30 16:14:28 scooter ntpd[614]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 16:14:29 scooter volumio[813]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 16:14:29 scooter volumio[813]: Error: send ENETUNREACH 255.255.255.255:3483 Mar 30 16:14:29 scooter volumio[813]: at doSend (dgram.js:692:16) Mar 30 16:14:29 scooter volumio[813]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Mar 30 16:14:29 scooter volumio[813]: at afterDns (dgram.js:638:5) Mar 30 16:14:29 scooter volumio[813]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Mar 30 16:14:29 scooter volumio[813]: errno: -101, Mar 30 16:14:29 scooter volumio[813]: code: 'ENETUNREACH', Mar 30 16:14:29 scooter volumio[813]: syscall: 'send', Mar 30 16:14:29 scooter volumio[813]: address: '255.255.255.255', Mar 30 16:14:29 scooter volumio[813]: port: 3483 Mar 30 16:14:29 scooter volumio[813]: } Mar 30 16:14:29 scooter volumio[813]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 16:14:30 scooter sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-03-30 16:13 Mar 30 16:14:30 scooter sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:30 scooter sudo[1232]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:30 scooter volumio-remote-updater[445]: [2023-03-30 16:14:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 30 16:14:30 scooter volumio-remote-updater[445]: [2023-03-30 16:14:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 30 16:14:30 scooter systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 30 16:14:30 scooter systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 30 16:14:30 scooter systemd[1]: Started dynamicswap service. Mar 30 16:14:30 scooter systemd[1]: dynamicswap.service: Succeeded. Mar 30 16:14:30 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=18:e8:29:aa:f0:61 status_code=16 Mar 30 16:14:30 scooter systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 30 16:14:30 scooter systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 30 16:14:30 scooter systemd[1]: Started dynamicswap service. Mar 30 16:14:30 scooter systemd[1]: Stopped Volumio Backend Module. Mar 30 16:14:30 scooter systemd[1]: Started Volumio Backend Module. Mar 30 16:14:30 scooter systemd[1]: dynamicswap.service: Succeeded. Mar 30 16:14:32 scooter ntpd[614]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 16:14:32 scooter volumio[1246]: info: ------------------------------------------- Mar 30 16:14:32 scooter volumio[1246]: info: ----- Volumio3 ---- Mar 30 16:14:32 scooter volumio[1246]: info: ------------------------------------------- Mar 30 16:14:32 scooter volumio[1246]: info: ----- System startup ---- Mar 30 16:14:32 scooter volumio[1246]: info: ------------------------------------------- Mar 30 16:14:33 scooter volumio[1246]: info: MYVOLUMIO Environment detected Mar 30 16:14:33 scooter volumio[1246]: info: Plugin folders cleanup Mar 30 16:14:33 scooter volumio[1246]: info: Scanning into folder /volumio/app/plugins/ Mar 30 16:14:33 scooter volumio[1246]: info: Scanning category audio_interface Mar 30 16:14:33 scooter volumio[1246]: info: Scanning category miscellanea Mar 30 16:14:33 scooter volumio[1246]: info: Scanning category music_service Mar 30 16:14:33 scooter volumio[1246]: info: Scanning category plugins.json Mar 30 16:14:33 scooter volumio[1246]: info: Scanning category system_controller Mar 30 16:14:33 scooter volumio[1246]: info: Scanning category user_interface Mar 30 16:14:33 scooter volumio[1246]: info: Scanning into folder /data/plugins/ Mar 30 16:14:33 scooter volumio[1246]: info: Scanning category music_service Mar 30 16:14:33 scooter volumio[1246]: info: Plugin folders cleanup completed Mar 30 16:14:33 scooter volumio[1246]: info: ------------------------------------------- Mar 30 16:14:33 scooter volumio[1246]: info: ----- Core plugins startup ---- Mar 30 16:14:33 scooter volumio[1246]: info: ------------------------------------------- Mar 30 16:14:33 scooter volumio[1246]: info: Loading plugins from folder /volumio/app/plugins/ Mar 30 16:14:33 scooter volumio[1246]: info: Adding plugin upnp to MyMusic Plugins Mar 30 16:14:33 scooter volumio[1246]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 30 16:14:33 scooter volumio[1246]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 30 16:14:33 scooter volumio[1246]: info: Loading plugins from folder /data/plugins/ Mar 30 16:14:33 scooter volumio[1246]: info: Loading plugin "system"... Mar 30 16:14:33 scooter volumio[1246]: info: Loading plugin "appearance"... Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "network"... Mar 30 16:14:34 scooter volumio[1246]: info: Refreshing Cached IP Addresses Mar 30 16:14:34 scooter sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 16:14:34 scooter sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:34 scooter sudo[1269]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:34 scooter sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 16:14:34 scooter sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "services"... Mar 30 16:14:34 scooter sudo[1271]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "alsa_controller"... Mar 30 16:14:34 scooter sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 16:14:34 scooter sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:34 scooter sudo[1274]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:34 scooter ntpd[614]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 16:14:34 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "wizard"... Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "volumio_command_line_client"... Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "upnp"... Mar 30 16:14:34 scooter volumio[1246]: info: [1680192874336] Starting Upmpd Daemon Mar 30 16:14:34 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "my_music"... Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "mpd"... Mar 30 16:14:34 scooter volumio[1246]: info: Creating MPD Configuration file Mar 30 16:14:34 scooter volumio[1246]: info: Loading plugin "upnp_browser"... Mar 30 16:14:34 scooter sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 16:14:34 scooter sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:34 scooter sudo[1282]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:34 scooter sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 16:14:34 scooter sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:34 scooter systemd[1]: Stopping Music Player Daemon... Mar 30 16:14:34 scooter systemd[1]: mpd.service: Succeeded. Mar 30 16:14:34 scooter systemd[1]: Stopped Music Player Daemon. Mar 30 16:14:34 scooter systemd[1]: Starting Music Player Daemon... Mar 30 16:14:35 scooter volumio[1246]: info: Loading plugin "networkfs"... Mar 30 16:14:35 scooter volumio[1246]: info: Starting Udev Watcher for removable devices Mar 30 16:14:35 scooter wpa_supplicant[713]: wlan0: Trying to associate with SSID 'FZL_MM' Mar 30 16:14:35 scooter volumio-remote-updater[445]: [2023-03-30 16:14:35] [connect] Successful connection Mar 30 16:14:35 scooter volumio[1246]: info: Ignoring mount for partition: boot Mar 30 16:14:35 scooter volumio[1246]: info: Ignoring mount for partition: volumio Mar 30 16:14:35 scooter volumio[1246]: info: Ignoring mount for partition: volumio_data Mar 30 16:14:35 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 16:14:35 scooter volumio[1246]: info: Loading plugin "alarm-clock"... Mar 30 16:14:35 scooter volumio[1246]: info: Loading plugin "airplay_emulation"... Mar 30 16:14:35 scooter volumio[1246]: info: Starting Shairport Sync Mar 30 16:14:35 scooter volumio[1246]: info: Loading plugin "last_100"... Mar 30 16:14:35 scooter volumio[1246]: info: Loading plugin "webradio"... Mar 30 16:14:35 scooter volumio[1246]: info: Loading plugin "i2s_dacs"... Mar 30 16:14:35 scooter volumio[1246]: info: I2S DAC not set, start Auto-detection Mar 30 16:14:35 scooter volumio[1246]: info: Loading plugin "volumiodiscovery"... Mar 30 16:14:35 scooter volumio[1246]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 16:14:35 scooter volumio[1246]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 16:14:35 scooter volumio[1246]: *** WARNING *** For more information see Mar 30 16:14:35 scooter node[1246]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 16:14:35 scooter volumio[1246]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 16:14:35 scooter volumio[1246]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 16:14:35 scooter volumio[1246]: *** WARNING *** For more information see Mar 30 16:14:35 scooter node[1246]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 16:14:35 scooter node[1246]: *** WARNING *** For more information see Mar 30 16:14:35 scooter node[1246]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 16:14:35 scooter node[1246]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 16:14:35 scooter node[1246]: *** WARNING *** For more information see Mar 30 16:14:35 scooter volumio[1246]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 30 16:14:35 scooter volumio[1246]: info: Discovery: Started advertising with name: Volumio Mar 30 16:14:35 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 16:14:35 scooter volumio[1246]: info: Loading plugin "squeezelite_mc"... Mar 30 16:14:36 scooter mpd[1289]: Mar 30 16:14 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 16:14:36 scooter mpd[1289]: Mar 30 16:14 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 30 16:14:36 scooter mpd[1289]: Mar 30 16:14 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 30 16:14:36 scooter systemd[1]: Started Music Player Daemon. Mar 30 16:14:36 scooter sudo[1284]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:36 scooter volumio[1246]: info: Loading plugin "outputs"... Mar 30 16:14:36 scooter volumio[1246]: info: Loading plugin "albumart"... Mar 30 16:14:36 scooter volumio[1246]: info: Plugin example_plugin is not enabled Mar 30 16:14:36 scooter volumio[1246]: info: Loading plugin "inputs"... Mar 30 16:14:36 scooter volumio[1246]: info: Loading plugin "updater_comm"... Mar 30 16:14:36 scooter volumio[1246]: info: Plugin mpdemulation is not enabled Mar 30 16:14:36 scooter volumio[1246]: info: Loading plugin "rest_api"... Mar 30 16:14:36 scooter volumio[1246]: info: Loading plugin "websocket"... Mar 30 16:14:36 scooter volumio[1246]: info: Loading i18n strings for locale de Mar 30 16:14:36 scooter volumio[1246]: Updating browse sources language Mar 30 16:14:36 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::initPlayerControls Mar 30 16:14:37 scooter volumio[1246]: Forking 3 albumart workers Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: Express server listening on port 3000 Mar 30 16:14:37 scooter volumio[1246]: [Metrics] WebUI: 5s 176.06ms Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::resetVolumioState Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::getcurrentVolume Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: Cannot read play queue from file Mar 30 16:14:37 scooter volumio[1246]: info: MPD Permissions set Mar 30 16:14:37 scooter volumio[1246]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 16:14:37 scooter volumio[1246]: wlan0 Interface doesn't support scanning : Device or resource busy Mar 30 16:14:37 scooter volumio[1246]: info: Cannot use regular scanning, forcing with ap-force Mar 30 16:14:37 scooter sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 16:14:37 scooter sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:37 scooter sudo[1330]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:37 scooter volumio[1246]: command failed: Resource temporarily unavailable (-11) Mar 30 16:14:37 scooter volumio[1246]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 16:14:37 scooter volumio[1246]: command failed: Resource temporarily unavailable (-11) Mar 30 16:14:37 scooter kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Mar 30 16:14:37 scooter volumio-remote-updater[445]: [2023-03-30 16:14:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1680192875 101 Mar 30 16:14:37 scooter volumio[1246]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 30 16:14:37 scooter volumio[1246]: info: MPD running with PID1289 Mar 30 16:14:37 scooter volumio[1246]: ,establishing connection Mar 30 16:14:37 scooter volumio[1246]: error: MPD error: The expression evaluated to a falsy value: Mar 30 16:14:37 scooter volumio[1246]: assert.ok(self.idling) Mar 30 16:14:37 scooter volumio[1246]: error: The expression evaluated to a falsy value: Mar 30 16:14:37 scooter volumio[1246]: assert.ok(self.idling) Mar 30 16:14:37 scooter volumio[1246]: error: MPD error: The expression evaluated to a falsy value: Mar 30 16:14:37 scooter volumio[1246]: assert.ok(self.idling) Mar 30 16:14:37 scooter volumio[1246]: error: The expression evaluated to a falsy value: Mar 30 16:14:37 scooter volumio[1246]: assert.ok(self.idling) Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: VolumeController:: Volume=86 Mute =false Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::pushState Mar 30 16:14:37 scooter volumio[1246]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::updateTrackBlock Mar 30 16:14:37 scooter volumio[1246]: info: CorePlayQueue::getTrackBlock Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::setRepeat null single undefined Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::pushState Mar 30 16:14:37 scooter volumio[1246]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::setRandom null Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::pushState Mar 30 16:14:37 scooter volumio[1246]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:37 scooter volumio[1246]: info: Setting Device type: Raspberry PI Mar 30 16:14:37 scooter volumio[1246]: error: updateQueue error: null Mar 30 16:14:37 scooter volumio[1246]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 30 16:14:37 scooter volumio[1246]: info: Completed loading Core Plugins Mar 30 16:14:37 scooter volumio[1246]: info: Preparing to generate the ALSA configuration file Mar 30 16:14:37 scooter volumio[1246]: info: VolumeController:: Volume=86 Mute =false Mar 30 16:14:37 scooter volumio[1246]: info: CoreStateMachine::pushState Mar 30 16:14:37 scooter volumio[1246]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:37 scooter volumio[1246]: info: Asound.conf file unchanged, so no further update is needed Mar 30 16:14:37 scooter volumio[1246]: info: Output device has changed, restarting MPD Mar 30 16:14:37 scooter volumio[1246]: info: Output device has changed, restarting Shairport Sync Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:37 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:37 scooter sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 16:14:37 scooter sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 16:14:38 scooter sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:38 scooter sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:38 scooter sudo[1345]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:38 scooter systemd[1]: Stopping Music Player Daemon... Mar 30 16:14:38 scooter volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 16:14:38 scooter volumio[1246]: info: ___________ START PLUGINS ___________ Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 16:14:38 scooter volumio[1246]: Starting albumart workers Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 16:14:38 scooter volumio[1246]: info: [1680192878068] CoreMusicLibrary::Adding element Media Servers Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 16:14:38 scooter volumio[1246]: Cannot find translation for sourceMedia Servers Mar 30 16:14:38 scooter systemd[1]: mpd.service: Succeeded. Mar 30 16:14:38 scooter systemd[1]: Stopped Music Player Daemon. Mar 30 16:14:38 scooter systemd[1]: Starting Music Player Daemon... Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:38 scooter volumio[1246]: Starting albumart workers Mar 30 16:14:38 scooter volumio[1246]: Starting albumart workers Mar 30 16:14:38 scooter volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:38 scooter volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 16:14:38 scooter volumio[1246]: info: [1680192878504] CoreMusicLibrary::Adding element Last_100 Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 16:14:38 scooter volumio[1246]: Cannot find translation for sourceMedia Servers Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 16:14:38 scooter volumio[1246]: info: [1680192878510] CoreMusicLibrary::Adding element Webradio Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 16:14:38 scooter volumio[1246]: Cannot find translation for sourceMedia Servers Mar 30 16:14:38 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 16:14:38 scooter volumio[1246]: info: [squeezelite_mc] Starting proxy server... Mar 30 16:14:38 scooter volumio[1246]: info: Volumio Calling Home Mar 30 16:14:38 scooter volumio[1246]: info: [squeezelite_mc] Proxy server started on port 36149 Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 16:14:39 scooter volumio[1246]: Unhandled rejection Error: No sockets available, cannot start. Mar 30 16:14:39 scooter volumio[1246]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Mar 30 16:14:39 scooter volumio[1246]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Mar 30 16:14:39 scooter volumio[1246]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Mar 30 16:14:39 scooter volumio[1246]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Mar 30 16:14:39 scooter volumio[1246]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Mar 30 16:14:39 scooter volumio[1246]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Mar 30 16:14:39 scooter volumio[1246]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Mar 30 16:14:39 scooter volumio[1246]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Mar 30 16:14:39 scooter volumio[1246]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Mar 30 16:14:39 scooter volumio[1246]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Mar 30 16:14:39 scooter volumio[1246]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Mar 30 16:14:39 scooter volumio[1246]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 30 16:14:39 scooter volumio[1246]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 30 16:14:39 scooter volumio[1246]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Mar 30 16:14:39 scooter volumio[1246]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Mar 30 16:14:39 scooter volumio[1246]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 30 16:14:39 scooter volumio[1246]: info: MPD Permissions set Mar 30 16:14:39 scooter volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: VolumeController:: Volume=86 Mute =false Mar 30 16:14:39 scooter volumio[1246]: info: CoreStateMachine::pushState Mar 30 16:14:39 scooter volumio[1246]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:39 scooter volumio[1246]: info: [squeezelite_mc] Server discovery started Mar 30 16:14:39 scooter volumio[1246]: info: [squeezelite_mc] Player finder started Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 16:14:39 scooter volumio[1246]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Mar 30 16:14:39 scooter volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 16:14:39 scooter volumio[1246]: Error: send ENETUNREACH 255.255.255.255:3483 Mar 30 16:14:39 scooter volumio[1246]: at doSend (dgram.js:692:16) Mar 30 16:14:39 scooter volumio[1246]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Mar 30 16:14:39 scooter volumio[1246]: at afterDns (dgram.js:638:5) Mar 30 16:14:39 scooter volumio[1246]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Mar 30 16:14:39 scooter volumio[1246]: errno: -101, Mar 30 16:14:39 scooter volumio[1246]: code: 'ENETUNREACH', Mar 30 16:14:39 scooter volumio[1246]: syscall: 'send', Mar 30 16:14:39 scooter volumio[1246]: address: '255.255.255.255', Mar 30 16:14:39 scooter volumio[1246]: port: 3483 Mar 30 16:14:39 scooter volumio[1246]: } Mar 30 16:14:39 scooter volumio[1246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 16:14:39 scooter dhcpcd[725]: wlan0: carrier acquired Mar 30 16:14:39 scooter wpa_supplicant[713]: wlan0: Associated with 68:d7:9a:59:36:4a Mar 30 16:14:39 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-CONNECTED - Connection to 68:d7:9a:59:36:4a completed [id=0 id_str=] Mar 30 16:14:39 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 30 16:14:39 scooter dhcpcd[725]: wlan0: IAID eb:3b:78:6f Mar 30 16:14:39 scooter dhcpcd[725]: wlan0: carrier lost Mar 30 16:14:39 scooter dhcpcd[725]: wlan0: carrier acquired Mar 30 16:14:39 scooter wpa_supplicant[713]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Mar 30 16:14:39 scooter dhcpcd[725]: wlan0: IAID eb:3b:78:6f Mar 30 16:14:39 scooter mpd[1358]: Mar 30 16:14 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 16:14:39 scooter mpd[1358]: Mar 30 16:14 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 30 16:14:39 scooter mpd[1358]: Mar 30 16:14 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 30 16:14:39 scooter systemd[1]: Started Music Player Daemon. Mar 30 16:14:39 scooter sudo[1347]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:39 scooter sudo[1421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-03-30 16:13 Mar 30 16:14:39 scooter sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:39 scooter sudo[1421]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:40 scooter dhcpcd[725]: wlan0: soliciting a DHCP lease Mar 30 16:14:40 scooter volumio-remote-updater[445]: [2023-03-30 16:14:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 30 16:14:40 scooter volumio-remote-updater[445]: [2023-03-30 16:14:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 30 16:14:40 scooter systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 30 16:14:40 scooter systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 30 16:14:40 scooter systemd[1]: Started dynamicswap service. Mar 30 16:14:40 scooter systemd[1]: dynamicswap.service: Succeeded. Mar 30 16:14:40 scooter systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 30 16:14:40 scooter systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Mar 30 16:14:40 scooter systemd[1]: Stopped Volumio Backend Module. Mar 30 16:14:40 scooter systemd[1]: Started Volumio Backend Module. Mar 30 16:14:40 scooter dhcpcd[725]: wlan0: soliciting an IPv6 router Mar 30 16:14:40 scooter systemd[1]: Started dynamicswap service. Mar 30 16:14:40 scooter systemd[1]: dynamicswap.service: Succeeded. Mar 30 16:14:40 scooter dhcpcd[725]: wlan0: offered 172.16.171.182 from 172.16.0.1 Mar 30 16:14:41 scooter volumio[1434]: info: ------------------------------------------- Mar 30 16:14:41 scooter volumio[1434]: info: ----- Volumio3 ---- Mar 30 16:14:41 scooter volumio[1434]: info: ------------------------------------------- Mar 30 16:14:41 scooter volumio[1434]: info: ----- System startup ---- Mar 30 16:14:41 scooter volumio[1434]: info: ------------------------------------------- Mar 30 16:14:42 scooter volumio[1434]: info: MYVOLUMIO Environment detected Mar 30 16:14:42 scooter volumio[1434]: info: Plugin folders cleanup Mar 30 16:14:42 scooter volumio[1434]: info: Scanning into folder /volumio/app/plugins/ Mar 30 16:14:42 scooter volumio[1434]: info: Scanning category audio_interface Mar 30 16:14:42 scooter volumio[1434]: info: Scanning category miscellanea Mar 30 16:14:42 scooter volumio[1434]: info: Scanning category music_service Mar 30 16:14:42 scooter volumio[1434]: info: Scanning category plugins.json Mar 30 16:14:42 scooter volumio[1434]: info: Scanning category system_controller Mar 30 16:14:42 scooter volumio[1434]: info: Scanning category user_interface Mar 30 16:14:42 scooter volumio[1434]: info: Scanning into folder /data/plugins/ Mar 30 16:14:42 scooter volumio[1434]: info: Scanning category music_service Mar 30 16:14:42 scooter volumio[1434]: info: Plugin folders cleanup completed Mar 30 16:14:42 scooter volumio[1434]: info: ------------------------------------------- Mar 30 16:14:42 scooter volumio[1434]: info: ----- Core plugins startup ---- Mar 30 16:14:42 scooter volumio[1434]: info: ------------------------------------------- Mar 30 16:14:42 scooter volumio[1434]: info: Loading plugins from folder /volumio/app/plugins/ Mar 30 16:14:42 scooter volumio[1434]: info: Adding plugin upnp to MyMusic Plugins Mar 30 16:14:42 scooter volumio[1434]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 30 16:14:42 scooter volumio[1434]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 30 16:14:42 scooter volumio[1434]: info: Loading plugins from folder /data/plugins/ Mar 30 16:14:42 scooter volumio[1434]: info: Loading plugin "system"... Mar 30 16:14:42 scooter volumio[1434]: info: Loading plugin "appearance"... Mar 30 16:14:43 scooter volumio[1434]: info: Loading plugin "network"... Mar 30 16:14:43 scooter dhcpcd[725]: wlan0: probing address 172.16.171.182/16 Mar 30 16:14:43 scooter volumio[1434]: info: Refreshing Cached IP Addresses Mar 30 16:14:43 scooter sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 16:14:43 scooter sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:43 scooter sudo[1459]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:43 scooter sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 16:14:43 scooter sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:43 scooter volumio[1434]: info: Loading plugin "services"... Mar 30 16:14:43 scooter volumio[1434]: info: Loading plugin "alsa_controller"... Mar 30 16:14:43 scooter sudo[1461]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:43 scooter sudo[1464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 16:14:43 scooter sudo[1464]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:43 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 16:14:43 scooter volumio[1434]: info: Loading plugin "wizard"... Mar 30 16:14:43 scooter volumio[1434]: info: Loading plugin "volumio_command_line_client"... Mar 30 16:14:43 scooter volumio[1434]: info: Loading plugin "upnp"... Mar 30 16:14:43 scooter volumio[1434]: info: [1680192883837] Starting Upmpd Daemon Mar 30 16:14:43 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 16:14:43 scooter volumio[1434]: info: Loading plugin "my_music"... Mar 30 16:14:43 scooter volumio[1434]: info: Loading plugin "mpd"... Mar 30 16:14:44 scooter volumio[1434]: info: Creating MPD Configuration file Mar 30 16:14:44 scooter volumio[1434]: info: Loading plugin "upnp_browser"... Mar 30 16:14:44 scooter sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 16:14:44 scooter sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:44 scooter sudo[1472]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:44 scooter sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 16:14:44 scooter sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:44 scooter systemd[1]: Stopping Music Player Daemon... Mar 30 16:14:44 scooter systemd[1]: mpd.service: Succeeded. Mar 30 16:14:44 scooter systemd[1]: Stopped Music Player Daemon. Mar 30 16:14:44 scooter systemd[1]: Starting Music Player Daemon... Mar 30 16:14:44 scooter volumio[1434]: info: Loading plugin "networkfs"... Mar 30 16:14:44 scooter volumio[1434]: info: Starting Udev Watcher for removable devices Mar 30 16:14:45 scooter volumio[1434]: info: Ignoring mount for partition: boot Mar 30 16:14:45 scooter volumio[1434]: info: Ignoring mount for partition: volumio Mar 30 16:14:45 scooter volumio[1434]: info: Ignoring mount for partition: volumio_data Mar 30 16:14:45 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 16:14:45 scooter volumio[1434]: info: Loading plugin "alarm-clock"... Mar 30 16:14:45 scooter volumio-remote-updater[445]: [2023-03-30 16:14:45] [connect] Successful connection Mar 30 16:14:45 scooter volumio[1434]: info: Loading plugin "airplay_emulation"... Mar 30 16:14:45 scooter volumio[1434]: info: Starting Shairport Sync Mar 30 16:14:45 scooter volumio[1434]: info: Loading plugin "last_100"... Mar 30 16:14:45 scooter volumio[1434]: info: Loading plugin "webradio"... Mar 30 16:14:45 scooter volumio[1434]: info: Loading plugin "i2s_dacs"... Mar 30 16:14:45 scooter volumio[1434]: info: I2S DAC not set, start Auto-detection Mar 30 16:14:45 scooter volumio[1434]: info: Loading plugin "volumiodiscovery"... Mar 30 16:14:45 scooter volumio[1434]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 16:14:45 scooter volumio[1434]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 16:14:45 scooter node[1434]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 16:14:45 scooter volumio[1434]: *** WARNING *** For more information see Mar 30 16:14:45 scooter volumio[1434]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 16:14:45 scooter volumio[1434]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 16:14:45 scooter volumio[1434]: *** WARNING *** For more information see Mar 30 16:14:45 scooter node[1434]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 16:14:45 scooter node[1434]: *** WARNING *** For more information see Mar 30 16:14:45 scooter node[1434]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 16:14:45 scooter node[1434]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 16:14:45 scooter node[1434]: *** WARNING *** For more information see Mar 30 16:14:45 scooter volumio[1434]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 30 16:14:45 scooter volumio[1434]: info: Discovery: Started advertising with name: Volumio Mar 30 16:14:45 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 16:14:45 scooter volumio[1434]: info: Loading plugin "squeezelite_mc"... Mar 30 16:14:45 scooter mpd[1479]: Mar 30 16:14 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 16:14:45 scooter mpd[1479]: Mar 30 16:14 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 30 16:14:45 scooter mpd[1479]: Mar 30 16:14 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 30 16:14:45 scooter systemd[1]: Started Music Player Daemon. Mar 30 16:14:45 scooter sudo[1474]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:46 scooter volumio[1434]: info: Loading plugin "outputs"... Mar 30 16:14:46 scooter volumio[1434]: info: Loading plugin "albumart"... Mar 30 16:14:46 scooter volumio[1434]: info: Plugin example_plugin is not enabled Mar 30 16:14:46 scooter volumio[1434]: info: Loading plugin "inputs"... Mar 30 16:14:46 scooter volumio[1434]: info: Loading plugin "updater_comm"... Mar 30 16:14:46 scooter volumio[1434]: info: Plugin mpdemulation is not enabled Mar 30 16:14:46 scooter volumio[1434]: info: Loading plugin "rest_api"... Mar 30 16:14:46 scooter volumio[1434]: info: Loading plugin "websocket"... Mar 30 16:14:46 scooter volumio[1434]: info: Loading i18n strings for locale de Mar 30 16:14:46 scooter volumio[1434]: Updating browse sources language Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: Forking 3 albumart workers Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::initPlayerControls Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: Express server listening on port 3000 Mar 30 16:14:46 scooter volumio[1434]: [Metrics] WebUI: 5s 171.25ms Mar 30 16:14:46 scooter volumio[1434]: info: CoreStateMachine::resetVolumioState Mar 30 16:14:46 scooter volumio[1434]: info: CoreStateMachine::getcurrentVolume Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:46 scooter volumio[1434]: info: Cannot read play queue from file Mar 30 16:14:46 scooter volumio[1434]: info: MPD Permissions set Mar 30 16:14:46 scooter volumio-remote-updater[445]: [2023-03-30 16:14:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1680192885 101 Mar 30 16:14:46 scooter volumio[1434]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 30 16:14:46 scooter volumio[1434]: info: MPD running with PID1479 Mar 30 16:14:46 scooter volumio[1434]: ,establishing connection Mar 30 16:14:46 scooter volumio[1434]: info: VolumeController:: Volume=86 Mute =false Mar 30 16:14:46 scooter volumio[1434]: info: CoreStateMachine::pushState Mar 30 16:14:46 scooter volumio[1434]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:46 scooter volumio[1434]: info: CoreStateMachine::updateTrackBlock Mar 30 16:14:46 scooter volumio[1434]: info: CorePlayQueue::getTrackBlock Mar 30 16:14:46 scooter volumio[1434]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: error: updateQueue error: null Mar 30 16:14:47 scooter volumio[1434]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 30 16:14:47 scooter volumio[1434]: info: CoreStateMachine::setRepeat null single undefined Mar 30 16:14:47 scooter volumio[1434]: info: CoreStateMachine::pushState Mar 30 16:14:47 scooter volumio[1434]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:47 scooter volumio[1434]: info: CoreStateMachine::setRandom null Mar 30 16:14:47 scooter volumio[1434]: info: CoreStateMachine::pushState Mar 30 16:14:47 scooter volumio[1434]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:47 scooter volumio[1434]: info: Setting Device type: Raspberry PI Mar 30 16:14:47 scooter volumio[1434]: error: updateQueue error: null Mar 30 16:14:47 scooter volumio[1434]: info: Completed loading Core Plugins Mar 30 16:14:47 scooter volumio[1434]: info: Preparing to generate the ALSA configuration file Mar 30 16:14:47 scooter volumio[1434]: info: Asound.conf file unchanged, so no further update is needed Mar 30 16:14:47 scooter volumio[1434]: info: Output device has changed, restarting MPD Mar 30 16:14:47 scooter volumio[1434]: info: Output device has changed, restarting Shairport Sync Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:47 scooter sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 16:14:47 scooter sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:47 scooter sudo[1532]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:47 scooter sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 16:14:47 scooter sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 16:14:47 scooter volumio[1434]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 16:14:47 scooter volumio[1434]: info: ___________ START PLUGINS ___________ Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 16:14:47 scooter volumio[1434]: info: [1680192887385] CoreMusicLibrary::Adding element Media Servers Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 16:14:47 scooter volumio[1434]: Cannot find translation for sourceMedia Servers Mar 30 16:14:47 scooter systemd[1]: Stopping Music Player Daemon... Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:47 scooter systemd[1]: mpd.service: Succeeded. Mar 30 16:14:47 scooter systemd[1]: Stopped Music Player Daemon. Mar 30 16:14:47 scooter systemd[1]: Starting Music Player Daemon... Mar 30 16:14:47 scooter volumio[1434]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:47 scooter volumio[1434]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 16:14:47 scooter volumio[1434]: info: [1680192887569] CoreMusicLibrary::Adding element Last_100 Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 16:14:47 scooter volumio[1434]: Cannot find translation for sourceMedia Servers Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 16:14:47 scooter volumio[1434]: info: [1680192887576] CoreMusicLibrary::Adding element Webradio Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 16:14:47 scooter volumio[1434]: Cannot find translation for sourceMedia Servers Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 16:14:47 scooter volumio[1434]: Starting albumart workers Mar 30 16:14:47 scooter volumio[1434]: info: [squeezelite_mc] Starting proxy server... Mar 30 16:14:47 scooter volumio[1434]: info: Volumio Calling Home Mar 30 16:14:47 scooter volumio[1434]: info: [squeezelite_mc] Proxy server started on port 36421 Mar 30 16:14:47 scooter volumio[1434]: Starting albumart workers Mar 30 16:14:47 scooter volumio[1434]: Starting albumart workers Mar 30 16:14:47 scooter volumio[1434]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 16:14:47 scooter sudo[1464]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:47 scooter volumio[1434]: Unhandled rejection Error: No sockets available, cannot start. Mar 30 16:14:47 scooter volumio[1434]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Mar 30 16:14:47 scooter volumio[1434]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Mar 30 16:14:47 scooter volumio[1434]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Mar 30 16:14:47 scooter volumio[1434]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Mar 30 16:14:47 scooter volumio[1434]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Mar 30 16:14:47 scooter volumio[1434]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Mar 30 16:14:47 scooter volumio[1434]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Mar 30 16:14:47 scooter volumio[1434]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Mar 30 16:14:47 scooter volumio[1434]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Mar 30 16:14:47 scooter volumio[1434]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Mar 30 16:14:47 scooter volumio[1434]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Mar 30 16:14:47 scooter volumio[1434]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 30 16:14:47 scooter volumio[1434]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 30 16:14:47 scooter volumio[1434]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Mar 30 16:14:47 scooter volumio[1434]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Mar 30 16:14:47 scooter volumio[1434]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 30 16:14:48 scooter volumio[1434]: info: MPD Permissions set Mar 30 16:14:48 scooter volumio[1434]: info: VolumeController:: Volume=86 Mute =false Mar 30 16:14:48 scooter volumio[1434]: info: CoreStateMachine::pushState Mar 30 16:14:48 scooter volumio[1434]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:48 scooter volumio[1434]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: VolumeController:: Volume=86 Mute =false Mar 30 16:14:48 scooter volumio[1434]: info: CoreStateMachine::pushState Mar 30 16:14:48 scooter volumio[1434]: info: CorePlayQueue::getTrack 0 Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::volumioPushState Mar 30 16:14:48 scooter volumio[1434]: info: [squeezelite_mc] Server discovery started Mar 30 16:14:48 scooter volumio[1434]: info: [squeezelite_mc] Player finder started Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 16:14:48 scooter volumio[1434]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Mar 30 16:14:48 scooter volumio[1434]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 16:14:48 scooter volumio[1434]: Error: send ENETUNREACH 255.255.255.255:3483 Mar 30 16:14:48 scooter volumio[1434]: at doSend (dgram.js:692:16) Mar 30 16:14:48 scooter volumio[1434]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Mar 30 16:14:48 scooter volumio[1434]: at afterDns (dgram.js:638:5) Mar 30 16:14:48 scooter volumio[1434]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Mar 30 16:14:48 scooter volumio[1434]: errno: -101, Mar 30 16:14:48 scooter volumio[1434]: code: 'ENETUNREACH', Mar 30 16:14:48 scooter volumio[1434]: syscall: 'send', Mar 30 16:14:48 scooter volumio[1434]: address: '255.255.255.255', Mar 30 16:14:48 scooter volumio[1434]: port: 3483 Mar 30 16:14:48 scooter volumio[1434]: } Mar 30 16:14:48 scooter volumio[1434]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 16:14:48 scooter dhcpcd[725]: wlan0: leased 172.16.171.182 for 86400 seconds Mar 30 16:14:48 scooter avahi-daemon[446]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.171.182. Mar 30 16:14:48 scooter avahi-daemon[446]: New relevant interface wlan0.IPv4 for mDNS. Mar 30 16:14:48 scooter dhcpcd[725]: wlan0: adding route to 172.16.0.0/16 Mar 30 16:14:48 scooter avahi-daemon[446]: Registering new address record for 172.16.171.182 on wlan0.IPv4. Mar 30 16:14:48 scooter dhcpcd[725]: wlan0: adding default route via 172.16.0.1 Mar 30 16:14:49 scooter mpd[1549]: exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 16:14:49 scooter mpd[1549]: output: No 'audio_output' defined in config file Mar 30 16:14:49 scooter mpd[1549]: output: Attempt to detect audio output device Mar 30 16:14:49 scooter mpd[1549]: output: Attempting to detect a alsa audio device Mar 30 16:14:49 scooter mpd[1549]: output: Successfully detected a alsa audio device Mar 30 16:14:49 scooter mpd[1549]: exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 30 16:14:49 scooter mpd[1549]: exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 30 16:14:49 scooter mpd[1549]: zeroconf: No global port, disabling zeroconf Mar 30 16:14:49 scooter systemd[1]: Started Music Player Daemon. Mar 30 16:14:49 scooter sudo[1533]: pam_unix(sudo:session): session closed for user root Mar 30 16:14:49 scooter sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-03-30 16:13 Mar 30 16:14:49 scooter sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"