-- 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"