-- Logs begin at Wed 2024-07-03 01:56:23 EDT, end at Fri 2024-07-05 18:48:34 EDT. -- Jul 05 18:47:04 basement volumio[23993]: info: Loading plugin "outputs"... Jul 05 18:47:04 basement volumio[23993]: info: Loading plugin "albumart"... Jul 05 18:47:05 basement volumio[23993]: info: Plugin example_plugin is not enabled Jul 05 18:47:05 basement volumio[23993]: info: Loading plugin "inputs"... Jul 05 18:47:05 basement volumio[23993]: info: Loading plugin "updater_comm"... Jul 05 18:47:06 basement volumio[23993]: info: Plugin mpdemulation is not enabled Jul 05 18:47:06 basement volumio[23993]: info: Loading plugin "rest_api"... Jul 05 18:47:06 basement volumio[23993]: info: Loading plugin "websocket"... Jul 05 18:47:06 basement volumio[23993]: info: Loading i18n strings for locale en Jul 05 18:47:06 basement volumio[23993]: Updating browse sources language Jul 05 18:47:06 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::initPlayerControls Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 18:47:08 basement volumio[23993]: Express server listening on port 3000 Jul 05 18:47:08 basement volumio[23993]: [Metrics] WebUI: 33s 87.81ms Jul 05 18:47:08 basement volumio[23993]: Forking 1 albumart workers Jul 05 18:47:08 basement volumio[23993]: info: CoreStateMachine::resetVolumioState Jul 05 18:47:08 basement volumio[23993]: info: CoreStateMachine::getcurrentVolume Jul 05 18:47:08 basement volumio[23993]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 18:47:09 basement sudo[24052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 18:47:09 basement sudo[24052]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:09 basement sudo[24054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 18:47:09 basement sudo[24052]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:09 basement sudo[24054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:09 basement sudo[24054]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:09 basement volumio[23993]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 05 18:47:09 basement volumio[23993]: wlan0 Interface doesn't support scanning : Device or resource busy Jul 05 18:47:09 basement volumio[23993]: info: Cannot use regular scanning, forcing with ap-force Jul 05 18:47:10 basement sudo[24061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 05 18:47:10 basement sudo[24061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:11 basement sudo[24061]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:11 basement volumio-remote-updater[293]: [2024-07-05 18:47:11] [connect] Successful connection Jul 05 18:47:12 basement volumio[23993]: info: CoreStateMachine::pushState Jul 05 18:47:12 basement volumio[23993]: info: CorePlayQueue::getTrack 0 Jul 05 18:47:12 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 18:47:12 basement volumio[23993]: info: CoreCommandRouter::volumioPushState Jul 05 18:47:12 basement volumio[23993]: info: CoreStateMachine::updateTrackBlock Jul 05 18:47:12 basement volumio[23993]: info: CorePlayQueue::getTrackBlock Jul 05 18:47:12 basement volumio[23993]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 18:47:13 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 18:47:13 basement volumio-remote-updater[293]: [2024-07-05 18:47:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720219631 101 Jul 05 18:47:13 basement volumio[23993]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 05 18:47:13 basement volumio[23993]: info: CoreStateMachine::setRepeat false single undefined Jul 05 18:47:13 basement volumio[23993]: info: CoreStateMachine::pushState Jul 05 18:47:13 basement volumio[23993]: info: CorePlayQueue::getTrack 0 Jul 05 18:47:13 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 18:47:13 basement volumio[23993]: info: CoreCommandRouter::volumioPushState Jul 05 18:47:13 basement volumio[23993]: info: CoreStateMachine::setRandom undefined Jul 05 18:47:13 basement volumio[23993]: info: CoreStateMachine::pushState Jul 05 18:47:13 basement volumio[23993]: info: CorePlayQueue::getTrack 0 Jul 05 18:47:13 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 18:47:13 basement volumio[23993]: info: CoreCommandRouter::volumioPushState Jul 05 18:47:13 basement volumio[23993]: info: Setting Device type: Raspberry PI Jul 05 18:47:13 basement volumio[23993]: info: Completed loading Core Plugins Jul 05 18:47:13 basement volumio[23993]: info: Preparing to generate the ALSA configuration file Jul 05 18:47:13 basement volumio[23993]: info: CoreStateMachine::pushState Jul 05 18:47:13 basement volumio[23993]: info: CorePlayQueue::getTrack 0 Jul 05 18:47:13 basement volumio[23993]: info: CoreCommandRouter::volumioPushState Jul 05 18:47:13 basement volumio[23993]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 05 18:47:13 basement volumio[23993]: info: Reading ALSA contributions from plugins. Jul 05 18:47:14 basement volumio[23993]: info: Asound.conf file unchanged, so no further update is needed Jul 05 18:47:14 basement volumio[23993]: info: Output device has changed, restarting MPD Jul 05 18:47:14 basement sudo[24075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 05 18:47:14 basement sudo[24075]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:14 basement volumio[23993]: info: Output device has changed, restarting Shairport Sync Jul 05 18:47:14 basement sudo[24075]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:14 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:14 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 18:47:14 basement sudo[24077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 18:47:14 basement sudo[24077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:14 basement sudo[24077]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:14 basement sudo[24080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 18:47:14 basement sudo[24080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:14 basement systemd[1]: Stopping Music Player Daemon... Jul 05 18:47:15 basement systemd[1]: mpd.service: Succeeded. Jul 05 18:47:15 basement systemd[1]: Stopped Music Player Daemon. Jul 05 18:47:15 basement systemd[1]: Starting Music Player Daemon... Jul 05 18:47:15 basement volumio[23993]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 18:47:15 basement volumio[23993]: info: ___________ START PLUGINS ___________ Jul 05 18:47:15 basement volumio[23993]: info: ControllerMpd::onStart: Initializing MPD Jul 05 18:47:15 basement volumio[23993]: info: Creating MPD Configuration file Jul 05 18:47:15 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:15 basement sudo[24086]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 18:47:15 basement sudo[24086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:15 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 18:47:15 basement sudo[24086]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:16 basement sudo[24088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 18:47:16 basement sudo[24088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:16 basement sudo[24088]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:16 basement sudo[24090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 18:47:16 basement sudo[24090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:16 basement systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 18:47:16 basement systemd[1]: mpd.service: Succeeded. Jul 05 18:47:16 basement systemd[1]: Stopped Music Player Daemon. Jul 05 18:47:16 basement systemd[1]: Starting Music Player Daemon... Jul 05 18:47:16 basement volumio[23993]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 18:47:17 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:17 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 18:47:17 basement volumio[23993]: Starting albumart workers Jul 05 18:47:17 basement sudo[24098]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 18:47:17 basement sudo[24098]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:17 basement sudo[24098]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:17 basement volumio[23993]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 18:47:17 basement volumio[23993]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 18:47:17 basement volumio[23993]: info: [1720219637859] CoreMusicLibrary::Adding element Last_100 Jul 05 18:47:17 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 18:47:17 basement volumio[23993]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 18:47:17 basement volumio[23993]: info: [1720219637931] CoreMusicLibrary::Adding element Webradio Jul 05 18:47:17 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 18:47:18 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 18:47:18 basement volumio[23993]: info: [squeezelite_mc] Starting proxy server... Jul 05 18:47:18 basement volumio[23993]: info: Volumio Calling Home Jul 05 18:47:20 basement volumio[23993]: info: [squeezelite_mc] Proxy server started on port 34717 Jul 05 18:47:22 basement volumio[23993]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 18:47:23 basement volumio[23993]: info: MPD Permissions set Jul 05 18:47:23 basement volumio[23993]: info: MPD Permissions set Jul 05 18:47:23 basement volumio[23993]: info: Upmpdcli Daemon Started Jul 05 18:47:23 basement volumio[23993]: info: CoreStateMachine::pushState Jul 05 18:47:23 basement volumio[23993]: info: CorePlayQueue::getTrack 0 Jul 05 18:47:23 basement volumio[23993]: info: CoreCommandRouter::volumioPushState Jul 05 18:47:23 basement volumio[23993]: info: [squeezelite_mc] Server discovery started Jul 05 18:47:23 basement volumio[23993]: info: [squeezelite_mc] Player finder started Jul 05 18:47:23 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:23 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:23 basement volumio[23993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:47:23 basement volumio[23993]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 05 18:47:24 basement volumio[23993]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 18:47:24 basement volumio[23993]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 05 18:47:24 basement volumio[23993]: at doSend (dgram.js:692:16) Jul 05 18:47:24 basement volumio[23993]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 05 18:47:24 basement volumio[23993]: at afterDns (dgram.js:638:5) Jul 05 18:47:24 basement volumio[23993]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 05 18:47:24 basement volumio[23993]: errno: -101, Jul 05 18:47:24 basement volumio[23993]: code: 'ENETUNREACH', Jul 05 18:47:24 basement volumio[23993]: syscall: 'send', Jul 05 18:47:24 basement volumio[23993]: address: '255.255.255.255', Jul 05 18:47:24 basement volumio[23993]: port: 3483 Jul 05 18:47:24 basement volumio[23993]: } Jul 05 18:47:24 basement volumio[23993]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 18:47:29 basement mpd[24103]: Jul 05 18:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 18:47:29 basement systemd[1]: Started Music Player Daemon. Jul 05 18:47:29 basement sudo[24090]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:29 basement sudo[24080]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:30 basement sudo[24134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 18:46 Jul 05 18:47:30 basement sudo[24134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:31 basement sudo[24134]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:31 basement volumio-remote-updater[293]: [2024-07-05 18:47:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 18:47:31 basement volumio-remote-updater[293]: [2024-07-05 18:47:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 18:47:31 basement systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 18:47:31 basement systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 18:47:31 basement systemd[1]: Started dynamicswap service. Jul 05 18:47:31 basement systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 18:47:31 basement systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19. Jul 05 18:47:31 basement systemd[1]: Stopped Volumio Backend Module. Jul 05 18:47:31 basement systemd[1]: Started Volumio Backend Module. Jul 05 18:47:31 basement systemd[1]: dynamicswap.service: Succeeded. Jul 05 18:47:36 basement volumio-remote-updater[293]: [2024-07-05 18:47:36] [info] asio async_connect error: system:111 (Connection refused) Jul 05 18:47:36 basement volumio-remote-updater[293]: [2024-07-05 18:47:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 05 18:47:36 basement volumio-remote-updater[293]: [2024-07-05 18:47:36] [error] handle_connect error: Underlying Transport Error Jul 05 18:47:40 basement volumio[24147]: info: ------------------------------------------- Jul 05 18:47:40 basement volumio[24147]: info: ----- Volumio3 ---- Jul 05 18:47:40 basement volumio[24147]: info: ------------------------------------------- Jul 05 18:47:40 basement volumio[24147]: info: ----- System startup ---- Jul 05 18:47:40 basement volumio[24147]: info: ------------------------------------------- Jul 05 18:47:44 basement volumio-remote-updater[293]: [2024-07-05 18:47:44] [connect] Successful connection Jul 05 18:47:44 basement volumio[24147]: info: MYVOLUMIO Environment detected Jul 05 18:47:44 basement volumio[24147]: info: Plugin folders cleanup Jul 05 18:47:44 basement volumio[24147]: info: Scanning into folder /volumio/app/plugins/ Jul 05 18:47:44 basement volumio[24147]: info: Scanning category audio_interface Jul 05 18:47:44 basement volumio[24147]: info: Scanning category miscellanea Jul 05 18:47:44 basement volumio[24147]: info: Scanning category music_service Jul 05 18:47:44 basement volumio[24147]: info: Scanning category plugins.json Jul 05 18:47:44 basement volumio[24147]: info: Scanning category system_controller Jul 05 18:47:44 basement volumio[24147]: info: Scanning category user_interface Jul 05 18:47:44 basement volumio[24147]: info: Scanning into folder /data/plugins/ Jul 05 18:47:44 basement volumio[24147]: info: Scanning category music_service Jul 05 18:47:44 basement volumio[24147]: info: Plugin folders cleanup completed Jul 05 18:47:44 basement volumio[24147]: info: ------------------------------------------- Jul 05 18:47:44 basement volumio[24147]: info: ----- Core plugins startup ---- Jul 05 18:47:44 basement volumio[24147]: info: ------------------------------------------- Jul 05 18:47:44 basement volumio[24147]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 18:47:44 basement volumio[24147]: info: Adding plugin upnp to MyMusic Plugins Jul 05 18:47:44 basement volumio[24147]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 18:47:44 basement volumio[24147]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 18:47:44 basement volumio[24147]: info: Loading plugins from folder /data/plugins/ Jul 05 18:47:44 basement volumio[24147]: info: Loading plugin "system"... Jul 05 18:47:44 basement volumio[24147]: info: Loading plugin "appearance"... Jul 05 18:47:49 basement volumio[24147]: info: Loading plugin "network"... Jul 05 18:47:49 basement volumio[24147]: info: Refreshing Cached IP Addresses Jul 05 18:47:49 basement sudo[24166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 18:47:50 basement sudo[24166]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:50 basement volumio[24147]: info: Loading plugin "services"... Jul 05 18:47:50 basement sudo[24166]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:50 basement volumio[24147]: info: Loading plugin "alsa_controller"... Jul 05 18:47:50 basement sudo[24168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 18:47:50 basement sudo[24168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:50 basement sudo[24168]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:50 basement sudo[24171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 18:47:50 basement sudo[24171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:47:50 basement sudo[24171]: pam_unix(sudo:session): session closed for user root Jul 05 18:47:50 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 18:47:50 basement volumio[24147]: info: Loading plugin "wizard"... Jul 05 18:47:50 basement volumio[24147]: info: Loading plugin "networkfs"... Jul 05 18:47:50 basement volumio[24147]: info: Starting Udev Watcher for removable devices Jul 05 18:47:51 basement volumio[24147]: info: Ignoring mount for partition: boot Jul 05 18:47:51 basement volumio[24147]: info: Ignoring mount for partition: volumio Jul 05 18:47:51 basement volumio[24147]: info: Ignoring mount for partition: volumio_data Jul 05 18:47:51 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 18:47:51 basement volumio[24147]: info: Loading plugin "volumio_command_line_client"... Jul 05 18:47:51 basement volumio[24147]: info: Loading plugin "upnp"... Jul 05 18:47:51 basement volumio[24147]: info: [1720219671865] Starting Upmpd Daemon Jul 05 18:47:51 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 18:47:51 basement volumio[24147]: info: Loading plugin "my_music"... Jul 05 18:47:51 basement volumio[24147]: info: Loading plugin "mpd"... Jul 05 18:47:53 basement volumio[24147]: info: Plugin upnp_browser is not enabled Jul 05 18:47:53 basement volumio[24147]: info: Loading plugin "alarm-clock"... Jul 05 18:47:54 basement volumio[24147]: info: Loading plugin "airplay_emulation"... Jul 05 18:47:54 basement volumio[24147]: info: Starting Shairport Sync Jul 05 18:47:54 basement volumio[24147]: info: Loading plugin "last_100"... Jul 05 18:47:55 basement volumio[24147]: info: Loading plugin "webradio"... Jul 05 18:47:55 basement volumio[24147]: info: Loading plugin "i2s_dacs"... Jul 05 18:47:55 basement volumio[24147]: info: Loading plugin "volumiodiscovery"... Jul 05 18:47:55 basement volumio[24147]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 18:47:55 basement node[24147]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 18:47:55 basement volumio[24147]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 18:47:55 basement volumio[24147]: *** WARNING *** For more information see Jul 05 18:47:55 basement volumio[24147]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 18:47:55 basement volumio[24147]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 18:47:55 basement volumio[24147]: *** WARNING *** For more information see Jul 05 18:47:55 basement node[24147]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 18:47:55 basement node[24147]: *** WARNING *** For more information see Jul 05 18:47:55 basement node[24147]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 18:47:55 basement node[24147]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 18:47:55 basement node[24147]: *** WARNING *** For more information see Jul 05 18:47:55 basement volumio[24147]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 18:47:55 basement volumio[24147]: info: Discovery: Started advertising with name: Basement Jul 05 18:47:56 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 18:47:56 basement volumio[24147]: info: Loading plugin "squeezelite_mc"... Jul 05 18:47:59 basement volumio-remote-updater[293]: [2024-07-05 18:47:59] [connect] Successful connection Jul 05 18:48:00 basement volumio[24147]: info: Loading plugin "ytcr"... Jul 05 18:48:07 basement volumio[24147]: info: Loading plugin "outputs"... Jul 05 18:48:07 basement volumio[24147]: info: Loading plugin "albumart"... Jul 05 18:48:07 basement volumio[24147]: info: Plugin example_plugin is not enabled Jul 05 18:48:07 basement volumio[24147]: info: Loading plugin "inputs"... Jul 05 18:48:07 basement volumio[24147]: info: Loading plugin "updater_comm"... Jul 05 18:48:09 basement volumio[24147]: info: Plugin mpdemulation is not enabled Jul 05 18:48:09 basement volumio[24147]: info: Loading plugin "rest_api"... Jul 05 18:48:09 basement volumio[24147]: info: Loading plugin "websocket"... Jul 05 18:48:09 basement volumio[24147]: info: Loading i18n strings for locale en Jul 05 18:48:09 basement volumio[24147]: Updating browse sources language Jul 05 18:48:09 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 18:48:10 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 18:48:10 basement volumio[24147]: info: CoreCommandRouter::initPlayerControls Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 18:48:11 basement volumio[24147]: Forking 1 albumart workers Jul 05 18:48:11 basement volumio[24147]: Express server listening on port 3000 Jul 05 18:48:11 basement volumio[24147]: [Metrics] WebUI: 33s 253.91ms Jul 05 18:48:11 basement volumio[24147]: info: CoreStateMachine::resetVolumioState Jul 05 18:48:11 basement volumio[24147]: info: CoreStateMachine::getcurrentVolume Jul 05 18:48:11 basement volumio[24147]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 18:48:12 basement sudo[24205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 18:48:12 basement sudo[24205]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:12 basement sudo[24205]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:12 basement sudo[24207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 18:48:12 basement sudo[24207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:12 basement sudo[24207]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:12 basement volumio[24147]: info: CoreStateMachine::pushState Jul 05 18:48:12 basement volumio[24147]: info: CorePlayQueue::getTrack 0 Jul 05 18:48:12 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 18:48:12 basement volumio[24147]: info: CoreCommandRouter::volumioPushState Jul 05 18:48:12 basement volumio[24147]: info: CoreStateMachine::updateTrackBlock Jul 05 18:48:12 basement volumio[24147]: info: CorePlayQueue::getTrackBlock Jul 05 18:48:12 basement volumio[24147]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 18:48:12 basement volumio[24147]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 05 18:48:12 basement volumio[24147]: wlan0 Interface doesn't support scanning : Device or resource busy Jul 05 18:48:12 basement volumio[24147]: info: Cannot use regular scanning, forcing with ap-force Jul 05 18:48:13 basement sudo[24221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 05 18:48:13 basement sudo[24221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:14 basement volumio-remote-updater[293]: [2024-07-05 18:48:14] [connect] Successful connection Jul 05 18:48:14 basement sudo[24221]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:15 basement volumio[24147]: info: CoreStateMachine::pushState Jul 05 18:48:15 basement volumio[24147]: info: CorePlayQueue::getTrack 0 Jul 05 18:48:15 basement volumio[24147]: info: CoreCommandRouter::volumioPushState Jul 05 18:48:15 basement volumio-remote-updater[293]: [2024-07-05 18:48:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720219694 101 Jul 05 18:48:16 basement volumio[24147]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 05 18:48:16 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 18:48:16 basement volumio[24147]: info: CoreStateMachine::setRepeat false single undefined Jul 05 18:48:16 basement volumio[24147]: info: CoreStateMachine::pushState Jul 05 18:48:16 basement volumio[24147]: info: CorePlayQueue::getTrack 0 Jul 05 18:48:16 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 18:48:16 basement volumio[24147]: info: CoreCommandRouter::volumioPushState Jul 05 18:48:16 basement volumio[24147]: info: CoreStateMachine::setRandom undefined Jul 05 18:48:16 basement volumio[24147]: info: CoreStateMachine::pushState Jul 05 18:48:16 basement volumio[24147]: info: CorePlayQueue::getTrack 0 Jul 05 18:48:16 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 18:48:16 basement volumio[24147]: info: CoreCommandRouter::volumioPushState Jul 05 18:48:16 basement volumio[24147]: info: Setting Device type: Raspberry PI Jul 05 18:48:16 basement volumio[24147]: info: Completed loading Core Plugins Jul 05 18:48:16 basement volumio[24147]: info: Preparing to generate the ALSA configuration file Jul 05 18:48:16 basement volumio[24147]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 05 18:48:16 basement volumio[24147]: info: Reading ALSA contributions from plugins. Jul 05 18:48:16 basement volumio[24147]: info: Asound.conf file unchanged, so no further update is needed Jul 05 18:48:16 basement volumio[24147]: info: Output device has changed, restarting MPD Jul 05 18:48:17 basement sudo[24228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 05 18:48:17 basement sudo[24228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:17 basement volumio[24147]: info: Output device has changed, restarting Shairport Sync Jul 05 18:48:17 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:17 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 18:48:17 basement sudo[24228]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:17 basement sudo[24230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 18:48:17 basement sudo[24230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:17 basement sudo[24230]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:17 basement sudo[24233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 18:48:17 basement sudo[24233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:17 basement systemd[1]: Stopping Music Player Daemon... Jul 05 18:48:18 basement systemd[1]: mpd.service: Succeeded. Jul 05 18:48:18 basement systemd[1]: Stopped Music Player Daemon. Jul 05 18:48:18 basement systemd[1]: Starting Music Player Daemon... Jul 05 18:48:18 basement volumio[24147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 18:48:18 basement volumio[24147]: info: ___________ START PLUGINS ___________ Jul 05 18:48:18 basement volumio[24147]: info: ControllerMpd::onStart: Initializing MPD Jul 05 18:48:18 basement volumio[24147]: info: Creating MPD Configuration file Jul 05 18:48:18 basement sudo[24239]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 18:48:18 basement sudo[24239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:18 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:18 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 18:48:18 basement sudo[24239]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:18 basement sudo[24241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 18:48:18 basement sudo[24241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:19 basement sudo[24241]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:19 basement sudo[24243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 18:48:19 basement sudo[24243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:19 basement systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 18:48:19 basement systemd[1]: mpd.service: Succeeded. Jul 05 18:48:19 basement systemd[1]: Stopped Music Player Daemon. Jul 05 18:48:19 basement systemd[1]: Starting Music Player Daemon... Jul 05 18:48:20 basement volumio[24147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 18:48:20 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:20 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 18:48:20 basement volumio[24147]: Starting albumart workers Jul 05 18:48:20 basement sudo[24251]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 18:48:20 basement sudo[24251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 18:48:20 basement sudo[24251]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:20 basement volumio[24147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 18:48:20 basement volumio[24147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 18:48:20 basement volumio[24147]: info: [1720219700716] CoreMusicLibrary::Adding element Last_100 Jul 05 18:48:20 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 18:48:20 basement volumio[24147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 18:48:20 basement volumio[24147]: info: [1720219700811] CoreMusicLibrary::Adding element Webradio Jul 05 18:48:20 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 18:48:20 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 18:48:21 basement volumio[24147]: info: [squeezelite_mc] Starting proxy server... Jul 05 18:48:21 basement volumio[24147]: info: Volumio Calling Home Jul 05 18:48:23 basement dhcpcd[716]: wlan1: carrier acquired Jul 05 18:48:23 basement dhcpcd[716]: wlan1: IAID eb:d7:7d:eb Jul 05 18:48:23 basement dhcpcd[716]: wlan1: soliciting an IPv6 router Jul 05 18:48:23 basement volumio[24147]: info: [squeezelite_mc] Proxy server started on port 41141 Jul 05 18:48:23 basement dhcpcd[716]: wlan1: rebinding lease of 192.168.1.101 Jul 05 18:48:24 basement dhcpcd[716]: wlan1: probing address 192.168.1.101/24 Jul 05 18:48:25 basement volumio[24147]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 18:48:26 basement volumio[24147]: info: MPD Permissions set Jul 05 18:48:26 basement volumio[24147]: info: MPD Permissions set Jul 05 18:48:26 basement volumio[24147]: info: Upmpdcli Daemon Started Jul 05 18:48:26 basement volumio[24147]: info: CoreStateMachine::pushState Jul 05 18:48:26 basement volumio[24147]: info: CorePlayQueue::getTrack 0 Jul 05 18:48:26 basement volumio[24147]: info: CoreCommandRouter::volumioPushState Jul 05 18:48:26 basement volumio[24147]: info: [squeezelite_mc] Server discovery started Jul 05 18:48:26 basement volumio[24147]: info: [squeezelite_mc] Player finder started Jul 05 18:48:26 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:26 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:26 basement volumio[24147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 18:48:26 basement volumio[24147]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 05 18:48:27 basement volumio[24147]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 18:48:27 basement volumio[24147]: Error: send ENETUNREACH 255.255.255.255:3483 Jul 05 18:48:27 basement volumio[24147]: at doSend (dgram.js:692:16) Jul 05 18:48:27 basement volumio[24147]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jul 05 18:48:27 basement volumio[24147]: at afterDns (dgram.js:638:5) Jul 05 18:48:27 basement volumio[24147]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jul 05 18:48:27 basement volumio[24147]: errno: -101, Jul 05 18:48:27 basement volumio[24147]: code: 'ENETUNREACH', Jul 05 18:48:27 basement volumio[24147]: syscall: 'send', Jul 05 18:48:27 basement volumio[24147]: address: '255.255.255.255', Jul 05 18:48:27 basement volumio[24147]: port: 3483 Jul 05 18:48:27 basement volumio[24147]: } Jul 05 18:48:27 basement volumio[24147]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 18:48:28 basement dhcpcd[716]: wlan1: leased 192.168.1.101 for 86400 seconds Jul 05 18:48:28 basement avahi-daemon[299]: Joining mDNS multicast group on interface wlan1.IPv4 with address 192.168.1.101. Jul 05 18:48:28 basement dhcpcd[716]: wlan1: adding route to 192.168.1.0/24 Jul 05 18:48:28 basement avahi-daemon[299]: New relevant interface wlan1.IPv4 for mDNS. Jul 05 18:48:28 basement avahi-daemon[299]: Registering new address record for 192.168.1.101 on wlan1.IPv4. Jul 05 18:48:28 basement dhcpcd[716]: wlan1: adding default route via 192.168.1.1 Jul 05 18:48:30 basement ntpd[442]: Listen normally on 5 wlan1 192.168.1.101:123 Jul 05 18:48:30 basement ntpd[442]: new interface(s) found: waking up resolver Jul 05 18:48:33 basement mpd[24256]: Jul 05 18:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 18:48:33 basement systemd[1]: Started Music Player Daemon. Jul 05 18:48:33 basement sudo[24233]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:33 basement sudo[24243]: pam_unix(sudo:session): session closed for user root Jul 05 18:48:34 basement sudo[24312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 18:47 Jul 05 18:48:34 basement sudo[24312]: 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="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 03:58:37 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1d6379ebb89c40061afa8aee78176887"