-- Logs begin at Sat 2024-04-20 00:42:43 BST, end at Sat 2024-04-20 01:24:55 BST. -- Apr 20 01:23:00 lctmusic volumio[11105]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 01:23:01 lctmusic volumio[11105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:23:01 lctmusic volumio[11105]: TypeError: Cannot read property 'charAt' of undefined Apr 20 01:23:01 lctmusic volumio[11105]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 20 01:23:01 lctmusic volumio[11105]: at listOnTimeout (internal/timers.js:554:17) Apr 20 01:23:01 lctmusic volumio[11105]: at processTimers (internal/timers.js:497:7) Apr 20 01:23:01 lctmusic volumio[11105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:23:02 lctmusic sudo[11273]: volumio : unable to resolve host lctmusic Apr 20 01:23:02 lctmusic sudo[11273]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:02 lctmusic sudo[11273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 01:22 Apr 20 01:23:02 lctmusic sudo[11273]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:02 lctmusic sudo[11273]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:02 lctmusic volumio[11105]: sudo: unable to resolve host lctmusic: System error Apr 20 01:23:02 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 20 01:23:02 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 20 01:23:02 lctmusic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 01:23:02 lctmusic systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 01:23:02 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:23:02 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:23:02 lctmusic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 01:23:02 lctmusic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1216. Apr 20 01:23:02 lctmusic systemd[1]: Stopped Volumio Backend Module. Apr 20 01:23:02 lctmusic systemd[1]: Started Volumio Backend Module. Apr 20 01:23:03 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:23:03 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:23:06 lctmusic volumio[11286]: info: ------------------------------------------- Apr 20 01:23:06 lctmusic volumio[11286]: info: ----- Volumio3 ---- Apr 20 01:23:06 lctmusic volumio[11286]: info: ------------------------------------------- Apr 20 01:23:06 lctmusic volumio[11286]: info: ----- System startup ---- Apr 20 01:23:06 lctmusic volumio[11286]: info: ------------------------------------------- Apr 20 01:23:07 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:07] [connect] Successful connection Apr 20 01:23:07 lctmusic volumio[11286]: info: MYVOLUMIO Environment detected Apr 20 01:23:07 lctmusic volumio[11286]: info: Plugin folders cleanup Apr 20 01:23:07 lctmusic volumio[11286]: info: Scanning into folder /volumio/app/plugins/ Apr 20 01:23:07 lctmusic volumio[11286]: info: Scanning category audio_interface Apr 20 01:23:07 lctmusic volumio[11286]: info: Scanning category miscellanea Apr 20 01:23:07 lctmusic volumio[11286]: info: Scanning category music_service Apr 20 01:23:07 lctmusic volumio[11286]: info: Scanning category plugins.json Apr 20 01:23:07 lctmusic volumio[11286]: info: Scanning category system_controller Apr 20 01:23:07 lctmusic volumio[11286]: info: Scanning category user_interface Apr 20 01:23:07 lctmusic volumio[11286]: info: Scanning into folder /data/plugins/ Apr 20 01:23:07 lctmusic volumio[11286]: info: Plugin folders cleanup completed Apr 20 01:23:07 lctmusic volumio[11286]: info: ------------------------------------------- Apr 20 01:23:07 lctmusic volumio[11286]: info: ----- Core plugins startup ---- Apr 20 01:23:07 lctmusic volumio[11286]: info: ------------------------------------------- Apr 20 01:23:07 lctmusic volumio[11286]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 01:23:07 lctmusic volumio[11286]: info: Adding plugin upnp to MyMusic Plugins Apr 20 01:23:07 lctmusic volumio[11286]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 01:23:07 lctmusic volumio[11286]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 01:23:07 lctmusic volumio[11286]: info: Loading plugins from folder /data/plugins/ Apr 20 01:23:07 lctmusic volumio[11286]: info: Loading plugin "system"... Apr 20 01:23:07 lctmusic volumio[11286]: info: Loading plugin "appearance"... Apr 20 01:23:09 lctmusic volumio[11286]: info: Loading plugin "network"... Apr 20 01:23:09 lctmusic volumio[11286]: info: Refreshing Cached IP Addresses Apr 20 01:23:09 lctmusic sudo[11311]: volumio : unable to resolve host lctmusic Apr 20 01:23:09 lctmusic sudo[11311]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:09 lctmusic sudo[11311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 01:23:09 lctmusic sudo[11311]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:09 lctmusic sudo[11311]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:09 lctmusic sudo[11313]: volumio : unable to resolve host lctmusic Apr 20 01:23:09 lctmusic sudo[11313]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:09 lctmusic volumio[11286]: info: Loading plugin "services"... Apr 20 01:23:09 lctmusic sudo[11313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 01:23:09 lctmusic sudo[11313]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:09 lctmusic volumio[11286]: info: Loading plugin "alsa_controller"... Apr 20 01:23:09 lctmusic sudo[11313]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:09 lctmusic sudo[11316]: volumio : unable to resolve host lctmusic Apr 20 01:23:09 lctmusic sudo[11316]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:09 lctmusic sudo[11316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 01:23:09 lctmusic sudo[11316]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:09 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:23:09 lctmusic volumio[11286]: info: Loading plugin "wizard"... Apr 20 01:23:09 lctmusic volumio[11286]: info: Loading plugin "volumio_command_line_client"... Apr 20 01:23:09 lctmusic volumio[11286]: info: Loading plugin "upnp"... Apr 20 01:23:10 lctmusic volumio[11286]: info: [1713572590000] Starting Upmpd Daemon Apr 20 01:23:10 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:10 lctmusic volumio[11286]: info: Loading plugin "my_music"... Apr 20 01:23:10 lctmusic volumio[11286]: info: Loading plugin "mpd"... Apr 20 01:23:10 lctmusic sudo[11316]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:10 lctmusic volumio[11286]: info: Creating MPD Configuration file Apr 20 01:23:10 lctmusic sudo[11324]: volumio : unable to resolve host lctmusic Apr 20 01:23:10 lctmusic sudo[11324]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:10 lctmusic volumio[11286]: info: Loading plugin "upnp_browser"... Apr 20 01:23:10 lctmusic sudo[11324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:23:10 lctmusic sudo[11324]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:10 lctmusic sudo[11324]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:10 lctmusic sudo[11326]: volumio : unable to resolve host lctmusic Apr 20 01:23:10 lctmusic sudo[11326]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:10 lctmusic sudo[11326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:23:10 lctmusic sudo[11326]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:10 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:23:10 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:23:10 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:23:10 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:23:11 lctmusic volumio[11286]: info: Loading plugin "networkfs"... Apr 20 01:23:11 lctmusic volumio[11286]: info: Starting Udev Watcher for removable devices Apr 20 01:23:12 lctmusic volumio[11286]: info: Ignoring mount for partition: boot Apr 20 01:23:12 lctmusic volumio[11286]: info: Ignoring mount for partition: volumio Apr 20 01:23:12 lctmusic volumio[11286]: info: Ignoring mount for partition: volumio_data Apr 20 01:23:12 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:12 lctmusic volumio[11286]: info: Loading plugin "alarm-clock"... Apr 20 01:23:12 lctmusic volumio[11286]: info: Loading plugin "airplay_emulation"... Apr 20 01:23:12 lctmusic volumio[11286]: info: Starting Shairport Sync Apr 20 01:23:12 lctmusic volumio[11286]: info: Loading plugin "last_100"... Apr 20 01:23:12 lctmusic volumio[11286]: info: Loading plugin "webradio"... Apr 20 01:23:12 lctmusic volumio[11286]: info: Loading plugin "i2s_dacs"... Apr 20 01:23:12 lctmusic volumio[11286]: info: Loading plugin "volumiodiscovery"... Apr 20 01:23:12 lctmusic volumio[11286]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:12 lctmusic volumio[11286]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:12 lctmusic node[11286]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:12 lctmusic volumio[11286]: *** WARNING *** For more information see Apr 20 01:23:12 lctmusic volumio[11286]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:12 lctmusic volumio[11286]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:12 lctmusic volumio[11286]: *** WARNING *** For more information see Apr 20 01:23:12 lctmusic node[11286]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:12 lctmusic node[11286]: *** WARNING *** For more information see Apr 20 01:23:12 lctmusic node[11286]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:12 lctmusic node[11286]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:12 lctmusic node[11286]: *** WARNING *** For more information see Apr 20 01:23:13 lctmusic volumio[11286]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 01:23:13 lctmusic volumio[11286]: info: Discovery: Started advertising with name: undefined Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:13 lctmusic volumio[11286]: info: Loading plugin "outputs"... Apr 20 01:23:13 lctmusic volumio[11286]: info: Loading plugin "albumart"... Apr 20 01:23:13 lctmusic volumio[11286]: info: Plugin example_plugin is not enabled Apr 20 01:23:13 lctmusic volumio[11286]: info: Loading plugin "inputs"... Apr 20 01:23:13 lctmusic volumio[11286]: info: Loading plugin "updater_comm"... Apr 20 01:23:13 lctmusic volumio[11286]: info: Plugin mpdemulation is not enabled Apr 20 01:23:13 lctmusic volumio[11286]: info: Loading plugin "rest_api"... Apr 20 01:23:13 lctmusic volumio[11286]: info: Loading plugin "websocket"... Apr 20 01:23:13 lctmusic volumio[11286]: info: Loading i18n strings for locale en Apr 20 01:23:13 lctmusic volumio[11286]: Updating browse sources language Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:13 lctmusic mpd[11331]: Apr 20 01:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:23:13 lctmusic mpd[11331]: Apr 20 01:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:23:13 lctmusic mpd[11331]: Apr 20 01:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:23:13 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:23:13 lctmusic sudo[11326]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:13 lctmusic volumio[11286]: Forking 3 albumart workers Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::initPlayerControls Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:13 lctmusic volumio[11286]: Express server listening on port 3000 Apr 20 01:23:13 lctmusic volumio[11286]: [Metrics] WebUI: 8s 639.02ms Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreStateMachine::resetVolumioState Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreStateMachine::getcurrentVolume Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:23:14 lctmusic volumio[11286]: info: MPD Permissions set Apr 20 01:23:14 lctmusic volumio[11286]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:23:14 lctmusic volumio[11286]: info: MPD running with PID11331 Apr 20 01:23:14 lctmusic volumio[11286]: ,establishing connection Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:14 lctmusic volumio[11286]: info: Cannot read play queue from file Apr 20 01:23:14 lctmusic volumio[11286]: error: updateQueue error: null Apr 20 01:23:14 lctmusic volumio[11286]: info: Setting Device type: Raspberry PI Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreStateMachine::setRepeat null single undefined Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreStateMachine::pushState Apr 20 01:23:14 lctmusic volumio[11286]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreStateMachine::setRandom null Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreStateMachine::pushState Apr 20 01:23:14 lctmusic volumio[11286]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:14 lctmusic volumio[11286]: info: Completed loading Core Plugins Apr 20 01:23:14 lctmusic volumio[11286]: info: Preparing to generate the ALSA configuration file Apr 20 01:23:14 lctmusic volumio[11286]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreStateMachine::pushState Apr 20 01:23:14 lctmusic volumio[11286]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreStateMachine::updateTrackBlock Apr 20 01:23:14 lctmusic volumio[11286]: info: CorePlayQueue::getTrackBlock Apr 20 01:23:14 lctmusic volumio[11286]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:23:14 lctmusic volumio[11286]: error: updateQueue error: null Apr 20 01:23:14 lctmusic volumio[11286]: info: Asound.conf file unchanged, so no further update is needed Apr 20 01:23:14 lctmusic volumio[11286]: info: Output device has changed, restarting MPD Apr 20 01:23:15 lctmusic volumio[11286]: info: Output device has changed, restarting Shairport Sync Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:15 lctmusic sudo[11382]: volumio : unable to resolve host lctmusic Apr 20 01:23:15 lctmusic sudo[11382]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:15 lctmusic sudo[11382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:23:15 lctmusic sudo[11382]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:15 lctmusic sudo[11382]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:15 lctmusic sudo[11384]: volumio : unable to resolve host lctmusic Apr 20 01:23:15 lctmusic sudo[11384]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:15 lctmusic sudo[11384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:23:15 lctmusic sudo[11384]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:15 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:23:15 lctmusic volumio[11286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:15 lctmusic volumio[11286]: info: ___________ START PLUGINS ___________ Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:15 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:23:15 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:15 lctmusic volumio[11286]: info: [1713572595219] CoreMusicLibrary::Adding element Media Servers Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:15 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:15 lctmusic volumio[11286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:15 lctmusic volumio[11286]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:15 lctmusic volumio[11286]: info: [1713572595538] CoreMusicLibrary::Adding element Last_100 Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:15 lctmusic volumio[11286]: info: [1713572595548] CoreMusicLibrary::Adding element Webradio Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:15 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:23:15 lctmusic volumio[11286]: info: Volumio Calling Home Apr 20 01:23:15 lctmusic volumio[11286]: Starting albumart workers Apr 20 01:23:16 lctmusic volumio[11286]: info: Completed starting Core Plugins Apr 20 01:23:16 lctmusic volumio[11286]: info: ------------------------------------------- Apr 20 01:23:16 lctmusic volumio[11286]: info: ----- MyVolumio plugins startup ---- Apr 20 01:23:16 lctmusic volumio[11286]: info: ------------------------------------------- Apr 20 01:23:16 lctmusic volumio[11286]: Starting albumart workers Apr 20 01:23:16 lctmusic volumio[11286]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 20 01:23:16 lctmusic volumio[11286]: Starting albumart workers Apr 20 01:23:16 lctmusic volumio[11286]: info: MPD Permissions set Apr 20 01:23:16 lctmusic volumio[11286]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:23:16 lctmusic volumio[11286]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:16 lctmusic volumio[11286]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreStateMachine::pushState Apr 20 01:23:16 lctmusic volumio[11286]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:16 lctmusic volumio[11286]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:16 lctmusic volumio[11286]: info: Starting Shairport Sync Apr 20 01:23:16 lctmusic volumio[11286]: info: Starting Shairport Sync Apr 20 01:23:16 lctmusic volumio[11286]: info: Starting Shairport Sync Apr 20 01:23:16 lctmusic sudo[11408]: volumio : unable to resolve host lctmusic Apr 20 01:23:16 lctmusic sudo[11408]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:16 lctmusic sudo[11408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:16 lctmusic sudo[11408]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:16 lctmusic sudo[11411]: volumio : unable to resolve host lctmusic Apr 20 01:23:16 lctmusic sudo[11411]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:16 lctmusic sudo[11411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:16 lctmusic sudo[11411]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:16 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:23:16 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:23:16 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:17 lctmusic sudo[11412]: volumio : unable to resolve host lctmusic Apr 20 01:23:17 lctmusic sudo[11412]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:17 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:17 lctmusic sudo[11412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:17 lctmusic sudo[11412]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:17 lctmusic sudo[11408]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:17 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:23:17 lctmusic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 20 01:23:17 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:23:17 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:17 lctmusic volumio[11286]: info: Shairport-Sync Started Apr 20 01:23:17 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:17 lctmusic sudo[11411]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:17 lctmusic volumio[11286]: Error adding Membership: Error: addMembership EINVAL Apr 20 01:23:17 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:23:17 lctmusic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 20 01:23:17 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:23:17 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:17 lctmusic volumio[11286]: info: Shairport-Sync Started Apr 20 01:23:17 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:17 lctmusic sudo[11412]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:17 lctmusic volumio[11286]: info: CoreCommandRouter::volumioGetState Apr 20 01:23:17 lctmusic volumio[11286]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:17 lctmusic volumio[11286]: info: Shairport-Sync Started Apr 20 01:23:18 lctmusic volumio[11286]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 20 01:23:18 lctmusic volumio[11286]: info: Discovery: Started advertising with name: undefined Apr 20 01:23:18 lctmusic mpd[11400]: Apr 20 01:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:23:18 lctmusic mpd[11400]: Apr 20 01:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:23:18 lctmusic mpd[11400]: Apr 20 01:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:23:18 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:23:18 lctmusic sudo[11384]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:18 lctmusic volumio[11286]: error: updateQueue error: null Apr 20 01:23:19 lctmusic volumio[11286]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 01:23:20 lctmusic volumio[11286]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:23:20 lctmusic volumio[11286]: TypeError: Cannot read property 'charAt' of undefined Apr 20 01:23:20 lctmusic volumio[11286]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 20 01:23:20 lctmusic volumio[11286]: at listOnTimeout (internal/timers.js:554:17) Apr 20 01:23:20 lctmusic volumio[11286]: at processTimers (internal/timers.js:497:7) Apr 20 01:23:20 lctmusic volumio[11286]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:23:20 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:20] [connect] Successful connection Apr 20 01:23:21 lctmusic sudo[11456]: volumio : unable to resolve host lctmusic Apr 20 01:23:21 lctmusic sudo[11456]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:21 lctmusic sudo[11456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 01:22 Apr 20 01:23:21 lctmusic sudo[11456]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:21 lctmusic sudo[11456]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:21 lctmusic volumio[11286]: sudo: unable to resolve host lctmusic: System error Apr 20 01:23:21 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:21] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 20 01:23:21 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:21] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 20 01:23:21 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 01:23:21 lctmusic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 01:23:21 lctmusic systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 01:23:21 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:23:21 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:23:21 lctmusic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 01:23:21 lctmusic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1217. Apr 20 01:23:21 lctmusic systemd[1]: Stopped Volumio Backend Module. Apr 20 01:23:21 lctmusic systemd[1]: Started Volumio Backend Module. Apr 20 01:23:21 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:23:21 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:23:25 lctmusic volumio[11469]: info: ------------------------------------------- Apr 20 01:23:25 lctmusic volumio[11469]: info: ----- Volumio3 ---- Apr 20 01:23:25 lctmusic volumio[11469]: info: ------------------------------------------- Apr 20 01:23:25 lctmusic volumio[11469]: info: ----- System startup ---- Apr 20 01:23:25 lctmusic volumio[11469]: info: ------------------------------------------- Apr 20 01:23:26 lctmusic volumio[11469]: info: MYVOLUMIO Environment detected Apr 20 01:23:26 lctmusic volumio[11469]: info: Plugin folders cleanup Apr 20 01:23:26 lctmusic volumio[11469]: info: Scanning into folder /volumio/app/plugins/ Apr 20 01:23:26 lctmusic volumio[11469]: info: Scanning category audio_interface Apr 20 01:23:26 lctmusic volumio[11469]: info: Scanning category miscellanea Apr 20 01:23:26 lctmusic volumio[11469]: info: Scanning category music_service Apr 20 01:23:26 lctmusic volumio[11469]: info: Scanning category plugins.json Apr 20 01:23:26 lctmusic volumio[11469]: info: Scanning category system_controller Apr 20 01:23:26 lctmusic volumio[11469]: info: Scanning category user_interface Apr 20 01:23:26 lctmusic volumio[11469]: info: Scanning into folder /data/plugins/ Apr 20 01:23:26 lctmusic volumio[11469]: info: Plugin folders cleanup completed Apr 20 01:23:26 lctmusic volumio[11469]: info: ------------------------------------------- Apr 20 01:23:26 lctmusic volumio[11469]: info: ----- Core plugins startup ---- Apr 20 01:23:26 lctmusic volumio[11469]: info: ------------------------------------------- Apr 20 01:23:26 lctmusic volumio[11469]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 01:23:26 lctmusic volumio[11469]: info: Adding plugin upnp to MyMusic Plugins Apr 20 01:23:26 lctmusic volumio[11469]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 01:23:26 lctmusic volumio[11469]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 01:23:26 lctmusic volumio[11469]: info: Loading plugins from folder /data/plugins/ Apr 20 01:23:26 lctmusic volumio[11469]: info: Loading plugin "system"... Apr 20 01:23:26 lctmusic volumio[11469]: info: Loading plugin "appearance"... Apr 20 01:23:28 lctmusic volumio[11469]: info: Loading plugin "network"... Apr 20 01:23:28 lctmusic volumio[11469]: info: Refreshing Cached IP Addresses Apr 20 01:23:28 lctmusic sudo[11493]: volumio : unable to resolve host lctmusic Apr 20 01:23:28 lctmusic sudo[11493]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:28 lctmusic sudo[11493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 01:23:28 lctmusic sudo[11493]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:28 lctmusic sudo[11493]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:28 lctmusic sudo[11495]: volumio : unable to resolve host lctmusic Apr 20 01:23:28 lctmusic sudo[11495]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:28 lctmusic volumio[11469]: info: Loading plugin "services"... Apr 20 01:23:28 lctmusic sudo[11495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 01:23:28 lctmusic sudo[11495]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:28 lctmusic volumio[11469]: info: Loading plugin "alsa_controller"... Apr 20 01:23:28 lctmusic sudo[11495]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:28 lctmusic sudo[11498]: volumio : unable to resolve host lctmusic Apr 20 01:23:28 lctmusic sudo[11498]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:28 lctmusic sudo[11498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 01:23:28 lctmusic sudo[11498]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:28 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:23:28 lctmusic volumio[11469]: info: Loading plugin "wizard"... Apr 20 01:23:28 lctmusic volumio[11469]: info: Loading plugin "volumio_command_line_client"... Apr 20 01:23:28 lctmusic volumio[11469]: info: Loading plugin "upnp"... Apr 20 01:23:28 lctmusic volumio[11469]: info: [1713572608647] Starting Upmpd Daemon Apr 20 01:23:28 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:28 lctmusic volumio[11469]: info: Loading plugin "my_music"... Apr 20 01:23:28 lctmusic volumio[11469]: info: Loading plugin "mpd"... Apr 20 01:23:29 lctmusic volumio[11469]: info: Creating MPD Configuration file Apr 20 01:23:29 lctmusic sudo[11498]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:29 lctmusic sudo[11506]: volumio : unable to resolve host lctmusic Apr 20 01:23:29 lctmusic sudo[11506]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:29 lctmusic volumio[11469]: info: Loading plugin "upnp_browser"... Apr 20 01:23:29 lctmusic sudo[11506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:23:29 lctmusic sudo[11506]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:29 lctmusic sudo[11508]: volumio : unable to resolve host lctmusic Apr 20 01:23:29 lctmusic sudo[11506]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:29 lctmusic sudo[11508]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:29 lctmusic sudo[11508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:23:29 lctmusic sudo[11508]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:29 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:23:29 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:23:29 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:23:29 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:23:30 lctmusic volumio[11469]: info: Loading plugin "networkfs"... Apr 20 01:23:30 lctmusic volumio[11469]: info: Starting Udev Watcher for removable devices Apr 20 01:23:30 lctmusic volumio[11469]: info: Ignoring mount for partition: boot Apr 20 01:23:30 lctmusic volumio[11469]: info: Ignoring mount for partition: volumio Apr 20 01:23:30 lctmusic volumio[11469]: info: Ignoring mount for partition: volumio_data Apr 20 01:23:30 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:30 lctmusic volumio[11469]: info: Loading plugin "alarm-clock"... Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "airplay_emulation"... Apr 20 01:23:31 lctmusic volumio[11469]: info: Starting Shairport Sync Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "last_100"... Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "webradio"... Apr 20 01:23:31 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:31] [connect] Successful connection Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "i2s_dacs"... Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "volumiodiscovery"... Apr 20 01:23:31 lctmusic volumio[11469]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:31 lctmusic volumio[11469]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:31 lctmusic node[11469]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:31 lctmusic volumio[11469]: *** WARNING *** For more information see Apr 20 01:23:31 lctmusic volumio[11469]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:31 lctmusic volumio[11469]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:31 lctmusic volumio[11469]: *** WARNING *** For more information see Apr 20 01:23:31 lctmusic node[11469]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:31 lctmusic node[11469]: *** WARNING *** For more information see Apr 20 01:23:31 lctmusic node[11469]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:31 lctmusic node[11469]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:31 lctmusic node[11469]: *** WARNING *** For more information see Apr 20 01:23:31 lctmusic volumio[11469]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 01:23:31 lctmusic volumio[11469]: info: Discovery: Started advertising with name: undefined Apr 20 01:23:31 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "outputs"... Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "albumart"... Apr 20 01:23:31 lctmusic volumio[11469]: info: Plugin example_plugin is not enabled Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "inputs"... Apr 20 01:23:31 lctmusic volumio[11469]: info: Loading plugin "updater_comm"... Apr 20 01:23:32 lctmusic volumio[11469]: info: Plugin mpdemulation is not enabled Apr 20 01:23:32 lctmusic volumio[11469]: info: Loading plugin "rest_api"... Apr 20 01:23:32 lctmusic volumio[11469]: info: Loading plugin "websocket"... Apr 20 01:23:32 lctmusic volumio[11469]: info: Loading i18n strings for locale en Apr 20 01:23:32 lctmusic volumio[11469]: Updating browse sources language Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:32 lctmusic mpd[11513]: Apr 20 01:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:23:32 lctmusic mpd[11513]: Apr 20 01:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:23:32 lctmusic mpd[11513]: Apr 20 01:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:23:32 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:23:32 lctmusic sudo[11508]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::initPlayerControls Apr 20 01:23:32 lctmusic volumio[11469]: Forking 3 albumart workers Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:32 lctmusic volumio[11469]: Express server listening on port 3000 Apr 20 01:23:32 lctmusic volumio[11469]: [Metrics] WebUI: 8s 540.07ms Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreStateMachine::resetVolumioState Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreStateMachine::getcurrentVolume Apr 20 01:23:32 lctmusic volumio[11469]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:23:32 lctmusic volumio[11469]: info: MPD Permissions set Apr 20 01:23:32 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713572611 101 Apr 20 01:23:33 lctmusic volumio[11469]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 20 01:23:33 lctmusic volumio[11469]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:23:33 lctmusic volumio[11469]: info: MPD running with PID11513 Apr 20 01:23:33 lctmusic volumio[11469]: ,establishing connection Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: Cannot read play queue from file Apr 20 01:23:33 lctmusic volumio[11469]: error: updateQueue error: null Apr 20 01:23:33 lctmusic volumio[11469]: info: Setting Device type: Raspberry PI Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreStateMachine::setRepeat null single undefined Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreStateMachine::pushState Apr 20 01:23:33 lctmusic volumio[11469]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreStateMachine::setRandom null Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreStateMachine::pushState Apr 20 01:23:33 lctmusic volumio[11469]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:33 lctmusic volumio[11469]: info: Completed loading Core Plugins Apr 20 01:23:33 lctmusic volumio[11469]: info: Preparing to generate the ALSA configuration file Apr 20 01:23:33 lctmusic volumio[11469]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreStateMachine::pushState Apr 20 01:23:33 lctmusic volumio[11469]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreStateMachine::updateTrackBlock Apr 20 01:23:33 lctmusic volumio[11469]: info: CorePlayQueue::getTrackBlock Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:23:33 lctmusic volumio[11469]: error: updateQueue error: null Apr 20 01:23:33 lctmusic volumio[11469]: info: Asound.conf file unchanged, so no further update is needed Apr 20 01:23:33 lctmusic volumio[11469]: info: Output device has changed, restarting MPD Apr 20 01:23:33 lctmusic volumio[11469]: info: Output device has changed, restarting Shairport Sync Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:33 lctmusic sudo[11565]: volumio : unable to resolve host lctmusic Apr 20 01:23:33 lctmusic sudo[11565]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:33 lctmusic sudo[11565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:23:33 lctmusic sudo[11563]: volumio : unable to resolve host lctmusic Apr 20 01:23:33 lctmusic sudo[11565]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:33 lctmusic sudo[11563]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:33 lctmusic sudo[11563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:23:33 lctmusic sudo[11563]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:33 lctmusic sudo[11563]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:33 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:23:33 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:23:33 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:23:33 lctmusic volumio[11469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:33 lctmusic volumio[11469]: info: ___________ START PLUGINS ___________ Apr 20 01:23:33 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:33 lctmusic volumio[11469]: info: [1713572613936] CoreMusicLibrary::Adding element Media Servers Apr 20 01:23:33 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:34 lctmusic volumio[11469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:34 lctmusic volumio[11469]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:34 lctmusic volumio[11469]: info: [1713572614341] CoreMusicLibrary::Adding element Last_100 Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:34 lctmusic volumio[11469]: info: [1713572614349] CoreMusicLibrary::Adding element Webradio Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:34 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:23:34 lctmusic volumio[11469]: info: Volumio Calling Home Apr 20 01:23:34 lctmusic volumio[11469]: info: Completed starting Core Plugins Apr 20 01:23:34 lctmusic volumio[11469]: info: ------------------------------------------- Apr 20 01:23:34 lctmusic volumio[11469]: info: ----- MyVolumio plugins startup ---- Apr 20 01:23:34 lctmusic volumio[11469]: info: ------------------------------------------- Apr 20 01:23:34 lctmusic volumio[11469]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 20 01:23:34 lctmusic volumio[11469]: Starting albumart workers Apr 20 01:23:34 lctmusic volumio[11469]: Starting albumart workers Apr 20 01:23:34 lctmusic volumio[11469]: Starting albumart workers Apr 20 01:23:34 lctmusic volumio[11469]: info: MPD Permissions set Apr 20 01:23:34 lctmusic volumio[11469]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:23:35 lctmusic volumio[11469]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:35 lctmusic volumio[11469]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreStateMachine::pushState Apr 20 01:23:35 lctmusic volumio[11469]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:35 lctmusic volumio[11469]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:35 lctmusic volumio[11469]: info: Starting Shairport Sync Apr 20 01:23:35 lctmusic volumio[11469]: info: Starting Shairport Sync Apr 20 01:23:35 lctmusic volumio[11469]: info: Starting Shairport Sync Apr 20 01:23:35 lctmusic sudo[11592]: volumio : unable to resolve host lctmusic Apr 20 01:23:35 lctmusic sudo[11590]: volumio : unable to resolve host lctmusic Apr 20 01:23:35 lctmusic sudo[11590]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:35 lctmusic sudo[11592]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:35 lctmusic sudo[11590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:35 lctmusic sudo[11590]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:35 lctmusic sudo[11592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:35 lctmusic sudo[11592]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:35 lctmusic sudo[11593]: volumio : unable to resolve host lctmusic Apr 20 01:23:35 lctmusic sudo[11593]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:35 lctmusic sudo[11593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:35 lctmusic sudo[11593]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:35 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:23:35 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:23:35 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:35 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:35 lctmusic sudo[11590]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:35 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:23:35 lctmusic sudo[11592]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:35 lctmusic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 20 01:23:35 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:23:35 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:35 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:35 lctmusic sudo[11593]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:35 lctmusic volumio[11469]: info: Shairport-Sync Started Apr 20 01:23:35 lctmusic volumio[11469]: Error adding Membership: Error: addMembership EINVAL Apr 20 01:23:35 lctmusic volumio[11469]: info: Shairport-Sync Started Apr 20 01:23:35 lctmusic volumio[11469]: info: Shairport-Sync Started Apr 20 01:23:36 lctmusic volumio[11469]: info: CoreCommandRouter::volumioGetState Apr 20 01:23:36 lctmusic volumio[11469]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:36 lctmusic volumio[11469]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 20 01:23:36 lctmusic volumio[11469]: info: Discovery: Started advertising with name: undefined Apr 20 01:23:37 lctmusic mpd[11581]: Apr 20 01:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:23:37 lctmusic mpd[11581]: Apr 20 01:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:23:37 lctmusic mpd[11581]: Apr 20 01:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:23:37 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:23:37 lctmusic sudo[11565]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:37 lctmusic volumio[11469]: error: updateQueue error: null Apr 20 01:23:38 lctmusic volumio[11469]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 01:23:38 lctmusic volumio[11469]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:23:38 lctmusic volumio[11469]: TypeError: Cannot read property 'charAt' of undefined Apr 20 01:23:38 lctmusic volumio[11469]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 20 01:23:38 lctmusic volumio[11469]: at listOnTimeout (internal/timers.js:554:17) Apr 20 01:23:38 lctmusic volumio[11469]: at processTimers (internal/timers.js:497:7) Apr 20 01:23:38 lctmusic volumio[11469]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:23:39 lctmusic sudo[11637]: volumio : unable to resolve host lctmusic Apr 20 01:23:39 lctmusic sudo[11637]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:39 lctmusic sudo[11637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 01:22 Apr 20 01:23:39 lctmusic sudo[11637]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:39 lctmusic sudo[11637]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:40 lctmusic volumio[11469]: sudo: unable to resolve host lctmusic: System error Apr 20 01:23:40 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 20 01:23:40 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 20 01:23:40 lctmusic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 01:23:40 lctmusic systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 01:23:40 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:23:40 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:23:40 lctmusic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 01:23:40 lctmusic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1218. Apr 20 01:23:40 lctmusic systemd[1]: Stopped Volumio Backend Module. Apr 20 01:23:40 lctmusic systemd[1]: Started Volumio Backend Module. Apr 20 01:23:40 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:23:40 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:23:43 lctmusic volumio[11650]: info: ------------------------------------------- Apr 20 01:23:43 lctmusic volumio[11650]: info: ----- Volumio3 ---- Apr 20 01:23:43 lctmusic volumio[11650]: info: ------------------------------------------- Apr 20 01:23:43 lctmusic volumio[11650]: info: ----- System startup ---- Apr 20 01:23:43 lctmusic volumio[11650]: info: ------------------------------------------- Apr 20 01:23:45 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:45] [connect] Successful connection Apr 20 01:23:45 lctmusic volumio[11650]: info: MYVOLUMIO Environment detected Apr 20 01:23:45 lctmusic volumio[11650]: info: Plugin folders cleanup Apr 20 01:23:45 lctmusic volumio[11650]: info: Scanning into folder /volumio/app/plugins/ Apr 20 01:23:45 lctmusic volumio[11650]: info: Scanning category audio_interface Apr 20 01:23:45 lctmusic volumio[11650]: info: Scanning category miscellanea Apr 20 01:23:45 lctmusic volumio[11650]: info: Scanning category music_service Apr 20 01:23:45 lctmusic volumio[11650]: info: Scanning category plugins.json Apr 20 01:23:45 lctmusic volumio[11650]: info: Scanning category system_controller Apr 20 01:23:45 lctmusic volumio[11650]: info: Scanning category user_interface Apr 20 01:23:45 lctmusic volumio[11650]: info: Scanning into folder /data/plugins/ Apr 20 01:23:45 lctmusic volumio[11650]: info: Plugin folders cleanup completed Apr 20 01:23:45 lctmusic volumio[11650]: info: ------------------------------------------- Apr 20 01:23:45 lctmusic volumio[11650]: info: ----- Core plugins startup ---- Apr 20 01:23:45 lctmusic volumio[11650]: info: ------------------------------------------- Apr 20 01:23:45 lctmusic volumio[11650]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 01:23:45 lctmusic volumio[11650]: info: Adding plugin upnp to MyMusic Plugins Apr 20 01:23:45 lctmusic volumio[11650]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 01:23:45 lctmusic volumio[11650]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 01:23:45 lctmusic volumio[11650]: info: Loading plugins from folder /data/plugins/ Apr 20 01:23:45 lctmusic volumio[11650]: info: Loading plugin "system"... Apr 20 01:23:45 lctmusic volumio[11650]: info: Loading plugin "appearance"... Apr 20 01:23:47 lctmusic volumio[11650]: info: Loading plugin "network"... Apr 20 01:23:47 lctmusic volumio[11650]: info: Refreshing Cached IP Addresses Apr 20 01:23:47 lctmusic sudo[11674]: volumio : unable to resolve host lctmusic Apr 20 01:23:47 lctmusic sudo[11674]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:47 lctmusic sudo[11674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 01:23:47 lctmusic sudo[11674]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:47 lctmusic sudo[11674]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:47 lctmusic sudo[11676]: volumio : unable to resolve host lctmusic Apr 20 01:23:47 lctmusic sudo[11676]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:47 lctmusic volumio[11650]: info: Loading plugin "services"... Apr 20 01:23:47 lctmusic volumio[11650]: info: Loading plugin "alsa_controller"... Apr 20 01:23:47 lctmusic sudo[11676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 01:23:47 lctmusic sudo[11676]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:47 lctmusic sudo[11679]: volumio : unable to resolve host lctmusic Apr 20 01:23:47 lctmusic sudo[11679]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:47 lctmusic sudo[11676]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:47 lctmusic sudo[11679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 01:23:47 lctmusic sudo[11679]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:47 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:23:47 lctmusic volumio[11650]: info: Loading plugin "wizard"... Apr 20 01:23:47 lctmusic volumio[11650]: info: Loading plugin "volumio_command_line_client"... Apr 20 01:23:47 lctmusic volumio[11650]: info: Loading plugin "upnp"... Apr 20 01:23:47 lctmusic volumio[11650]: info: [1713572627491] Starting Upmpd Daemon Apr 20 01:23:47 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:47 lctmusic volumio[11650]: info: Loading plugin "my_music"... Apr 20 01:23:47 lctmusic volumio[11650]: info: Loading plugin "mpd"... Apr 20 01:23:48 lctmusic volumio[11650]: info: Creating MPD Configuration file Apr 20 01:23:48 lctmusic sudo[11679]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:48 lctmusic sudo[11687]: volumio : unable to resolve host lctmusic Apr 20 01:23:48 lctmusic sudo[11687]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:48 lctmusic volumio[11650]: info: Loading plugin "upnp_browser"... Apr 20 01:23:48 lctmusic sudo[11687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:23:48 lctmusic sudo[11687]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:48 lctmusic sudo[11689]: volumio : unable to resolve host lctmusic Apr 20 01:23:48 lctmusic sudo[11687]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:48 lctmusic sudo[11689]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:48 lctmusic sudo[11689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:23:48 lctmusic sudo[11689]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:48 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:23:48 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:23:48 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:23:48 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:23:48 lctmusic volumio[11650]: info: Loading plugin "networkfs"... Apr 20 01:23:49 lctmusic volumio[11650]: info: Starting Udev Watcher for removable devices Apr 20 01:23:49 lctmusic volumio[11650]: info: Ignoring mount for partition: boot Apr 20 01:23:49 lctmusic volumio[11650]: info: Ignoring mount for partition: volumio Apr 20 01:23:49 lctmusic volumio[11650]: info: Ignoring mount for partition: volumio_data Apr 20 01:23:49 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:49 lctmusic volumio[11650]: info: Loading plugin "alarm-clock"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "airplay_emulation"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Starting Shairport Sync Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "last_100"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "webradio"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "i2s_dacs"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "volumiodiscovery"... Apr 20 01:23:50 lctmusic volumio[11650]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:50 lctmusic volumio[11650]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:50 lctmusic volumio[11650]: *** WARNING *** For more information see Apr 20 01:23:50 lctmusic node[11650]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:50 lctmusic volumio[11650]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:50 lctmusic volumio[11650]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:50 lctmusic volumio[11650]: *** WARNING *** For more information see Apr 20 01:23:50 lctmusic node[11650]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:50 lctmusic node[11650]: *** WARNING *** For more information see Apr 20 01:23:50 lctmusic node[11650]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:23:50 lctmusic node[11650]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:23:50 lctmusic node[11650]: *** WARNING *** For more information see Apr 20 01:23:50 lctmusic volumio[11650]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 01:23:50 lctmusic volumio[11650]: info: Discovery: Started advertising with name: undefined Apr 20 01:23:50 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "outputs"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "albumart"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Plugin example_plugin is not enabled Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "inputs"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "updater_comm"... Apr 20 01:23:50 lctmusic volumio[11650]: info: Plugin mpdemulation is not enabled Apr 20 01:23:50 lctmusic volumio[11650]: info: Loading plugin "rest_api"... Apr 20 01:23:51 lctmusic volumio[11650]: info: Loading plugin "websocket"... Apr 20 01:23:51 lctmusic volumio[11650]: info: Loading i18n strings for locale en Apr 20 01:23:51 lctmusic volumio[11650]: Updating browse sources language Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:51 lctmusic mpd[11694]: Apr 20 01:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:23:51 lctmusic mpd[11694]: Apr 20 01:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:23:51 lctmusic mpd[11694]: Apr 20 01:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:23:51 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:23:51 lctmusic sudo[11689]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:51 lctmusic volumio[11650]: Forking 3 albumart workers Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::initPlayerControls Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:51 lctmusic volumio[11650]: Express server listening on port 3000 Apr 20 01:23:51 lctmusic volumio[11650]: [Metrics] WebUI: 8s 625.48ms Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreStateMachine::resetVolumioState Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreStateMachine::getcurrentVolume Apr 20 01:23:51 lctmusic volumio[11650]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:23:51 lctmusic volumio[11650]: info: MPD Permissions set Apr 20 01:23:51 lctmusic volumio[11650]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:23:52 lctmusic volumio[11650]: info: MPD running with PID11694 Apr 20 01:23:52 lctmusic volumio[11650]: ,establishing connection Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: Cannot read play queue from file Apr 20 01:23:52 lctmusic volumio[11650]: error: updateQueue error: null Apr 20 01:23:52 lctmusic volumio[11650]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreStateMachine::pushState Apr 20 01:23:52 lctmusic volumio[11650]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreStateMachine::updateTrackBlock Apr 20 01:23:52 lctmusic volumio[11650]: info: CorePlayQueue::getTrackBlock Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreStateMachine::setRepeat null single undefined Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreStateMachine::pushState Apr 20 01:23:52 lctmusic volumio[11650]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreStateMachine::setRandom null Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreStateMachine::pushState Apr 20 01:23:52 lctmusic volumio[11650]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:52 lctmusic volumio[11650]: info: Setting Device type: Raspberry PI Apr 20 01:23:52 lctmusic volumio[11650]: info: Completed loading Core Plugins Apr 20 01:23:52 lctmusic volumio[11650]: info: Preparing to generate the ALSA configuration file Apr 20 01:23:52 lctmusic volumio[11650]: error: updateQueue error: null Apr 20 01:23:52 lctmusic volumio[11650]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:23:52 lctmusic volumio[11650]: info: Asound.conf file unchanged, so no further update is needed Apr 20 01:23:52 lctmusic volumio[11650]: info: Output device has changed, restarting MPD Apr 20 01:23:52 lctmusic volumio[11650]: info: Output device has changed, restarting Shairport Sync Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:52 lctmusic sudo[11745]: volumio : unable to resolve host lctmusic Apr 20 01:23:52 lctmusic sudo[11745]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:52 lctmusic sudo[11745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:23:52 lctmusic sudo[11748]: volumio : unable to resolve host lctmusic Apr 20 01:23:52 lctmusic sudo[11748]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:52 lctmusic sudo[11745]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:52 lctmusic sudo[11748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:23:52 lctmusic sudo[11748]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:52 lctmusic sudo[11745]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:52 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:23:52 lctmusic volumio[11650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:52 lctmusic volumio[11650]: info: ___________ START PLUGINS ___________ Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:52 lctmusic volumio[11650]: info: [1713572632788] CoreMusicLibrary::Adding element Media Servers Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:52 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:23:52 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:52 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:23:52 lctmusic volumio[11650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:52 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:53 lctmusic volumio[11650]: info: [1713572633097] CoreMusicLibrary::Adding element Last_100 Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:23:53 lctmusic volumio[11650]: info: [1713572633109] CoreMusicLibrary::Adding element Webradio Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:23:53 lctmusic volumio[11650]: info: Volumio Calling Home Apr 20 01:23:53 lctmusic volumio[11650]: Starting albumart workers Apr 20 01:23:53 lctmusic volumio[11650]: info: Completed starting Core Plugins Apr 20 01:23:53 lctmusic volumio[11650]: info: ------------------------------------------- Apr 20 01:23:53 lctmusic volumio[11650]: info: ----- MyVolumio plugins startup ---- Apr 20 01:23:53 lctmusic volumio[11650]: info: ------------------------------------------- Apr 20 01:23:53 lctmusic volumio[11650]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 20 01:23:53 lctmusic volumio[11650]: Starting albumart workers Apr 20 01:23:53 lctmusic volumio[11650]: Starting albumart workers Apr 20 01:23:53 lctmusic volumio[11650]: info: MPD Permissions set Apr 20 01:23:53 lctmusic volumio[11650]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreStateMachine::pushState Apr 20 01:23:53 lctmusic volumio[11650]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::volumioPushState Apr 20 01:23:53 lctmusic volumio[11650]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:23:53 lctmusic volumio[11650]: info: Starting Shairport Sync Apr 20 01:23:54 lctmusic volumio[11650]: info: Starting Shairport Sync Apr 20 01:23:54 lctmusic sudo[11771]: volumio : unable to resolve host lctmusic Apr 20 01:23:54 lctmusic volumio[11650]: info: Starting Shairport Sync Apr 20 01:23:54 lctmusic sudo[11771]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:54 lctmusic sudo[11771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:54 lctmusic sudo[11771]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:54 lctmusic sudo[11772]: volumio : unable to resolve host lctmusic Apr 20 01:23:54 lctmusic sudo[11772]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:54 lctmusic sudo[11772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:54 lctmusic sudo[11772]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:54 lctmusic sudo[11775]: volumio : unable to resolve host lctmusic Apr 20 01:23:54 lctmusic sudo[11775]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:54 lctmusic sudo[11775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:23:54 lctmusic sudo[11775]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:54 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:23:54 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:23:54 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:54 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:54 lctmusic volumio[11650]: info: CoreCommandRouter::volumioGetState Apr 20 01:23:54 lctmusic volumio[11650]: info: CorePlayQueue::getTrack 0 Apr 20 01:23:54 lctmusic sudo[11771]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:54 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:23:54 lctmusic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 20 01:23:54 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:23:54 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:54 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:23:54 lctmusic volumio[11650]: info: Shairport-Sync Started Apr 20 01:23:54 lctmusic volumio[11650]: Error adding Membership: Error: addMembership EINVAL Apr 20 01:23:54 lctmusic sudo[11772]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:54 lctmusic sudo[11775]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:54 lctmusic volumio[11650]: info: Shairport-Sync Started Apr 20 01:23:54 lctmusic volumio[11650]: info: Shairport-Sync Started Apr 20 01:23:55 lctmusic volumio[11650]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 20 01:23:55 lctmusic volumio[11650]: info: Discovery: Started advertising with name: undefined Apr 20 01:23:56 lctmusic mpd[11764]: Apr 20 01:23 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:23:56 lctmusic mpd[11764]: Apr 20 01:23 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:23:56 lctmusic mpd[11764]: Apr 20 01:23 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:23:56 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:23:56 lctmusic sudo[11748]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:56 lctmusic volumio[11650]: error: updateQueue error: null Apr 20 01:23:56 lctmusic volumio[11650]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 01:23:57 lctmusic volumio[11650]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:23:57 lctmusic volumio[11650]: TypeError: Cannot read property 'charAt' of undefined Apr 20 01:23:57 lctmusic volumio[11650]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 20 01:23:57 lctmusic volumio[11650]: at listOnTimeout (internal/timers.js:554:17) Apr 20 01:23:57 lctmusic volumio[11650]: at processTimers (internal/timers.js:497:7) Apr 20 01:23:57 lctmusic volumio[11650]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:23:57 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:57] [connect] Successful connection Apr 20 01:23:58 lctmusic sudo[11818]: volumio : unable to resolve host lctmusic Apr 20 01:23:58 lctmusic sudo[11818]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:23:58 lctmusic sudo[11818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 01:22 Apr 20 01:23:58 lctmusic sudo[11818]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:23:58 lctmusic sudo[11818]: pam_unix(sudo:session): session closed for user root Apr 20 01:23:59 lctmusic volumio[11650]: sudo: unable to resolve host lctmusic: System error Apr 20 01:23:59 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:59] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 20 01:23:59 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:59] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 20 01:23:59 lctmusic volumio-remote-updater[533]: [2024-04-20 01:23:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 01:23:59 lctmusic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 01:23:59 lctmusic systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 01:23:59 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:23:59 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:23:59 lctmusic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 01:23:59 lctmusic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1219. Apr 20 01:23:59 lctmusic systemd[1]: Stopped Volumio Backend Module. Apr 20 01:23:59 lctmusic systemd[1]: Started Volumio Backend Module. Apr 20 01:23:59 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:23:59 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:24:02 lctmusic volumio[11831]: info: ------------------------------------------- Apr 20 01:24:02 lctmusic volumio[11831]: info: ----- Volumio3 ---- Apr 20 01:24:02 lctmusic volumio[11831]: info: ------------------------------------------- Apr 20 01:24:02 lctmusic volumio[11831]: info: ----- System startup ---- Apr 20 01:24:02 lctmusic volumio[11831]: info: ------------------------------------------- Apr 20 01:24:04 lctmusic volumio[11831]: info: MYVOLUMIO Environment detected Apr 20 01:24:04 lctmusic volumio[11831]: info: Plugin folders cleanup Apr 20 01:24:04 lctmusic volumio[11831]: info: Scanning into folder /volumio/app/plugins/ Apr 20 01:24:04 lctmusic volumio[11831]: info: Scanning category audio_interface Apr 20 01:24:04 lctmusic volumio[11831]: info: Scanning category miscellanea Apr 20 01:24:04 lctmusic volumio[11831]: info: Scanning category music_service Apr 20 01:24:04 lctmusic volumio[11831]: info: Scanning category plugins.json Apr 20 01:24:04 lctmusic volumio[11831]: info: Scanning category system_controller Apr 20 01:24:04 lctmusic volumio[11831]: info: Scanning category user_interface Apr 20 01:24:04 lctmusic volumio[11831]: info: Scanning into folder /data/plugins/ Apr 20 01:24:04 lctmusic volumio[11831]: info: Plugin folders cleanup completed Apr 20 01:24:04 lctmusic volumio[11831]: info: ------------------------------------------- Apr 20 01:24:04 lctmusic volumio[11831]: info: ----- Core plugins startup ---- Apr 20 01:24:04 lctmusic volumio[11831]: info: ------------------------------------------- Apr 20 01:24:04 lctmusic volumio[11831]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 01:24:04 lctmusic volumio[11831]: info: Adding plugin upnp to MyMusic Plugins Apr 20 01:24:04 lctmusic volumio[11831]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 01:24:04 lctmusic volumio[11831]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 01:24:04 lctmusic volumio[11831]: info: Loading plugins from folder /data/plugins/ Apr 20 01:24:04 lctmusic volumio[11831]: info: Loading plugin "system"... Apr 20 01:24:04 lctmusic volumio[11831]: info: Loading plugin "appearance"... Apr 20 01:24:06 lctmusic volumio[11831]: info: Loading plugin "network"... Apr 20 01:24:06 lctmusic volumio[11831]: info: Refreshing Cached IP Addresses Apr 20 01:24:06 lctmusic sudo[11856]: volumio : unable to resolve host lctmusic Apr 20 01:24:06 lctmusic sudo[11856]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:06 lctmusic sudo[11856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 01:24:06 lctmusic sudo[11856]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:06 lctmusic sudo[11858]: volumio : unable to resolve host lctmusic Apr 20 01:24:06 lctmusic sudo[11858]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:06 lctmusic sudo[11856]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:06 lctmusic volumio[11831]: info: Loading plugin "services"... Apr 20 01:24:06 lctmusic volumio[11831]: info: Loading plugin "alsa_controller"... Apr 20 01:24:06 lctmusic sudo[11858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 01:24:06 lctmusic sudo[11858]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:06 lctmusic sudo[11858]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:06 lctmusic sudo[11861]: volumio : unable to resolve host lctmusic Apr 20 01:24:06 lctmusic sudo[11861]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:06 lctmusic sudo[11861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 01:24:06 lctmusic sudo[11861]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:06 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:24:06 lctmusic volumio[11831]: info: Loading plugin "wizard"... Apr 20 01:24:06 lctmusic volumio[11831]: info: Loading plugin "volumio_command_line_client"... Apr 20 01:24:06 lctmusic volumio[11831]: info: Loading plugin "upnp"... Apr 20 01:24:06 lctmusic volumio[11831]: info: [1713572646475] Starting Upmpd Daemon Apr 20 01:24:06 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:06 lctmusic volumio[11831]: info: Loading plugin "my_music"... Apr 20 01:24:06 lctmusic volumio[11831]: info: Loading plugin "mpd"... Apr 20 01:24:07 lctmusic volumio[11831]: info: Creating MPD Configuration file Apr 20 01:24:07 lctmusic sudo[11861]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:07 lctmusic volumio[11831]: info: Loading plugin "upnp_browser"... Apr 20 01:24:07 lctmusic sudo[11869]: volumio : unable to resolve host lctmusic Apr 20 01:24:07 lctmusic sudo[11869]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:07 lctmusic sudo[11869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:24:07 lctmusic sudo[11869]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:07 lctmusic sudo[11871]: volumio : unable to resolve host lctmusic Apr 20 01:24:07 lctmusic sudo[11871]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:07 lctmusic sudo[11871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:24:07 lctmusic sudo[11869]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:07 lctmusic sudo[11871]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:07 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:24:07 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:24:07 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:24:07 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:24:07 lctmusic volumio[11831]: info: Loading plugin "networkfs"... Apr 20 01:24:08 lctmusic volumio[11831]: info: Starting Udev Watcher for removable devices Apr 20 01:24:08 lctmusic volumio[11831]: info: Ignoring mount for partition: boot Apr 20 01:24:08 lctmusic volumio[11831]: info: Ignoring mount for partition: volumio Apr 20 01:24:08 lctmusic volumio[11831]: info: Ignoring mount for partition: volumio_data Apr 20 01:24:08 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:08 lctmusic volumio[11831]: info: Loading plugin "alarm-clock"... Apr 20 01:24:09 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:09] [connect] Successful connection Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "airplay_emulation"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Starting Shairport Sync Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "last_100"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "webradio"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "i2s_dacs"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "volumiodiscovery"... Apr 20 01:24:09 lctmusic volumio[11831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:09 lctmusic node[11831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:09 lctmusic volumio[11831]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:09 lctmusic node[11831]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:09 lctmusic volumio[11831]: *** WARNING *** For more information see Apr 20 01:24:09 lctmusic node[11831]: *** WARNING *** For more information see Apr 20 01:24:09 lctmusic volumio[11831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:09 lctmusic node[11831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:09 lctmusic volumio[11831]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:09 lctmusic node[11831]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:09 lctmusic volumio[11831]: *** WARNING *** For more information see Apr 20 01:24:09 lctmusic node[11831]: *** WARNING *** For more information see Apr 20 01:24:09 lctmusic volumio[11831]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 01:24:09 lctmusic volumio[11831]: info: Discovery: Started advertising with name: undefined Apr 20 01:24:09 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "outputs"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "albumart"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Plugin example_plugin is not enabled Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "inputs"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "updater_comm"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Plugin mpdemulation is not enabled Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "rest_api"... Apr 20 01:24:09 lctmusic volumio[11831]: info: Loading plugin "websocket"... Apr 20 01:24:10 lctmusic volumio[11831]: info: Loading i18n strings for locale en Apr 20 01:24:10 lctmusic volumio[11831]: Updating browse sources language Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:10 lctmusic mpd[11876]: Apr 20 01:24 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:24:10 lctmusic mpd[11876]: Apr 20 01:24 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:24:10 lctmusic mpd[11876]: Apr 20 01:24 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:24:10 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:24:10 lctmusic sudo[11871]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::initPlayerControls Apr 20 01:24:10 lctmusic volumio[11831]: Forking 3 albumart workers Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:10 lctmusic volumio[11831]: Express server listening on port 3000 Apr 20 01:24:10 lctmusic volumio[11831]: [Metrics] WebUI: 8s 489.01ms Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreStateMachine::resetVolumioState Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreStateMachine::getcurrentVolume Apr 20 01:24:10 lctmusic volumio[11831]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:24:10 lctmusic volumio[11831]: info: MPD Permissions set Apr 20 01:24:10 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713572649 101 Apr 20 01:24:10 lctmusic volumio[11831]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 20 01:24:10 lctmusic volumio[11831]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:24:10 lctmusic volumio[11831]: info: MPD running with PID11876 Apr 20 01:24:10 lctmusic volumio[11831]: ,establishing connection Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: Cannot read play queue from file Apr 20 01:24:11 lctmusic volumio[11831]: error: updateQueue error: null Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreStateMachine::setRepeat null single undefined Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreStateMachine::pushState Apr 20 01:24:11 lctmusic volumio[11831]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreStateMachine::setRandom null Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreStateMachine::pushState Apr 20 01:24:11 lctmusic volumio[11831]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:11 lctmusic volumio[11831]: info: Setting Device type: Raspberry PI Apr 20 01:24:11 lctmusic volumio[11831]: info: Completed loading Core Plugins Apr 20 01:24:11 lctmusic volumio[11831]: info: Preparing to generate the ALSA configuration file Apr 20 01:24:11 lctmusic volumio[11831]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreStateMachine::pushState Apr 20 01:24:11 lctmusic volumio[11831]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreStateMachine::updateTrackBlock Apr 20 01:24:11 lctmusic volumio[11831]: info: CorePlayQueue::getTrackBlock Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:24:11 lctmusic volumio[11831]: error: updateQueue error: null Apr 20 01:24:11 lctmusic volumio[11831]: info: Asound.conf file unchanged, so no further update is needed Apr 20 01:24:11 lctmusic volumio[11831]: info: Output device has changed, restarting MPD Apr 20 01:24:11 lctmusic volumio[11831]: info: Output device has changed, restarting Shairport Sync Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:11 lctmusic sudo[11926]: volumio : unable to resolve host lctmusic Apr 20 01:24:11 lctmusic sudo[11926]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:11 lctmusic sudo[11926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:24:11 lctmusic sudo[11926]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:11 lctmusic sudo[11928]: volumio : unable to resolve host lctmusic Apr 20 01:24:11 lctmusic sudo[11928]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:11 lctmusic sudo[11926]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:11 lctmusic sudo[11928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:24:11 lctmusic sudo[11928]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:11 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:24:11 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:24:11 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:24:11 lctmusic volumio[11831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:11 lctmusic volumio[11831]: info: ___________ START PLUGINS ___________ Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:11 lctmusic volumio[11831]: info: [1713572651715] CoreMusicLibrary::Adding element Media Servers Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:11 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:11 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:12 lctmusic volumio[11831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:12 lctmusic volumio[11831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:12 lctmusic volumio[11831]: info: [1713572652088] CoreMusicLibrary::Adding element Last_100 Apr 20 01:24:12 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:12 lctmusic volumio[11831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:12 lctmusic volumio[11831]: info: [1713572652144] CoreMusicLibrary::Adding element Webradio Apr 20 01:24:12 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:12 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:24:12 lctmusic volumio[11831]: info: Volumio Calling Home Apr 20 01:24:12 lctmusic volumio[11831]: Starting albumart workers Apr 20 01:24:12 lctmusic volumio[11831]: Starting albumart workers Apr 20 01:24:12 lctmusic volumio[11831]: Starting albumart workers Apr 20 01:24:12 lctmusic volumio[11831]: info: Completed starting Core Plugins Apr 20 01:24:12 lctmusic volumio[11831]: info: ------------------------------------------- Apr 20 01:24:12 lctmusic volumio[11831]: info: ----- MyVolumio plugins startup ---- Apr 20 01:24:12 lctmusic volumio[11831]: info: ------------------------------------------- Apr 20 01:24:12 lctmusic volumio[11831]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 20 01:24:13 lctmusic volumio[11831]: info: MPD Permissions set Apr 20 01:24:13 lctmusic volumio[11831]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:24:13 lctmusic volumio[11831]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:13 lctmusic volumio[11831]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreStateMachine::pushState Apr 20 01:24:13 lctmusic volumio[11831]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:13 lctmusic volumio[11831]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:13 lctmusic volumio[11831]: info: Starting Shairport Sync Apr 20 01:24:13 lctmusic volumio[11831]: info: Starting Shairport Sync Apr 20 01:24:13 lctmusic volumio[11831]: info: Starting Shairport Sync Apr 20 01:24:13 lctmusic sudo[11952]: volumio : unable to resolve host lctmusic Apr 20 01:24:13 lctmusic sudo[11952]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:13 lctmusic sudo[11952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:13 lctmusic sudo[11952]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:13 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:24:13 lctmusic sudo[11955]: volumio : unable to resolve host lctmusic Apr 20 01:24:13 lctmusic sudo[11955]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:13 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:24:13 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:13 lctmusic sudo[11956]: volumio : unable to resolve host lctmusic Apr 20 01:24:13 lctmusic sudo[11955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:13 lctmusic sudo[11956]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:13 lctmusic sudo[11955]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:13 lctmusic sudo[11956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:13 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:13 lctmusic sudo[11956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:13 lctmusic sudo[11952]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:13 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:24:13 lctmusic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 20 01:24:13 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:24:13 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:13 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:13 lctmusic sudo[11955]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:13 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:24:13 lctmusic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 20 01:24:13 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:24:13 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:14 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:14 lctmusic sudo[11956]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:14 lctmusic volumio[11831]: info: Shairport-Sync Started Apr 20 01:24:14 lctmusic volumio[11831]: Error adding Membership: Error: addMembership EINVAL Apr 20 01:24:14 lctmusic volumio[11831]: info: Shairport-Sync Started Apr 20 01:24:14 lctmusic volumio[11831]: info: Shairport-Sync Started Apr 20 01:24:14 lctmusic volumio[11831]: info: CoreCommandRouter::volumioGetState Apr 20 01:24:14 lctmusic volumio[11831]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:14 lctmusic volumio[11831]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 20 01:24:14 lctmusic volumio[11831]: info: Discovery: Started advertising with name: undefined Apr 20 01:24:15 lctmusic mpd[11944]: Apr 20 01:24 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:24:15 lctmusic mpd[11944]: Apr 20 01:24 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:24:15 lctmusic mpd[11944]: Apr 20 01:24 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:24:15 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:24:15 lctmusic sudo[11928]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:15 lctmusic volumio[11831]: error: updateQueue error: null Apr 20 01:24:15 lctmusic volumio[11831]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 01:24:16 lctmusic volumio[11831]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:24:16 lctmusic volumio[11831]: TypeError: Cannot read property 'charAt' of undefined Apr 20 01:24:16 lctmusic volumio[11831]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 20 01:24:16 lctmusic volumio[11831]: at listOnTimeout (internal/timers.js:554:17) Apr 20 01:24:16 lctmusic volumio[11831]: at processTimers (internal/timers.js:497:7) Apr 20 01:24:16 lctmusic volumio[11831]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:24:17 lctmusic sudo[12001]: volumio : unable to resolve host lctmusic Apr 20 01:24:17 lctmusic sudo[12001]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:17 lctmusic sudo[12001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 01:23 Apr 20 01:24:17 lctmusic sudo[12001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:17 lctmusic sudo[12001]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:17 lctmusic volumio[11831]: sudo: unable to resolve host lctmusic: System error Apr 20 01:24:17 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 20 01:24:17 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 20 01:24:17 lctmusic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 01:24:17 lctmusic systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 01:24:18 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:24:18 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:24:18 lctmusic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 01:24:18 lctmusic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1220. Apr 20 01:24:18 lctmusic systemd[1]: Stopped Volumio Backend Module. Apr 20 01:24:18 lctmusic systemd[1]: Started Volumio Backend Module. Apr 20 01:24:18 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:24:18 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:24:21 lctmusic volumio[12014]: info: ------------------------------------------- Apr 20 01:24:21 lctmusic volumio[12014]: info: ----- Volumio3 ---- Apr 20 01:24:21 lctmusic volumio[12014]: info: ------------------------------------------- Apr 20 01:24:21 lctmusic volumio[12014]: info: ----- System startup ---- Apr 20 01:24:21 lctmusic volumio[12014]: info: ------------------------------------------- Apr 20 01:24:22 lctmusic volumio[12014]: info: MYVOLUMIO Environment detected Apr 20 01:24:22 lctmusic volumio[12014]: info: Plugin folders cleanup Apr 20 01:24:22 lctmusic volumio[12014]: info: Scanning into folder /volumio/app/plugins/ Apr 20 01:24:22 lctmusic volumio[12014]: info: Scanning category audio_interface Apr 20 01:24:22 lctmusic volumio[12014]: info: Scanning category miscellanea Apr 20 01:24:22 lctmusic volumio[12014]: info: Scanning category music_service Apr 20 01:24:22 lctmusic volumio[12014]: info: Scanning category plugins.json Apr 20 01:24:22 lctmusic volumio[12014]: info: Scanning category system_controller Apr 20 01:24:22 lctmusic volumio[12014]: info: Scanning category user_interface Apr 20 01:24:22 lctmusic volumio[12014]: info: Scanning into folder /data/plugins/ Apr 20 01:24:22 lctmusic volumio[12014]: info: Plugin folders cleanup completed Apr 20 01:24:22 lctmusic volumio[12014]: info: ------------------------------------------- Apr 20 01:24:22 lctmusic volumio[12014]: info: ----- Core plugins startup ---- Apr 20 01:24:22 lctmusic volumio[12014]: info: ------------------------------------------- Apr 20 01:24:22 lctmusic volumio[12014]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 01:24:22 lctmusic volumio[12014]: info: Adding plugin upnp to MyMusic Plugins Apr 20 01:24:22 lctmusic volumio[12014]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 01:24:22 lctmusic volumio[12014]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 01:24:22 lctmusic volumio[12014]: info: Loading plugins from folder /data/plugins/ Apr 20 01:24:22 lctmusic volumio[12014]: info: Loading plugin "system"... Apr 20 01:24:22 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:22] [connect] Successful connection Apr 20 01:24:22 lctmusic volumio[12014]: info: Loading plugin "appearance"... Apr 20 01:24:24 lctmusic volumio[12014]: info: Loading plugin "network"... Apr 20 01:24:24 lctmusic volumio[12014]: info: Refreshing Cached IP Addresses Apr 20 01:24:24 lctmusic sudo[12038]: volumio : unable to resolve host lctmusic Apr 20 01:24:24 lctmusic sudo[12038]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:24 lctmusic sudo[12038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 01:24:24 lctmusic sudo[12038]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:24 lctmusic sudo[12038]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:24 lctmusic sudo[12040]: volumio : unable to resolve host lctmusic Apr 20 01:24:24 lctmusic sudo[12040]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:24 lctmusic volumio[12014]: info: Loading plugin "services"... Apr 20 01:24:24 lctmusic sudo[12040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 01:24:24 lctmusic sudo[12040]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:24 lctmusic volumio[12014]: info: Loading plugin "alsa_controller"... Apr 20 01:24:24 lctmusic sudo[12040]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:24 lctmusic sudo[12043]: volumio : unable to resolve host lctmusic Apr 20 01:24:24 lctmusic sudo[12043]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:24 lctmusic sudo[12043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 01:24:24 lctmusic sudo[12043]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:25 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:24:25 lctmusic volumio[12014]: info: Loading plugin "wizard"... Apr 20 01:24:25 lctmusic volumio[12014]: info: Loading plugin "volumio_command_line_client"... Apr 20 01:24:25 lctmusic volumio[12014]: info: Loading plugin "upnp"... Apr 20 01:24:25 lctmusic volumio[12014]: info: [1713572665090] Starting Upmpd Daemon Apr 20 01:24:25 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:25 lctmusic volumio[12014]: info: Loading plugin "my_music"... Apr 20 01:24:25 lctmusic volumio[12014]: info: Loading plugin "mpd"... Apr 20 01:24:25 lctmusic sudo[12043]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:25 lctmusic volumio[12014]: info: Creating MPD Configuration file Apr 20 01:24:25 lctmusic sudo[12051]: volumio : unable to resolve host lctmusic Apr 20 01:24:25 lctmusic sudo[12051]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:25 lctmusic volumio[12014]: info: Loading plugin "upnp_browser"... Apr 20 01:24:25 lctmusic sudo[12051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:24:25 lctmusic sudo[12051]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:25 lctmusic sudo[12053]: volumio : unable to resolve host lctmusic Apr 20 01:24:25 lctmusic sudo[12051]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:25 lctmusic sudo[12053]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:25 lctmusic sudo[12053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:24:25 lctmusic sudo[12053]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:25 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:24:25 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:24:25 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:24:26 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:24:26 lctmusic volumio[12014]: info: Loading plugin "networkfs"... Apr 20 01:24:26 lctmusic volumio[12014]: info: Starting Udev Watcher for removable devices Apr 20 01:24:27 lctmusic volumio[12014]: info: Ignoring mount for partition: boot Apr 20 01:24:27 lctmusic volumio[12014]: info: Ignoring mount for partition: volumio Apr 20 01:24:27 lctmusic volumio[12014]: info: Ignoring mount for partition: volumio_data Apr 20 01:24:27 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:27 lctmusic volumio[12014]: info: Loading plugin "alarm-clock"... Apr 20 01:24:27 lctmusic volumio[12014]: info: Loading plugin "airplay_emulation"... Apr 20 01:24:27 lctmusic volumio[12014]: info: Starting Shairport Sync Apr 20 01:24:27 lctmusic volumio[12014]: info: Loading plugin "last_100"... Apr 20 01:24:27 lctmusic volumio[12014]: info: Loading plugin "webradio"... Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading plugin "i2s_dacs"... Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading plugin "volumiodiscovery"... Apr 20 01:24:28 lctmusic volumio[12014]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:28 lctmusic node[12014]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:28 lctmusic volumio[12014]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:28 lctmusic node[12014]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:28 lctmusic volumio[12014]: *** WARNING *** For more information see Apr 20 01:24:28 lctmusic node[12014]: *** WARNING *** For more information see Apr 20 01:24:28 lctmusic volumio[12014]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:28 lctmusic node[12014]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:28 lctmusic volumio[12014]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:28 lctmusic node[12014]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:28 lctmusic volumio[12014]: *** WARNING *** For more information see Apr 20 01:24:28 lctmusic node[12014]: *** WARNING *** For more information see Apr 20 01:24:28 lctmusic volumio[12014]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 01:24:28 lctmusic volumio[12014]: info: Discovery: Started advertising with name: undefined Apr 20 01:24:28 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading plugin "outputs"... Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading plugin "albumart"... Apr 20 01:24:28 lctmusic volumio[12014]: info: Plugin example_plugin is not enabled Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading plugin "inputs"... Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading plugin "updater_comm"... Apr 20 01:24:28 lctmusic volumio[12014]: info: Plugin mpdemulation is not enabled Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading plugin "rest_api"... Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading plugin "websocket"... Apr 20 01:24:28 lctmusic volumio[12014]: info: Loading i18n strings for locale en Apr 20 01:24:28 lctmusic volumio[12014]: Updating browse sources language Apr 20 01:24:28 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:28 lctmusic mpd[12058]: Apr 20 01:24 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:24:28 lctmusic mpd[12058]: Apr 20 01:24 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:24:28 lctmusic mpd[12058]: Apr 20 01:24 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:24:28 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:24:28 lctmusic sudo[12053]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:29 lctmusic volumio[12014]: Forking 3 albumart workers Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::initPlayerControls Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: Express server listening on port 3000 Apr 20 01:24:29 lctmusic volumio[12014]: [Metrics] WebUI: 8s 636.91ms Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreStateMachine::resetVolumioState Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreStateMachine::getcurrentVolume Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:24:29 lctmusic volumio[12014]: info: MPD Permissions set Apr 20 01:24:29 lctmusic volumio[12014]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:24:29 lctmusic volumio[12014]: info: MPD running with PID12058 Apr 20 01:24:29 lctmusic volumio[12014]: ,establishing connection Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:29 lctmusic volumio[12014]: info: Cannot read play queue from file Apr 20 01:24:29 lctmusic volumio[12014]: error: updateQueue error: null Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreStateMachine::setRepeat null single undefined Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreStateMachine::pushState Apr 20 01:24:29 lctmusic volumio[12014]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreStateMachine::setRandom null Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreStateMachine::pushState Apr 20 01:24:29 lctmusic volumio[12014]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:29 lctmusic volumio[12014]: info: Setting Device type: Raspberry PI Apr 20 01:24:29 lctmusic volumio[12014]: info: Completed loading Core Plugins Apr 20 01:24:29 lctmusic volumio[12014]: info: Preparing to generate the ALSA configuration file Apr 20 01:24:29 lctmusic volumio[12014]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreStateMachine::pushState Apr 20 01:24:29 lctmusic volumio[12014]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreStateMachine::updateTrackBlock Apr 20 01:24:29 lctmusic volumio[12014]: info: CorePlayQueue::getTrackBlock Apr 20 01:24:29 lctmusic volumio[12014]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:24:29 lctmusic volumio[12014]: error: updateQueue error: null Apr 20 01:24:29 lctmusic volumio[12014]: info: Asound.conf file unchanged, so no further update is needed Apr 20 01:24:29 lctmusic volumio[12014]: info: Output device has changed, restarting MPD Apr 20 01:24:30 lctmusic volumio[12014]: info: Output device has changed, restarting Shairport Sync Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:30 lctmusic sudo[12108]: volumio : unable to resolve host lctmusic Apr 20 01:24:30 lctmusic sudo[12108]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:30 lctmusic sudo[12108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:24:30 lctmusic sudo[12110]: volumio : unable to resolve host lctmusic Apr 20 01:24:30 lctmusic sudo[12110]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:30 lctmusic sudo[12108]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:30 lctmusic sudo[12110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:24:30 lctmusic sudo[12110]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:30 lctmusic sudo[12108]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:30 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:24:30 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:24:30 lctmusic volumio[12014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:30 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:24:30 lctmusic volumio[12014]: info: ___________ START PLUGINS ___________ Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:30 lctmusic volumio[12014]: info: [1713572670346] CoreMusicLibrary::Adding element Media Servers Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:30 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:30 lctmusic volumio[12014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:30 lctmusic volumio[12014]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:30 lctmusic volumio[12014]: info: [1713572670637] CoreMusicLibrary::Adding element Last_100 Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:30 lctmusic volumio[12014]: info: [1713572670650] CoreMusicLibrary::Adding element Webradio Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:30 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:24:30 lctmusic volumio[12014]: info: Volumio Calling Home Apr 20 01:24:30 lctmusic volumio[12014]: Starting albumart workers Apr 20 01:24:31 lctmusic volumio[12014]: info: Completed starting Core Plugins Apr 20 01:24:31 lctmusic volumio[12014]: info: ------------------------------------------- Apr 20 01:24:31 lctmusic volumio[12014]: info: ----- MyVolumio plugins startup ---- Apr 20 01:24:31 lctmusic volumio[12014]: info: ------------------------------------------- Apr 20 01:24:31 lctmusic volumio[12014]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 20 01:24:31 lctmusic volumio[12014]: Starting albumart workers Apr 20 01:24:31 lctmusic volumio[12014]: Starting albumart workers Apr 20 01:24:31 lctmusic volumio[12014]: info: MPD Permissions set Apr 20 01:24:31 lctmusic volumio[12014]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:24:31 lctmusic volumio[12014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:31 lctmusic volumio[12014]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreStateMachine::pushState Apr 20 01:24:31 lctmusic volumio[12014]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:31 lctmusic volumio[12014]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:31 lctmusic volumio[12014]: info: Starting Shairport Sync Apr 20 01:24:31 lctmusic volumio[12014]: info: Starting Shairport Sync Apr 20 01:24:31 lctmusic volumio[12014]: info: Starting Shairport Sync Apr 20 01:24:31 lctmusic sudo[12134]: volumio : unable to resolve host lctmusic Apr 20 01:24:31 lctmusic sudo[12134]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:31 lctmusic sudo[12134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:31 lctmusic sudo[12134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:31 lctmusic sudo[12136]: volumio : unable to resolve host lctmusic Apr 20 01:24:31 lctmusic sudo[12136]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:31 lctmusic sudo[12136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:31 lctmusic sudo[12136]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:31 lctmusic sudo[12138]: volumio : unable to resolve host lctmusic Apr 20 01:24:31 lctmusic sudo[12138]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:31 lctmusic sudo[12138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:31 lctmusic sudo[12138]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:32 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:24:32 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:24:32 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:32 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:32 lctmusic sudo[12138]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:32 lctmusic sudo[12134]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:32 lctmusic sudo[12136]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:32 lctmusic volumio[12014]: info: CoreCommandRouter::volumioGetState Apr 20 01:24:32 lctmusic volumio[12014]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:32 lctmusic volumio[12014]: info: Shairport-Sync Started Apr 20 01:24:32 lctmusic volumio[12014]: Error adding Membership: Error: addMembership EINVAL Apr 20 01:24:32 lctmusic volumio[12014]: info: Shairport-Sync Started Apr 20 01:24:32 lctmusic volumio[12014]: info: Shairport-Sync Started Apr 20 01:24:33 lctmusic volumio[12014]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 20 01:24:33 lctmusic volumio[12014]: info: Discovery: Started advertising with name: undefined Apr 20 01:24:33 lctmusic mpd[12127]: Apr 20 01:24 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:24:33 lctmusic mpd[12127]: Apr 20 01:24 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:24:33 lctmusic mpd[12127]: Apr 20 01:24 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:24:33 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:24:33 lctmusic sudo[12110]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:33 lctmusic volumio[12014]: error: updateQueue error: null Apr 20 01:24:34 lctmusic volumio[12014]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 01:24:35 lctmusic volumio[12014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:24:35 lctmusic volumio[12014]: TypeError: Cannot read property 'charAt' of undefined Apr 20 01:24:35 lctmusic volumio[12014]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 20 01:24:35 lctmusic volumio[12014]: at listOnTimeout (internal/timers.js:554:17) Apr 20 01:24:35 lctmusic volumio[12014]: at processTimers (internal/timers.js:497:7) Apr 20 01:24:35 lctmusic volumio[12014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:24:35 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:35] [connect] Successful connection Apr 20 01:24:36 lctmusic sudo[12180]: volumio : unable to resolve host lctmusic Apr 20 01:24:36 lctmusic sudo[12180]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Apr 20 01:24:36 lctmusic sudo[12180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 01:23 Apr 20 01:24:36 lctmusic sudo[12180]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:36 lctmusic sudo[12180]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:36 lctmusic volumio[12014]: sudo: unable to resolve host lctmusic: System error Apr 20 01:24:36 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:36] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 20 01:24:36 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:36] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 20 01:24:36 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 01:24:36 lctmusic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 01:24:36 lctmusic systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 01:24:36 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:24:36 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:24:36 lctmusic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 01:24:36 lctmusic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1221. Apr 20 01:24:36 lctmusic systemd[1]: Started dynamicswap service. Apr 20 01:24:36 lctmusic systemd[1]: Stopped Volumio Backend Module. Apr 20 01:24:36 lctmusic systemd[1]: Started Volumio Backend Module. Apr 20 01:24:37 lctmusic systemd[1]: dynamicswap.service: Succeeded. Apr 20 01:24:40 lctmusic volumio[12195]: info: ------------------------------------------- Apr 20 01:24:40 lctmusic volumio[12195]: info: ----- Volumio3 ---- Apr 20 01:24:40 lctmusic volumio[12195]: info: ------------------------------------------- Apr 20 01:24:40 lctmusic volumio[12195]: info: ----- System startup ---- Apr 20 01:24:40 lctmusic volumio[12195]: info: ------------------------------------------- Apr 20 01:24:41 lctmusic volumio[12195]: info: MYVOLUMIO Environment detected Apr 20 01:24:41 lctmusic volumio[12195]: info: Plugin folders cleanup Apr 20 01:24:41 lctmusic volumio[12195]: info: Scanning into folder /volumio/app/plugins/ Apr 20 01:24:41 lctmusic volumio[12195]: info: Scanning category audio_interface Apr 20 01:24:41 lctmusic volumio[12195]: info: Scanning category miscellanea Apr 20 01:24:41 lctmusic volumio[12195]: info: Scanning category music_service Apr 20 01:24:41 lctmusic volumio[12195]: info: Scanning category plugins.json Apr 20 01:24:41 lctmusic volumio[12195]: info: Scanning category system_controller Apr 20 01:24:41 lctmusic volumio[12195]: info: Scanning category user_interface Apr 20 01:24:41 lctmusic volumio[12195]: info: Scanning into folder /data/plugins/ Apr 20 01:24:41 lctmusic volumio[12195]: info: Plugin folders cleanup completed Apr 20 01:24:41 lctmusic volumio[12195]: info: ------------------------------------------- Apr 20 01:24:41 lctmusic volumio[12195]: info: ----- Core plugins startup ---- Apr 20 01:24:41 lctmusic volumio[12195]: info: ------------------------------------------- Apr 20 01:24:41 lctmusic volumio[12195]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 01:24:41 lctmusic volumio[12195]: info: Adding plugin upnp to MyMusic Plugins Apr 20 01:24:41 lctmusic volumio[12195]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 01:24:41 lctmusic volumio[12195]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 01:24:41 lctmusic volumio[12195]: info: Loading plugins from folder /data/plugins/ Apr 20 01:24:41 lctmusic volumio[12195]: info: Loading plugin "system"... Apr 20 01:24:41 lctmusic volumio[12195]: info: Loading plugin "appearance"... Apr 20 01:24:43 lctmusic volumio[12195]: info: Loading plugin "network"... Apr 20 01:24:43 lctmusic volumio[12195]: info: Refreshing Cached IP Addresses Apr 20 01:24:43 lctmusic sudo[12242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 01:24:43 lctmusic sudo[12242]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:43 lctmusic sudo[12242]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:43 lctmusic volumio[12195]: info: Loading plugin "services"... Apr 20 01:24:43 lctmusic sudo[12244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 01:24:43 lctmusic volumio[12195]: info: Loading plugin "alsa_controller"... Apr 20 01:24:43 lctmusic sudo[12244]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:43 lctmusic sudo[12244]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:43 lctmusic sudo[12247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 01:24:43 lctmusic sudo[12247]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:43 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:24:43 lctmusic volumio[12195]: info: Loading plugin "wizard"... Apr 20 01:24:43 lctmusic volumio[12195]: info: Loading plugin "volumio_command_line_client"... Apr 20 01:24:43 lctmusic volumio[12195]: info: Loading plugin "upnp"... Apr 20 01:24:44 lctmusic volumio[12195]: info: [1713572684005] Starting Upmpd Daemon Apr 20 01:24:44 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:44 lctmusic volumio[12195]: info: Loading plugin "my_music"... Apr 20 01:24:44 lctmusic volumio[12195]: info: Loading plugin "mpd"... Apr 20 01:24:44 lctmusic volumio[12195]: info: Creating MPD Configuration file Apr 20 01:24:44 lctmusic sudo[12247]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:44 lctmusic volumio[12195]: info: Loading plugin "upnp_browser"... Apr 20 01:24:44 lctmusic sudo[12255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:24:44 lctmusic sudo[12255]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:44 lctmusic sudo[12255]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:44 lctmusic sudo[12257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:24:44 lctmusic sudo[12257]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:44 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:24:44 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:24:44 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:24:44 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:24:45 lctmusic volumio[12195]: info: Loading plugin "networkfs"... Apr 20 01:24:45 lctmusic volumio[12195]: info: Starting Udev Watcher for removable devices Apr 20 01:24:46 lctmusic volumio[12195]: info: Ignoring mount for partition: boot Apr 20 01:24:46 lctmusic volumio[12195]: info: Ignoring mount for partition: volumio Apr 20 01:24:46 lctmusic volumio[12195]: info: Ignoring mount for partition: volumio_data Apr 20 01:24:46 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:46 lctmusic volumio[12195]: info: Loading plugin "alarm-clock"... Apr 20 01:24:46 lctmusic volumio[12195]: info: Loading plugin "airplay_emulation"... Apr 20 01:24:46 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:46] [connect] Successful connection Apr 20 01:24:46 lctmusic volumio[12195]: info: Starting Shairport Sync Apr 20 01:24:46 lctmusic volumio[12195]: info: Loading plugin "last_100"... Apr 20 01:24:46 lctmusic volumio[12195]: info: Loading plugin "webradio"... Apr 20 01:24:46 lctmusic volumio[12195]: info: Loading plugin "i2s_dacs"... Apr 20 01:24:46 lctmusic volumio[12195]: info: Loading plugin "volumiodiscovery"... Apr 20 01:24:46 lctmusic volumio[12195]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:46 lctmusic volumio[12195]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:46 lctmusic volumio[12195]: *** WARNING *** For more information see Apr 20 01:24:46 lctmusic node[12195]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:46 lctmusic volumio[12195]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:46 lctmusic volumio[12195]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:46 lctmusic volumio[12195]: *** WARNING *** For more information see Apr 20 01:24:46 lctmusic node[12195]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:46 lctmusic node[12195]: *** WARNING *** For more information see Apr 20 01:24:46 lctmusic node[12195]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 01:24:46 lctmusic node[12195]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 01:24:46 lctmusic node[12195]: *** WARNING *** For more information see Apr 20 01:24:46 lctmusic volumio[12195]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 01:24:46 lctmusic volumio[12195]: info: Discovery: Started advertising with name: undefined Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 01:24:47 lctmusic volumio[12195]: info: Loading plugin "outputs"... Apr 20 01:24:47 lctmusic volumio[12195]: info: Loading plugin "albumart"... Apr 20 01:24:47 lctmusic volumio[12195]: info: Plugin example_plugin is not enabled Apr 20 01:24:47 lctmusic volumio[12195]: info: Loading plugin "inputs"... Apr 20 01:24:47 lctmusic volumio[12195]: info: Loading plugin "updater_comm"... Apr 20 01:24:47 lctmusic volumio[12195]: info: Plugin mpdemulation is not enabled Apr 20 01:24:47 lctmusic volumio[12195]: info: Loading plugin "rest_api"... Apr 20 01:24:47 lctmusic volumio[12195]: info: Loading plugin "websocket"... Apr 20 01:24:47 lctmusic volumio[12195]: info: Loading i18n strings for locale en Apr 20 01:24:47 lctmusic volumio[12195]: Updating browse sources language Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:47 lctmusic mpd[12262]: Apr 20 01:24 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:24:47 lctmusic mpd[12262]: Apr 20 01:24 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:24:47 lctmusic mpd[12262]: Apr 20 01:24 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:24:47 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:24:47 lctmusic sudo[12257]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:47 lctmusic volumio[12195]: Forking 3 albumart workers Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::initPlayerControls Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:47 lctmusic volumio[12195]: Express server listening on port 3000 Apr 20 01:24:47 lctmusic volumio[12195]: [Metrics] WebUI: 8s 511.54ms Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreStateMachine::resetVolumioState Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreStateMachine::getcurrentVolume Apr 20 01:24:47 lctmusic volumio[12195]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:24:48 lctmusic volumio[12195]: info: MPD Permissions set Apr 20 01:24:48 lctmusic volumio-remote-updater[533]: [2024-04-20 01:24:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713572686 101 Apr 20 01:24:48 lctmusic volumio[12195]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 20 01:24:48 lctmusic volumio[12195]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:24:48 lctmusic volumio[12195]: info: MPD running with PID12262 Apr 20 01:24:48 lctmusic volumio[12195]: ,establishing connection Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: Cannot read play queue from file Apr 20 01:24:48 lctmusic volumio[12195]: error: updateQueue error: null Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreStateMachine::setRepeat null single undefined Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreStateMachine::pushState Apr 20 01:24:48 lctmusic volumio[12195]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreStateMachine::setRandom null Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreStateMachine::pushState Apr 20 01:24:48 lctmusic volumio[12195]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:48 lctmusic volumio[12195]: info: Setting Device type: Raspberry PI Apr 20 01:24:48 lctmusic volumio[12195]: info: Completed loading Core Plugins Apr 20 01:24:48 lctmusic volumio[12195]: info: Preparing to generate the ALSA configuration file Apr 20 01:24:48 lctmusic volumio[12195]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreStateMachine::pushState Apr 20 01:24:48 lctmusic volumio[12195]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreStateMachine::updateTrackBlock Apr 20 01:24:48 lctmusic volumio[12195]: info: CorePlayQueue::getTrackBlock Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 01:24:48 lctmusic volumio[12195]: error: updateQueue error: null Apr 20 01:24:48 lctmusic volumio[12195]: info: Asound.conf file unchanged, so no further update is needed Apr 20 01:24:48 lctmusic volumio[12195]: info: Output device has changed, restarting MPD Apr 20 01:24:48 lctmusic volumio[12195]: info: Output device has changed, restarting Shairport Sync Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:48 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:48 lctmusic sudo[12314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 01:24:48 lctmusic sudo[12314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:48 lctmusic sudo[12312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 01:24:48 lctmusic sudo[12312]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:49 lctmusic sudo[12312]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:49 lctmusic systemd[1]: Stopping Music Player Daemon... Apr 20 01:24:49 lctmusic volumio[12195]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:49 lctmusic systemd[1]: mpd.service: Succeeded. Apr 20 01:24:49 lctmusic systemd[1]: Stopped Music Player Daemon. Apr 20 01:24:49 lctmusic volumio[12195]: info: ___________ START PLUGINS ___________ Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:49 lctmusic volumio[12195]: info: [1713572689200] CoreMusicLibrary::Adding element Media Servers Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:49 lctmusic systemd[1]: Starting Music Player Daemon... Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:49 lctmusic volumio[12195]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:49 lctmusic volumio[12195]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:49 lctmusic volumio[12195]: info: [1713572689514] CoreMusicLibrary::Adding element Last_100 Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 01:24:49 lctmusic volumio[12195]: info: [1713572689521] CoreMusicLibrary::Adding element Webradio Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 01:24:49 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 01:24:49 lctmusic volumio[12195]: info: Volumio Calling Home Apr 20 01:24:49 lctmusic volumio[12195]: Starting albumart workers Apr 20 01:24:50 lctmusic volumio[12195]: info: Completed starting Core Plugins Apr 20 01:24:50 lctmusic volumio[12195]: info: ------------------------------------------- Apr 20 01:24:50 lctmusic volumio[12195]: info: ----- MyVolumio plugins startup ---- Apr 20 01:24:50 lctmusic volumio[12195]: info: ------------------------------------------- Apr 20 01:24:50 lctmusic volumio[12195]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 20 01:24:50 lctmusic volumio[12195]: Starting albumart workers Apr 20 01:24:50 lctmusic volumio[12195]: Starting albumart workers Apr 20 01:24:50 lctmusic volumio[12195]: info: MPD Permissions set Apr 20 01:24:50 lctmusic volumio[12195]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 20 01:24:50 lctmusic volumio[12195]: info: Volumio called home Apr 20 01:24:50 lctmusic volumio[12195]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 01:24:50 lctmusic volumio[12195]: info: VolumeController:: Volume=undefined Mute =false Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreStateMachine::pushState Apr 20 01:24:50 lctmusic volumio[12195]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:50 lctmusic volumio[12195]: info: CoreCommandRouter::volumioPushState Apr 20 01:24:50 lctmusic volumio[12195]: info: Starting Shairport Sync Apr 20 01:24:50 lctmusic volumio[12195]: info: Starting Shairport Sync Apr 20 01:24:50 lctmusic volumio[12195]: info: Starting Shairport Sync Apr 20 01:24:50 lctmusic sudo[12339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:50 lctmusic sudo[12339]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:51 lctmusic sudo[12341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:51 lctmusic sudo[12341]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:51 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:24:51 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:24:51 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:51 lctmusic sudo[12344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 01:24:51 lctmusic sudo[12344]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 01:24:51 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:51 lctmusic sudo[12339]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:51 lctmusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 01:24:51 lctmusic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 20 01:24:51 lctmusic systemd[1]: shairport-sync.service: Succeeded. Apr 20 01:24:51 lctmusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:51 lctmusic volumio[12195]: info: Shairport-Sync Started Apr 20 01:24:51 lctmusic volumio[12195]: Error adding Membership: Error: addMembership EINVAL Apr 20 01:24:51 lctmusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 01:24:51 lctmusic sudo[12344]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:51 lctmusic sudo[12341]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:51 lctmusic volumio[12195]: info: CoreCommandRouter::volumioGetState Apr 20 01:24:51 lctmusic volumio[12195]: info: CorePlayQueue::getTrack 0 Apr 20 01:24:51 lctmusic volumio[12195]: info: Shairport-Sync Started Apr 20 01:24:51 lctmusic volumio[12195]: info: Shairport-Sync Started Apr 20 01:24:52 lctmusic volumio[12195]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 20 01:24:52 lctmusic volumio[12195]: info: Discovery: Started advertising with name: undefined Apr 20 01:24:52 lctmusic mpd[12332]: Apr 20 01:24 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 01:24:52 lctmusic mpd[12332]: Apr 20 01:24 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 01:24:52 lctmusic mpd[12332]: Apr 20 01:24 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 01:24:52 lctmusic systemd[1]: Started Music Player Daemon. Apr 20 01:24:52 lctmusic sudo[12314]: pam_unix(sudo:session): session closed for user root Apr 20 01:24:52 lctmusic volumio[12195]: error: updateQueue error: null Apr 20 01:24:53 lctmusic volumio[12195]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 01:24:54 lctmusic volumio[12195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:24:54 lctmusic volumio[12195]: TypeError: Cannot read property 'charAt' of undefined Apr 20 01:24:54 lctmusic volumio[12195]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 20 01:24:54 lctmusic volumio[12195]: at listOnTimeout (internal/timers.js:554:17) Apr 20 01:24:54 lctmusic volumio[12195]: at processTimers (internal/timers.js:497:7) Apr 20 01:24:54 lctmusic volumio[12195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 01:24:55 lctmusic sudo[12386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 01:23 Apr 20 01:24:55 lctmusic sudo[12386]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"