-- Logs begin at Wed 2024-08-28 04:47:19 CEST, end at Wed 2024-08-28 05:22:57 CEST. -- Aug 28 05:21:00 volumio volumio[23748]: info: MYVOLUMIO Environment detected Aug 28 05:21:00 volumio volumio[23748]: info: Plugin folders cleanup Aug 28 05:21:00 volumio volumio[23748]: info: Scanning into folder /volumio/app/plugins/ Aug 28 05:21:00 volumio volumio[23748]: info: Scanning category audio_interface Aug 28 05:21:00 volumio volumio[23748]: info: Scanning category miscellanea Aug 28 05:21:00 volumio volumio[23748]: info: Scanning category music_service Aug 28 05:21:00 volumio volumio[23748]: info: Scanning category plugins.json Aug 28 05:21:00 volumio volumio[23748]: info: Scanning category system_controller Aug 28 05:21:00 volumio volumio[23748]: info: Scanning category user_interface Aug 28 05:21:00 volumio volumio[23748]: info: Scanning into folder /data/plugins/ Aug 28 05:21:00 volumio volumio[23748]: info: Plugin folders cleanup completed Aug 28 05:21:00 volumio volumio[23748]: info: ------------------------------------------- Aug 28 05:21:00 volumio volumio[23748]: info: ----- Core plugins startup ---- Aug 28 05:21:00 volumio volumio[23748]: info: ------------------------------------------- Aug 28 05:21:00 volumio volumio[23748]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 05:21:00 volumio volumio[23748]: info: Adding plugin upnp to MyMusic Plugins Aug 28 05:21:00 volumio volumio[23748]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 05:21:00 volumio volumio[23748]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 05:21:00 volumio volumio[23748]: info: Loading plugins from folder /data/plugins/ Aug 28 05:21:00 volumio volumio[23748]: info: Loading plugin "system"... Aug 28 05:21:00 volumio volumio[23748]: info: Loading plugin "appearance"... Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "network"... Aug 28 05:21:01 volumio volumio[23748]: info: Refreshing Cached IP Addresses Aug 28 05:21:01 volumio sudo[23772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 05:21:01 volumio sudo[23772]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:01 volumio sudo[23772]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:01 volumio sudo[23774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 05:21:01 volumio sudo[23774]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "services"... Aug 28 05:21:01 volumio sudo[23774]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "alsa_controller"... Aug 28 05:21:01 volumio sudo[23777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 05:21:01 volumio sudo[23777]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:01 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "wizard"... Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "volumio_command_line_client"... Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "upnp"... Aug 28 05:21:01 volumio volumio[23748]: info: [1724815261564] Starting Upmpd Daemon Aug 28 05:21:01 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "my_music"... Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "mpd"... Aug 28 05:21:01 volumio volumio[23748]: info: Creating MPD Configuration file Aug 28 05:21:01 volumio volumio[23748]: info: Loading plugin "upnp_browser"... Aug 28 05:21:01 volumio sudo[23785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:21:01 volumio sudo[23785]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:01 volumio sudo[23785]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:01 volumio sudo[23787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:21:01 volumio sudo[23787]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:01 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:21:02 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:21:02 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:21:02 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:21:02 volumio volumio[23748]: info: Loading plugin "networkfs"... Aug 28 05:21:02 volumio volumio-remote-updater[472]: [2024-08-28 05:21:02] [connect] Successful connection Aug 28 05:21:02 volumio volumio[23748]: info: Starting Udev Watcher for removable devices Aug 28 05:21:02 volumio volumio[23748]: info: Ignoring mount for partition: boot Aug 28 05:21:02 volumio volumio[23748]: info: Ignoring mount for partition: volumio Aug 28 05:21:02 volumio volumio[23748]: info: Ignoring mount for partition: volumio_data Aug 28 05:21:02 volumio volumio[23748]: info: Mounting Device INTENSO Aug 28 05:21:02 volumio sudo[23798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Aug 28 05:21:02 volumio sudo[23798]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:02 volumio sudo[23798]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:02 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:02 volumio volumio[23748]: info: Loading plugin "alarm-clock"... Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "airplay_emulation"... Aug 28 05:21:03 volumio volumio[23748]: info: Starting Shairport Sync Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "last_100"... Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "webradio"... Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "i2s_dacs"... Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "volumiodiscovery"... Aug 28 05:21:03 volumio volumio[23748]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:03 volumio node[23748]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:03 volumio volumio[23748]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:03 volumio node[23748]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:03 volumio volumio[23748]: *** WARNING *** For more information see Aug 28 05:21:03 volumio node[23748]: *** WARNING *** For more information see Aug 28 05:21:03 volumio volumio[23748]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:03 volumio node[23748]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:03 volumio volumio[23748]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:03 volumio node[23748]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:03 volumio volumio[23748]: *** WARNING *** For more information see Aug 28 05:21:03 volumio node[23748]: *** WARNING *** For more information see Aug 28 05:21:03 volumio volumio[23748]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 05:21:03 volumio volumio[23748]: info: Discovery: Started advertising with name: undefined Aug 28 05:21:03 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "outputs"... Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "albumart"... Aug 28 05:21:03 volumio volumio[23748]: info: Plugin example_plugin is not enabled Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "inputs"... Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "updater_comm"... Aug 28 05:21:03 volumio mpd[23792]: Aug 28 05:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:21:03 volumio mpd[23792]: Aug 28 05:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:21:03 volumio mpd[23792]: Aug 28 05:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:21:03 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:21:03 volumio sudo[23787]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:03 volumio volumio[23748]: info: Plugin mpdemulation is not enabled Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "rest_api"... Aug 28 05:21:03 volumio volumio[23748]: info: Loading plugin "websocket"... Aug 28 05:21:03 volumio volumio[23748]: info: Loading i18n strings for locale de Aug 28 05:21:03 volumio volumio[23748]: Updating browse sources language Aug 28 05:21:03 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:03 volumio volumio[23748]: Forking 3 albumart workers Aug 28 05:21:03 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:03 volumio volumio[23748]: info: CoreCommandRouter::initPlayerControls Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: Express server listening on port 3000 Aug 28 05:21:04 volumio volumio[23748]: [Metrics] WebUI: 5s 85.38ms Aug 28 05:21:04 volumio volumio[23748]: info: CoreStateMachine::resetVolumioState Aug 28 05:21:04 volumio volumio[23748]: info: CoreStateMachine::getcurrentVolume Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:21:04 volumio volumio[23748]: info: MPD Permissions set Aug 28 05:21:04 volumio volumio-remote-updater[472]: [2024-08-28 05:21:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724815262 101 Aug 28 05:21:04 volumio volumio[23748]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 28 05:21:04 volumio volumio[23748]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:21:04 volumio volumio[23748]: info: MPD running with PID23792 Aug 28 05:21:04 volumio volumio[23748]: ,establishing connection Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: Cannot read play queue from file Aug 28 05:21:04 volumio volumio[23748]: error: updateQueue error: null Aug 28 05:21:04 volumio volumio[23748]: info: CoreStateMachine::setRepeat false single undefined Aug 28 05:21:04 volumio volumio[23748]: info: CoreStateMachine::pushState Aug 28 05:21:04 volumio volumio[23748]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:04 volumio volumio[23748]: info: CoreStateMachine::setRandom null Aug 28 05:21:04 volumio volumio[23748]: info: CoreStateMachine::pushState Aug 28 05:21:04 volumio volumio[23748]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:04 volumio volumio[23748]: info: Setting Device type: Raspberry PI Aug 28 05:21:04 volumio volumio[23748]: info: Completed loading Core Plugins Aug 28 05:21:04 volumio volumio[23748]: info: Preparing to generate the ALSA configuration file Aug 28 05:21:04 volumio volumio[23748]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:21:04 volumio volumio[23748]: info: CoreStateMachine::pushState Aug 28 05:21:04 volumio volumio[23748]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:04 volumio volumio[23748]: info: CoreStateMachine::updateTrackBlock Aug 28 05:21:04 volumio volumio[23748]: info: CorePlayQueue::getTrackBlock Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:21:04 volumio volumio[23748]: error: updateQueue error: null Aug 28 05:21:04 volumio volumio[23748]: info: Asound.conf file unchanged, so no further update is needed Aug 28 05:21:04 volumio volumio[23748]: info: Output device has changed, restarting MPD Aug 28 05:21:04 volumio sudo[23852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:21:04 volumio volumio[23748]: info: Output device has changed, restarting Shairport Sync Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:04 volumio sudo[23852]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:04 volumio sudo[23852]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:04 volumio sudo[23777]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:04 volumio sudo[23853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:21:04 volumio sudo[23853]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:04 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:21:04 volumio volumio[23748]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:04 volumio volumio[23748]: info: ___________ START PLUGINS ___________ Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:04 volumio volumio[23748]: info: [1724815264794] CoreMusicLibrary::Adding element Media Servers Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:04 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:21:04 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:04 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:04 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:21:04 volumio volumio[23748]: Starting albumart workers Aug 28 05:21:05 volumio volumio[23748]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:05 volumio volumio[23748]: info: [1724815265007] CoreMusicLibrary::Adding element Last_100 Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:05 volumio volumio[23748]: info: [1724815265012] CoreMusicLibrary::Adding element Webradio Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:21:05 volumio volumio[23748]: info: Volumio Calling Home Aug 28 05:21:05 volumio volumio[23748]: Starting albumart workers Aug 28 05:21:05 volumio volumio[23748]: info: Completed starting Core Plugins Aug 28 05:21:05 volumio volumio[23748]: info: ------------------------------------------- Aug 28 05:21:05 volumio volumio[23748]: info: ----- MyVolumio plugins startup ---- Aug 28 05:21:05 volumio volumio[23748]: info: ------------------------------------------- Aug 28 05:21:05 volumio volumio[23748]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 05:21:05 volumio volumio[23748]: Starting albumart workers Aug 28 05:21:05 volumio volumio[23748]: info: MPD Permissions set Aug 28 05:21:05 volumio volumio[23748]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:21:05 volumio volumio[23748]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:05 volumio volumio[23748]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:21:05 volumio volumio[23748]: info: CoreStateMachine::pushState Aug 28 05:21:05 volumio volumio[23748]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:05 volumio volumio[23748]: info: Starting Shairport Sync Aug 28 05:21:05 volumio volumio[23748]: info: Starting Shairport Sync Aug 28 05:21:05 volumio volumio[23748]: info: Starting Shairport Sync Aug 28 05:21:05 volumio sudo[23878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:05 volumio sudo[23878]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:05 volumio sudo[23880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:05 volumio sudo[23880]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:05 volumio sudo[23881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:05 volumio sudo[23881]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:05 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:05 volumio sudo[23878]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:05 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:05 volumio sudo[23881]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:05 volumio volumio[23748]: info: Shairport-Sync Started Aug 28 05:21:05 volumio volumio[23748]: Error adding Membership: Error: addMembership EINVAL Aug 28 05:21:05 volumio sudo[23880]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:05 volumio volumio[23748]: info: CoreCommandRouter::volumioGetState Aug 28 05:21:05 volumio volumio[23748]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:05 volumio volumio[23748]: info: Shairport-Sync Started Aug 28 05:21:05 volumio volumio[23748]: info: Shairport-Sync Started Aug 28 05:21:06 volumio mpd[23870]: Aug 28 05:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:21:06 volumio mpd[23870]: Aug 28 05:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:21:06 volumio mpd[23870]: Aug 28 05:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:21:06 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:21:06 volumio sudo[23853]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:06 volumio volumio[23748]: error: updateQueue error: null Aug 28 05:21:08 volumio volumio[23748]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Aug 28 05:21:08 volumio volumio[23748]: info: Discovery: Started advertising with name: undefined Aug 28 05:21:11 volumio volumio[23748]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:21:11 volumio volumio[23748]: TypeError: Cannot read property 'charAt' of undefined Aug 28 05:21:11 volumio volumio[23748]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 28 05:21:11 volumio volumio[23748]: at listOnTimeout (internal/timers.js:554:17) Aug 28 05:21:11 volumio volumio[23748]: at processTimers (internal/timers.js:497:7) Aug 28 05:21:11 volumio volumio[23748]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:21:12 volumio sudo[23926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 05:20 Aug 28 05:21:12 volumio sudo[23926]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:12 volumio sudo[23926]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:12 volumio volumio-remote-updater[472]: [2024-08-28 05:21:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 28 05:21:12 volumio volumio-remote-updater[472]: [2024-08-28 05:21:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 28 05:21:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 28 05:21:12 volumio systemd[1]: media-INTENSO.mount: Succeeded. Aug 28 05:21:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 28 05:21:12 volumio systemd[1]: Started dynamicswap service. Aug 28 05:21:12 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:21:12 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 05:21:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 240. Aug 28 05:21:12 volumio systemd[1]: Stopped Volumio Backend Module. Aug 28 05:21:12 volumio systemd[1]: Started Volumio Backend Module. Aug 28 05:21:12 volumio systemd[1]: Started dynamicswap service. Aug 28 05:21:12 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:21:14 volumio volumio[23940]: info: ------------------------------------------- Aug 28 05:21:14 volumio volumio[23940]: info: ----- Volumio3 ---- Aug 28 05:21:14 volumio volumio[23940]: info: ------------------------------------------- Aug 28 05:21:14 volumio volumio[23940]: info: ----- System startup ---- Aug 28 05:21:14 volumio volumio[23940]: info: ------------------------------------------- Aug 28 05:21:15 volumio volumio[23940]: info: MYVOLUMIO Environment detected Aug 28 05:21:15 volumio volumio[23940]: info: Plugin folders cleanup Aug 28 05:21:15 volumio volumio[23940]: info: Scanning into folder /volumio/app/plugins/ Aug 28 05:21:15 volumio volumio[23940]: info: Scanning category audio_interface Aug 28 05:21:15 volumio volumio[23940]: info: Scanning category miscellanea Aug 28 05:21:15 volumio volumio[23940]: info: Scanning category music_service Aug 28 05:21:15 volumio volumio[23940]: info: Scanning category plugins.json Aug 28 05:21:15 volumio volumio[23940]: info: Scanning category system_controller Aug 28 05:21:15 volumio volumio[23940]: info: Scanning category user_interface Aug 28 05:21:15 volumio volumio[23940]: info: Scanning into folder /data/plugins/ Aug 28 05:21:15 volumio volumio[23940]: info: Plugin folders cleanup completed Aug 28 05:21:15 volumio volumio[23940]: info: ------------------------------------------- Aug 28 05:21:15 volumio volumio[23940]: info: ----- Core plugins startup ---- Aug 28 05:21:15 volumio volumio[23940]: info: ------------------------------------------- Aug 28 05:21:15 volumio volumio[23940]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 05:21:15 volumio volumio[23940]: info: Adding plugin upnp to MyMusic Plugins Aug 28 05:21:15 volumio volumio[23940]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 05:21:15 volumio volumio[23940]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 05:21:15 volumio volumio[23940]: info: Loading plugins from folder /data/plugins/ Aug 28 05:21:15 volumio volumio[23940]: info: Loading plugin "system"... Aug 28 05:21:15 volumio volumio[23940]: info: Loading plugin "appearance"... Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "network"... Aug 28 05:21:16 volumio volumio[23940]: info: Refreshing Cached IP Addresses Aug 28 05:21:16 volumio sudo[23964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 05:21:16 volumio sudo[23964]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:16 volumio sudo[23964]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:16 volumio sudo[23966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 05:21:16 volumio sudo[23966]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "services"... Aug 28 05:21:16 volumio sudo[23966]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "alsa_controller"... Aug 28 05:21:16 volumio sudo[23969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 05:21:16 volumio sudo[23969]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:16 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "wizard"... Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "volumio_command_line_client"... Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "upnp"... Aug 28 05:21:16 volumio volumio[23940]: info: [1724815276575] Starting Upmpd Daemon Aug 28 05:21:16 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "my_music"... Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "mpd"... Aug 28 05:21:16 volumio volumio[23940]: info: Creating MPD Configuration file Aug 28 05:21:16 volumio volumio[23940]: info: Loading plugin "upnp_browser"... Aug 28 05:21:16 volumio sudo[23977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:21:16 volumio sudo[23977]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:16 volumio sudo[23977]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:16 volumio sudo[23979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:21:16 volumio sudo[23979]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:17 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:21:17 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:21:17 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:21:17 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:21:17 volumio volumio[23940]: info: Loading plugin "networkfs"... Aug 28 05:21:17 volumio volumio-remote-updater[472]: [2024-08-28 05:21:17] [connect] Successful connection Aug 28 05:21:17 volumio volumio[23940]: info: Starting Udev Watcher for removable devices Aug 28 05:21:17 volumio volumio[23940]: info: Ignoring mount for partition: boot Aug 28 05:21:17 volumio volumio[23940]: info: Ignoring mount for partition: volumio Aug 28 05:21:17 volumio volumio[23940]: info: Ignoring mount for partition: volumio_data Aug 28 05:21:17 volumio volumio[23940]: info: Mounting Device INTENSO Aug 28 05:21:17 volumio sudo[23990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Aug 28 05:21:17 volumio sudo[23990]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:18 volumio sudo[23990]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "alarm-clock"... Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "airplay_emulation"... Aug 28 05:21:18 volumio volumio[23940]: info: Starting Shairport Sync Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "last_100"... Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "webradio"... Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "i2s_dacs"... Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "volumiodiscovery"... Aug 28 05:21:18 volumio volumio[23940]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:18 volumio volumio[23940]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:18 volumio volumio[23940]: *** WARNING *** For more information see Aug 28 05:21:18 volumio node[23940]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:18 volumio volumio[23940]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:18 volumio volumio[23940]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:18 volumio volumio[23940]: *** WARNING *** For more information see Aug 28 05:21:18 volumio node[23940]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:18 volumio node[23940]: *** WARNING *** For more information see Aug 28 05:21:18 volumio node[23940]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:18 volumio node[23940]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:18 volumio node[23940]: *** WARNING *** For more information see Aug 28 05:21:18 volumio volumio[23940]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 05:21:18 volumio volumio[23940]: info: Discovery: Started advertising with name: undefined Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "outputs"... Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "albumart"... Aug 28 05:21:18 volumio volumio[23940]: info: Plugin example_plugin is not enabled Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "inputs"... Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "updater_comm"... Aug 28 05:21:18 volumio mpd[23984]: Aug 28 05:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:21:18 volumio mpd[23984]: Aug 28 05:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:21:18 volumio mpd[23984]: Aug 28 05:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:21:18 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:21:18 volumio sudo[23979]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:18 volumio volumio[23940]: info: Plugin mpdemulation is not enabled Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "rest_api"... Aug 28 05:21:18 volumio volumio[23940]: info: Loading plugin "websocket"... Aug 28 05:21:18 volumio volumio[23940]: info: Loading i18n strings for locale de Aug 28 05:21:18 volumio volumio[23940]: Updating browse sources language Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:18 volumio volumio[23940]: Forking 3 albumart workers Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::initPlayerControls Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:18 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: Express server listening on port 3000 Aug 28 05:21:19 volumio volumio[23940]: [Metrics] WebUI: 5s 113.96ms Aug 28 05:21:19 volumio volumio[23940]: info: CoreStateMachine::resetVolumioState Aug 28 05:21:19 volumio volumio[23940]: info: CoreStateMachine::getcurrentVolume Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:21:19 volumio volumio[23940]: info: MPD Permissions set Aug 28 05:21:19 volumio volumio-remote-updater[472]: [2024-08-28 05:21:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724815277 101 Aug 28 05:21:19 volumio volumio[23940]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 28 05:21:19 volumio volumio[23940]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:21:19 volumio volumio[23940]: info: MPD running with PID23984 Aug 28 05:21:19 volumio volumio[23940]: ,establishing connection Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: Cannot read play queue from file Aug 28 05:21:19 volumio volumio[23940]: error: updateQueue error: null Aug 28 05:21:19 volumio volumio[23940]: info: CoreStateMachine::setRepeat false single undefined Aug 28 05:21:19 volumio volumio[23940]: info: CoreStateMachine::pushState Aug 28 05:21:19 volumio volumio[23940]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:19 volumio volumio[23940]: info: CoreStateMachine::setRandom null Aug 28 05:21:19 volumio volumio[23940]: info: CoreStateMachine::pushState Aug 28 05:21:19 volumio volumio[23940]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:19 volumio volumio[23940]: info: Setting Device type: Raspberry PI Aug 28 05:21:19 volumio volumio[23940]: info: Completed loading Core Plugins Aug 28 05:21:19 volumio volumio[23940]: info: Preparing to generate the ALSA configuration file Aug 28 05:21:19 volumio volumio[23940]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:21:19 volumio volumio[23940]: info: CoreStateMachine::pushState Aug 28 05:21:19 volumio volumio[23940]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:19 volumio volumio[23940]: info: CoreStateMachine::updateTrackBlock Aug 28 05:21:19 volumio volumio[23940]: info: CorePlayQueue::getTrackBlock Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:21:19 volumio volumio[23940]: error: updateQueue error: null Aug 28 05:21:19 volumio volumio[23940]: info: Asound.conf file unchanged, so no further update is needed Aug 28 05:21:19 volumio volumio[23940]: info: Output device has changed, restarting MPD Aug 28 05:21:19 volumio volumio[23940]: info: Output device has changed, restarting Shairport Sync Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:19 volumio sudo[24044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:21:19 volumio sudo[24044]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:19 volumio sudo[23969]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:19 volumio sudo[24044]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:19 volumio sudo[24046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:21:19 volumio sudo[24046]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:19 volumio volumio[23940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:19 volumio volumio[23940]: info: ___________ START PLUGINS ___________ Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:19 volumio volumio[23940]: info: [1724815279786] CoreMusicLibrary::Adding element Media Servers Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:19 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:19 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:21:19 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:21:19 volumio volumio[23940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:19 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:19 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:21:20 volumio volumio[23940]: Starting albumart workers Aug 28 05:21:20 volumio volumio[23940]: Starting albumart workers Aug 28 05:21:20 volumio volumio[23940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:20 volumio volumio[23940]: info: [1724815280049] CoreMusicLibrary::Adding element Last_100 Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:20 volumio volumio[23940]: info: [1724815280053] CoreMusicLibrary::Adding element Webradio Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:21:20 volumio volumio[23940]: info: Volumio Calling Home Aug 28 05:21:20 volumio volumio[23940]: Starting albumart workers Aug 28 05:21:20 volumio volumio[23940]: info: Completed starting Core Plugins Aug 28 05:21:20 volumio volumio[23940]: info: ------------------------------------------- Aug 28 05:21:20 volumio volumio[23940]: info: ----- MyVolumio plugins startup ---- Aug 28 05:21:20 volumio volumio[23940]: info: ------------------------------------------- Aug 28 05:21:20 volumio volumio[23940]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 05:21:20 volumio volumio[23940]: info: MPD Permissions set Aug 28 05:21:20 volumio volumio[23940]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:21:20 volumio volumio[23940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:20 volumio volumio[23940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:21 volumio volumio[23940]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:21:21 volumio volumio[23940]: info: CoreStateMachine::pushState Aug 28 05:21:21 volumio volumio[23940]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:21 volumio volumio[23940]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:21 volumio volumio[23940]: info: Starting Shairport Sync Aug 28 05:21:21 volumio volumio[23940]: info: Starting Shairport Sync Aug 28 05:21:21 volumio volumio[23940]: info: Starting Shairport Sync Aug 28 05:21:21 volumio sudo[24088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:21 volumio sudo[24088]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:21 volumio sudo[24089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:21 volumio sudo[24089]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:21 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:21 volumio sudo[24088]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:21 volumio sudo[24092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:21 volumio sudo[24092]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:21 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 28 05:21:21 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:21 volumio sudo[24089]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:21 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 28 05:21:21 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:21 volumio volumio[23940]: info: Shairport-Sync Started Aug 28 05:21:21 volumio volumio[23940]: Error adding Membership: Error: addMembership EINVAL Aug 28 05:21:21 volumio volumio[23940]: info: Shairport-Sync Started Aug 28 05:21:21 volumio volumio[23940]: info: CoreCommandRouter::volumioGetState Aug 28 05:21:21 volumio volumio[23940]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:21 volumio sudo[24092]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:21 volumio volumio[23940]: info: Shairport-Sync Started Aug 28 05:21:21 volumio mpd[24062]: Aug 28 05:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:21:21 volumio mpd[24062]: Aug 28 05:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:21:21 volumio mpd[24062]: Aug 28 05:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:21:21 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:21:21 volumio sudo[24046]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:21 volumio volumio[23940]: error: updateQueue error: null Aug 28 05:21:23 volumio volumio[23940]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Aug 28 05:21:23 volumio volumio[23940]: info: Discovery: Started advertising with name: undefined Aug 28 05:21:26 volumio volumio[23940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:21:26 volumio volumio[23940]: TypeError: Cannot read property 'charAt' of undefined Aug 28 05:21:26 volumio volumio[23940]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 28 05:21:26 volumio volumio[23940]: at listOnTimeout (internal/timers.js:554:17) Aug 28 05:21:26 volumio volumio[23940]: at processTimers (internal/timers.js:497:7) Aug 28 05:21:26 volumio volumio[23940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:21:27 volumio sudo[24120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 05:20 Aug 28 05:21:27 volumio sudo[24120]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:27 volumio sudo[24120]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:27 volumio volumio-remote-updater[472]: [2024-08-28 05:21:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 28 05:21:27 volumio volumio-remote-updater[472]: [2024-08-28 05:21:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 28 05:21:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 28 05:21:27 volumio systemd[1]: media-INTENSO.mount: Succeeded. Aug 28 05:21:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 28 05:21:27 volumio systemd[1]: Started dynamicswap service. Aug 28 05:21:27 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:21:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 05:21:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 241. Aug 28 05:21:27 volumio systemd[1]: Started dynamicswap service. Aug 28 05:21:27 volumio systemd[1]: Stopped Volumio Backend Module. Aug 28 05:21:27 volumio systemd[1]: Started Volumio Backend Module. Aug 28 05:21:27 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:21:29 volumio volumio[24135]: info: ------------------------------------------- Aug 28 05:21:29 volumio volumio[24135]: info: ----- Volumio3 ---- Aug 28 05:21:29 volumio volumio[24135]: info: ------------------------------------------- Aug 28 05:21:29 volumio volumio[24135]: info: ----- System startup ---- Aug 28 05:21:29 volumio volumio[24135]: info: ------------------------------------------- Aug 28 05:21:30 volumio volumio[24135]: info: MYVOLUMIO Environment detected Aug 28 05:21:30 volumio volumio[24135]: info: Plugin folders cleanup Aug 28 05:21:30 volumio volumio[24135]: info: Scanning into folder /volumio/app/plugins/ Aug 28 05:21:30 volumio volumio[24135]: info: Scanning category audio_interface Aug 28 05:21:30 volumio volumio[24135]: info: Scanning category miscellanea Aug 28 05:21:30 volumio volumio[24135]: info: Scanning category music_service Aug 28 05:21:30 volumio volumio[24135]: info: Scanning category plugins.json Aug 28 05:21:30 volumio volumio[24135]: info: Scanning category system_controller Aug 28 05:21:30 volumio volumio[24135]: info: Scanning category user_interface Aug 28 05:21:30 volumio volumio[24135]: info: Scanning into folder /data/plugins/ Aug 28 05:21:30 volumio volumio[24135]: info: Plugin folders cleanup completed Aug 28 05:21:30 volumio volumio[24135]: info: ------------------------------------------- Aug 28 05:21:30 volumio volumio[24135]: info: ----- Core plugins startup ---- Aug 28 05:21:30 volumio volumio[24135]: info: ------------------------------------------- Aug 28 05:21:30 volumio volumio[24135]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 05:21:30 volumio volumio[24135]: info: Adding plugin upnp to MyMusic Plugins Aug 28 05:21:30 volumio volumio[24135]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 05:21:30 volumio volumio[24135]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 05:21:30 volumio volumio[24135]: info: Loading plugins from folder /data/plugins/ Aug 28 05:21:30 volumio volumio[24135]: info: Loading plugin "system"... Aug 28 05:21:30 volumio volumio[24135]: info: Loading plugin "appearance"... Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "network"... Aug 28 05:21:31 volumio volumio[24135]: info: Refreshing Cached IP Addresses Aug 28 05:21:31 volumio sudo[24158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 05:21:31 volumio sudo[24158]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:31 volumio sudo[24158]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:31 volumio sudo[24160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 05:21:31 volumio sudo[24160]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "services"... Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "alsa_controller"... Aug 28 05:21:31 volumio sudo[24160]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:31 volumio sudo[24163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 05:21:31 volumio sudo[24163]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:31 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "wizard"... Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "volumio_command_line_client"... Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "upnp"... Aug 28 05:21:31 volumio volumio[24135]: info: [1724815291527] Starting Upmpd Daemon Aug 28 05:21:31 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "my_music"... Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "mpd"... Aug 28 05:21:31 volumio volumio[24135]: info: Creating MPD Configuration file Aug 28 05:21:31 volumio volumio[24135]: info: Loading plugin "upnp_browser"... Aug 28 05:21:31 volumio sudo[24171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:21:31 volumio sudo[24171]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:31 volumio sudo[24171]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:31 volumio sudo[24173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:21:31 volumio sudo[24173]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:31 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:21:31 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:21:31 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:21:32 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:21:32 volumio volumio[24135]: info: Loading plugin "networkfs"... Aug 28 05:21:32 volumio volumio-remote-updater[472]: [2024-08-28 05:21:32] [connect] Successful connection Aug 28 05:21:32 volumio volumio[24135]: info: Starting Udev Watcher for removable devices Aug 28 05:21:32 volumio volumio[24135]: info: Ignoring mount for partition: boot Aug 28 05:21:32 volumio volumio[24135]: info: Ignoring mount for partition: volumio Aug 28 05:21:32 volumio volumio[24135]: info: Ignoring mount for partition: volumio_data Aug 28 05:21:32 volumio volumio[24135]: info: Mounting Device INTENSO Aug 28 05:21:32 volumio sudo[24184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Aug 28 05:21:32 volumio sudo[24184]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:32 volumio sudo[24184]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:32 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:32 volumio volumio[24135]: info: Loading plugin "alarm-clock"... Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "airplay_emulation"... Aug 28 05:21:33 volumio volumio[24135]: info: Starting Shairport Sync Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "last_100"... Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "webradio"... Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "i2s_dacs"... Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "volumiodiscovery"... Aug 28 05:21:33 volumio volumio[24135]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:33 volumio volumio[24135]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:33 volumio volumio[24135]: *** WARNING *** For more information see Aug 28 05:21:33 volumio node[24135]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:33 volumio volumio[24135]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:33 volumio volumio[24135]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:33 volumio volumio[24135]: *** WARNING *** For more information see Aug 28 05:21:33 volumio node[24135]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:33 volumio node[24135]: *** WARNING *** For more information see Aug 28 05:21:33 volumio node[24135]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:33 volumio node[24135]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:33 volumio node[24135]: *** WARNING *** For more information see Aug 28 05:21:33 volumio volumio[24135]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 05:21:33 volumio volumio[24135]: info: Discovery: Started advertising with name: undefined Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "outputs"... Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "albumart"... Aug 28 05:21:33 volumio volumio[24135]: info: Plugin example_plugin is not enabled Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "inputs"... Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "updater_comm"... Aug 28 05:21:33 volumio volumio[24135]: info: Plugin mpdemulation is not enabled Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "rest_api"... Aug 28 05:21:33 volumio mpd[24178]: Aug 28 05:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:21:33 volumio volumio[24135]: info: Loading plugin "websocket"... Aug 28 05:21:33 volumio volumio[24135]: info: Loading i18n strings for locale de Aug 28 05:21:33 volumio volumio[24135]: Updating browse sources language Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:33 volumio mpd[24178]: Aug 28 05:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:21:33 volumio mpd[24178]: Aug 28 05:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:21:33 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:21:33 volumio sudo[24173]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:33 volumio volumio[24135]: Forking 3 albumart workers Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::initPlayerControls Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:33 volumio volumio[24135]: Express server listening on port 3000 Aug 28 05:21:33 volumio volumio[24135]: [Metrics] WebUI: 5s 2.42ms Aug 28 05:21:33 volumio volumio[24135]: info: CoreStateMachine::resetVolumioState Aug 28 05:21:33 volumio volumio[24135]: info: CoreStateMachine::getcurrentVolume Aug 28 05:21:33 volumio volumio[24135]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:21:34 volumio volumio[24135]: info: MPD Permissions set Aug 28 05:21:34 volumio volumio-remote-updater[472]: [2024-08-28 05:21:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724815292 101 Aug 28 05:21:34 volumio volumio[24135]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 28 05:21:34 volumio volumio[24135]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:21:34 volumio volumio[24135]: info: MPD running with PID24178 Aug 28 05:21:34 volumio volumio[24135]: ,establishing connection Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: Cannot read play queue from file Aug 28 05:21:34 volumio volumio[24135]: error: updateQueue error: null Aug 28 05:21:34 volumio volumio[24135]: info: CoreStateMachine::setRepeat false single undefined Aug 28 05:21:34 volumio volumio[24135]: info: CoreStateMachine::pushState Aug 28 05:21:34 volumio volumio[24135]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:34 volumio volumio[24135]: info: CoreStateMachine::setRandom null Aug 28 05:21:34 volumio volumio[24135]: info: CoreStateMachine::pushState Aug 28 05:21:34 volumio volumio[24135]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:34 volumio volumio[24135]: info: Setting Device type: Raspberry PI Aug 28 05:21:34 volumio volumio[24135]: info: Completed loading Core Plugins Aug 28 05:21:34 volumio volumio[24135]: info: Preparing to generate the ALSA configuration file Aug 28 05:21:34 volumio volumio[24135]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:21:34 volumio volumio[24135]: info: CoreStateMachine::pushState Aug 28 05:21:34 volumio volumio[24135]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:34 volumio volumio[24135]: info: CoreStateMachine::updateTrackBlock Aug 28 05:21:34 volumio volumio[24135]: info: CorePlayQueue::getTrackBlock Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:21:34 volumio volumio[24135]: error: updateQueue error: null Aug 28 05:21:34 volumio volumio[24135]: info: Asound.conf file unchanged, so no further update is needed Aug 28 05:21:34 volumio volumio[24135]: info: Output device has changed, restarting MPD Aug 28 05:21:34 volumio volumio[24135]: info: Output device has changed, restarting Shairport Sync Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:34 volumio sudo[24238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:21:34 volumio sudo[24238]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:34 volumio sudo[24238]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:34 volumio sudo[24239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:21:34 volumio sudo[24239]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:34 volumio volumio[24135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:34 volumio volumio[24135]: info: ___________ START PLUGINS ___________ Aug 28 05:21:34 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:34 volumio sudo[24163]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:34 volumio volumio[24135]: info: [1724815294678] CoreMusicLibrary::Adding element Media Servers Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:34 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:21:34 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:34 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:21:34 volumio volumio[24135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:34 volumio volumio[24135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:34 volumio volumio[24135]: info: [1724815294980] CoreMusicLibrary::Adding element Last_100 Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:34 volumio volumio[24135]: info: [1724815294986] CoreMusicLibrary::Adding element Webradio Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:34 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:21:35 volumio volumio[24135]: info: Volumio Calling Home Aug 28 05:21:35 volumio volumio[24135]: Starting albumart workers Aug 28 05:21:35 volumio volumio[24135]: Starting albumart workers Aug 28 05:21:35 volumio volumio[24135]: Starting albumart workers Aug 28 05:21:35 volumio volumio[24135]: info: Completed starting Core Plugins Aug 28 05:21:35 volumio volumio[24135]: info: ------------------------------------------- Aug 28 05:21:35 volumio volumio[24135]: info: ----- MyVolumio plugins startup ---- Aug 28 05:21:35 volumio volumio[24135]: info: ------------------------------------------- Aug 28 05:21:35 volumio volumio[24135]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 05:21:35 volumio volumio[24135]: info: MPD Permissions set Aug 28 05:21:35 volumio volumio[24135]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:21:35 volumio volumio[24135]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:35 volumio volumio[24135]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:21:35 volumio volumio[24135]: info: CoreStateMachine::pushState Aug 28 05:21:35 volumio volumio[24135]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:35 volumio volumio[24135]: info: Starting Shairport Sync Aug 28 05:21:35 volumio volumio[24135]: info: Starting Shairport Sync Aug 28 05:21:35 volumio volumio[24135]: info: Starting Shairport Sync Aug 28 05:21:35 volumio sudo[24264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:35 volumio sudo[24264]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:35 volumio sudo[24266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:35 volumio sudo[24266]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:35 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:35 volumio volumio[24135]: info: CoreCommandRouter::volumioGetState Aug 28 05:21:35 volumio volumio[24135]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:35 volumio sudo[24267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:35 volumio sudo[24267]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:35 volumio sudo[24266]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:35 volumio sudo[24264]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:35 volumio volumio[24135]: info: Shairport-Sync Started Aug 28 05:21:35 volumio volumio[24135]: Error adding Membership: Error: addMembership EINVAL Aug 28 05:21:35 volumio volumio[24135]: info: Shairport-Sync Started Aug 28 05:21:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:35 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 28 05:21:35 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:35 volumio sudo[24267]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:35 volumio volumio[24135]: info: Shairport-Sync Started Aug 28 05:21:36 volumio mpd[24255]: Aug 28 05:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:21:36 volumio mpd[24255]: Aug 28 05:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:21:36 volumio mpd[24255]: Aug 28 05:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:21:36 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:21:36 volumio sudo[24239]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:36 volumio volumio[24135]: error: updateQueue error: null Aug 28 05:21:38 volumio volumio[24135]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Aug 28 05:21:38 volumio volumio[24135]: info: Discovery: Started advertising with name: undefined Aug 28 05:21:41 volumio volumio[24135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:21:41 volumio volumio[24135]: TypeError: Cannot read property 'charAt' of undefined Aug 28 05:21:41 volumio volumio[24135]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 28 05:21:41 volumio volumio[24135]: at listOnTimeout (internal/timers.js:554:17) Aug 28 05:21:41 volumio volumio[24135]: at processTimers (internal/timers.js:497:7) Aug 28 05:21:41 volumio volumio[24135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:21:42 volumio sudo[24311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 05:20 Aug 28 05:21:42 volumio sudo[24311]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:42 volumio sudo[24311]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:42 volumio volumio-remote-updater[472]: [2024-08-28 05:21:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 28 05:21:42 volumio volumio-remote-updater[472]: [2024-08-28 05:21:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 28 05:21:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 28 05:21:42 volumio systemd[1]: media-INTENSO.mount: Succeeded. Aug 28 05:21:42 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 28 05:21:42 volumio systemd[1]: Started dynamicswap service. Aug 28 05:21:42 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:21:42 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 05:21:42 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 242. Aug 28 05:21:42 volumio systemd[1]: Started dynamicswap service. Aug 28 05:21:42 volumio systemd[1]: Stopped Volumio Backend Module. Aug 28 05:21:42 volumio systemd[1]: Started Volumio Backend Module. Aug 28 05:21:42 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:21:44 volumio volumio[24326]: info: ------------------------------------------- Aug 28 05:21:44 volumio volumio[24326]: info: ----- Volumio3 ---- Aug 28 05:21:44 volumio volumio[24326]: info: ------------------------------------------- Aug 28 05:21:44 volumio volumio[24326]: info: ----- System startup ---- Aug 28 05:21:44 volumio volumio[24326]: info: ------------------------------------------- Aug 28 05:21:45 volumio volumio[24326]: info: MYVOLUMIO Environment detected Aug 28 05:21:45 volumio volumio[24326]: info: Plugin folders cleanup Aug 28 05:21:45 volumio volumio[24326]: info: Scanning into folder /volumio/app/plugins/ Aug 28 05:21:45 volumio volumio[24326]: info: Scanning category audio_interface Aug 28 05:21:45 volumio volumio[24326]: info: Scanning category miscellanea Aug 28 05:21:45 volumio volumio[24326]: info: Scanning category music_service Aug 28 05:21:45 volumio volumio[24326]: info: Scanning category plugins.json Aug 28 05:21:45 volumio volumio[24326]: info: Scanning category system_controller Aug 28 05:21:45 volumio volumio[24326]: info: Scanning category user_interface Aug 28 05:21:45 volumio volumio[24326]: info: Scanning into folder /data/plugins/ Aug 28 05:21:45 volumio volumio[24326]: info: Plugin folders cleanup completed Aug 28 05:21:45 volumio volumio[24326]: info: ------------------------------------------- Aug 28 05:21:45 volumio volumio[24326]: info: ----- Core plugins startup ---- Aug 28 05:21:45 volumio volumio[24326]: info: ------------------------------------------- Aug 28 05:21:45 volumio volumio[24326]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 05:21:45 volumio volumio[24326]: info: Adding plugin upnp to MyMusic Plugins Aug 28 05:21:45 volumio volumio[24326]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 05:21:45 volumio volumio[24326]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 05:21:45 volumio volumio[24326]: info: Loading plugins from folder /data/plugins/ Aug 28 05:21:45 volumio volumio[24326]: info: Loading plugin "system"... Aug 28 05:21:45 volumio volumio[24326]: info: Loading plugin "appearance"... Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "network"... Aug 28 05:21:46 volumio volumio[24326]: info: Refreshing Cached IP Addresses Aug 28 05:21:46 volumio sudo[24349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 05:21:46 volumio sudo[24349]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:46 volumio sudo[24349]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "services"... Aug 28 05:21:46 volumio sudo[24351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 05:21:46 volumio sudo[24351]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "alsa_controller"... Aug 28 05:21:46 volumio sudo[24351]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:46 volumio sudo[24354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 05:21:46 volumio sudo[24354]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:46 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "wizard"... Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "volumio_command_line_client"... Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "upnp"... Aug 28 05:21:46 volumio volumio[24326]: info: [1724815306476] Starting Upmpd Daemon Aug 28 05:21:46 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "my_music"... Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "mpd"... Aug 28 05:21:46 volumio volumio[24326]: info: Creating MPD Configuration file Aug 28 05:21:46 volumio volumio[24326]: info: Loading plugin "upnp_browser"... Aug 28 05:21:46 volumio sudo[24362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:21:46 volumio sudo[24362]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:46 volumio sudo[24362]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:46 volumio sudo[24364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:21:46 volumio sudo[24364]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:46 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:21:46 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:21:46 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:21:46 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:21:47 volumio volumio[24326]: info: Loading plugin "networkfs"... Aug 28 05:21:47 volumio volumio-remote-updater[472]: [2024-08-28 05:21:47] [connect] Successful connection Aug 28 05:21:47 volumio volumio[24326]: info: Starting Udev Watcher for removable devices Aug 28 05:21:47 volumio volumio[24326]: info: Ignoring mount for partition: boot Aug 28 05:21:47 volumio volumio[24326]: info: Ignoring mount for partition: volumio Aug 28 05:21:47 volumio volumio[24326]: info: Ignoring mount for partition: volumio_data Aug 28 05:21:47 volumio volumio[24326]: info: Mounting Device INTENSO Aug 28 05:21:47 volumio sudo[24375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Aug 28 05:21:47 volumio sudo[24375]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:47 volumio sudo[24375]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:47 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:47 volumio volumio[24326]: info: Loading plugin "alarm-clock"... Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "airplay_emulation"... Aug 28 05:21:48 volumio volumio[24326]: info: Starting Shairport Sync Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "last_100"... Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "webradio"... Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "i2s_dacs"... Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "volumiodiscovery"... Aug 28 05:21:48 volumio volumio[24326]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:48 volumio volumio[24326]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:48 volumio volumio[24326]: *** WARNING *** For more information see Aug 28 05:21:48 volumio node[24326]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:48 volumio volumio[24326]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:48 volumio volumio[24326]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:48 volumio volumio[24326]: *** WARNING *** For more information see Aug 28 05:21:48 volumio node[24326]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:48 volumio node[24326]: *** WARNING *** For more information see Aug 28 05:21:48 volumio node[24326]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:21:48 volumio node[24326]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:21:48 volumio node[24326]: *** WARNING *** For more information see Aug 28 05:21:48 volumio volumio[24326]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 05:21:48 volumio volumio[24326]: info: Discovery: Started advertising with name: undefined Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "outputs"... Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "albumart"... Aug 28 05:21:48 volumio volumio[24326]: info: Plugin example_plugin is not enabled Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "inputs"... Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "updater_comm"... Aug 28 05:21:48 volumio mpd[24369]: Aug 28 05:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:21:48 volumio mpd[24369]: Aug 28 05:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:21:48 volumio mpd[24369]: Aug 28 05:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:21:48 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:21:48 volumio sudo[24364]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:48 volumio volumio[24326]: info: Plugin mpdemulation is not enabled Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "rest_api"... Aug 28 05:21:48 volumio volumio[24326]: info: Loading plugin "websocket"... Aug 28 05:21:48 volumio volumio[24326]: info: Loading i18n strings for locale de Aug 28 05:21:48 volumio volumio[24326]: Updating browse sources language Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:48 volumio volumio[24326]: Forking 3 albumart workers Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::initPlayerControls Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:48 volumio volumio[24326]: Express server listening on port 3000 Aug 28 05:21:48 volumio volumio[24326]: [Metrics] WebUI: 5s 62.50ms Aug 28 05:21:48 volumio volumio[24326]: info: CoreStateMachine::resetVolumioState Aug 28 05:21:48 volumio volumio[24326]: info: CoreStateMachine::getcurrentVolume Aug 28 05:21:48 volumio volumio[24326]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:21:49 volumio volumio[24326]: info: MPD Permissions set Aug 28 05:21:49 volumio volumio-remote-updater[472]: [2024-08-28 05:21:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724815307 101 Aug 28 05:21:49 volumio volumio[24326]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 28 05:21:49 volumio volumio[24326]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:21:49 volumio volumio[24326]: info: MPD running with PID24369 Aug 28 05:21:49 volumio volumio[24326]: ,establishing connection Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: Cannot read play queue from file Aug 28 05:21:49 volumio volumio[24326]: error: updateQueue error: null Aug 28 05:21:49 volumio volumio[24326]: info: Setting Device type: Raspberry PI Aug 28 05:21:49 volumio volumio[24326]: info: CoreStateMachine::setRepeat false single undefined Aug 28 05:21:49 volumio volumio[24326]: info: CoreStateMachine::pushState Aug 28 05:21:49 volumio volumio[24326]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:49 volumio volumio[24326]: info: CoreStateMachine::setRandom null Aug 28 05:21:49 volumio volumio[24326]: info: CoreStateMachine::pushState Aug 28 05:21:49 volumio volumio[24326]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:49 volumio volumio[24326]: info: Completed loading Core Plugins Aug 28 05:21:49 volumio volumio[24326]: info: Preparing to generate the ALSA configuration file Aug 28 05:21:49 volumio volumio[24326]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:21:49 volumio volumio[24326]: info: CoreStateMachine::pushState Aug 28 05:21:49 volumio volumio[24326]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:49 volumio volumio[24326]: info: CoreStateMachine::updateTrackBlock Aug 28 05:21:49 volumio volumio[24326]: info: CorePlayQueue::getTrackBlock Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:21:49 volumio volumio[24326]: error: updateQueue error: null Aug 28 05:21:49 volumio volumio[24326]: info: Asound.conf file unchanged, so no further update is needed Aug 28 05:21:49 volumio volumio[24326]: info: Output device has changed, restarting MPD Aug 28 05:21:49 volumio sudo[24429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:21:49 volumio volumio[24326]: info: Output device has changed, restarting Shairport Sync Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:49 volumio sudo[24429]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:49 volumio sudo[24429]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:49 volumio sudo[24354]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:49 volumio sudo[24431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:21:49 volumio sudo[24431]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:49 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:21:49 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:21:49 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:21:49 volumio volumio[24326]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:49 volumio volumio[24326]: info: ___________ START PLUGINS ___________ Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:49 volumio volumio[24326]: info: [1724815309773] CoreMusicLibrary::Adding element Media Servers Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:49 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:49 volumio volumio[24326]: Starting albumart workers Aug 28 05:21:49 volumio volumio[24326]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:49 volumio volumio[24326]: info: [1724815309992] CoreMusicLibrary::Adding element Last_100 Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:49 volumio volumio[24326]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:21:49 volumio volumio[24326]: info: [1724815309999] CoreMusicLibrary::Adding element Webradio Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:21:50 volumio volumio[24326]: Starting albumart workers Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:21:50 volumio volumio[24326]: info: Volumio Calling Home Aug 28 05:21:50 volumio volumio[24326]: Starting albumart workers Aug 28 05:21:50 volumio volumio[24326]: info: Completed starting Core Plugins Aug 28 05:21:50 volumio volumio[24326]: info: ------------------------------------------- Aug 28 05:21:50 volumio volumio[24326]: info: ----- MyVolumio plugins startup ---- Aug 28 05:21:50 volumio volumio[24326]: info: ------------------------------------------- Aug 28 05:21:50 volumio volumio[24326]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 05:21:50 volumio volumio[24326]: info: MPD Permissions set Aug 28 05:21:50 volumio volumio[24326]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:21:50 volumio volumio[24326]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:21:50 volumio volumio[24326]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:21:50 volumio volumio[24326]: info: CoreStateMachine::pushState Aug 28 05:21:50 volumio volumio[24326]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:50 volumio volumio[24326]: info: CoreCommandRouter::volumioPushState Aug 28 05:21:50 volumio volumio[24326]: info: Starting Shairport Sync Aug 28 05:21:50 volumio volumio[24326]: info: Starting Shairport Sync Aug 28 05:21:50 volumio volumio[24326]: info: Starting Shairport Sync Aug 28 05:21:50 volumio sudo[24455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:50 volumio sudo[24455]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:50 volumio sudo[24458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:50 volumio sudo[24458]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:50 volumio sudo[24457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:21:50 volumio sudo[24457]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:50 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:50 volumio sudo[24455]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:21:50 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 28 05:21:50 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:21:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:21:50 volumio sudo[24458]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:50 volumio volumio[24326]: info: Shairport-Sync Started Aug 28 05:21:51 volumio sudo[24457]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:51 volumio volumio[24326]: Error adding Membership: Error: addMembership EINVAL Aug 28 05:21:51 volumio volumio[24326]: info: CoreCommandRouter::volumioGetState Aug 28 05:21:51 volumio volumio[24326]: info: CorePlayQueue::getTrack 0 Aug 28 05:21:51 volumio volumio[24326]: info: Shairport-Sync Started Aug 28 05:21:51 volumio volumio[24326]: info: Shairport-Sync Started Aug 28 05:21:51 volumio mpd[24446]: Aug 28 05:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:21:51 volumio mpd[24446]: Aug 28 05:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:21:51 volumio mpd[24446]: Aug 28 05:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:21:51 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:21:51 volumio sudo[24431]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:51 volumio volumio[24326]: error: updateQueue error: null Aug 28 05:21:53 volumio volumio[24326]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Aug 28 05:21:53 volumio volumio[24326]: info: Discovery: Started advertising with name: undefined Aug 28 05:21:56 volumio volumio[24326]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:21:56 volumio volumio[24326]: TypeError: Cannot read property 'charAt' of undefined Aug 28 05:21:56 volumio volumio[24326]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 28 05:21:56 volumio volumio[24326]: at listOnTimeout (internal/timers.js:554:17) Aug 28 05:21:56 volumio volumio[24326]: at processTimers (internal/timers.js:497:7) Aug 28 05:21:56 volumio volumio[24326]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:21:57 volumio sudo[24501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 05:20 Aug 28 05:21:57 volumio sudo[24501]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:21:57 volumio sudo[24501]: pam_unix(sudo:session): session closed for user root Aug 28 05:21:57 volumio volumio-remote-updater[472]: [2024-08-28 05:21:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 28 05:21:57 volumio volumio-remote-updater[472]: [2024-08-28 05:21:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 28 05:21:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 28 05:21:57 volumio systemd[1]: media-INTENSO.mount: Succeeded. Aug 28 05:21:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 28 05:21:57 volumio systemd[1]: Started dynamicswap service. Aug 28 05:21:57 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:21:57 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 05:21:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 243. Aug 28 05:21:57 volumio systemd[1]: Stopped Volumio Backend Module. Aug 28 05:21:57 volumio systemd[1]: Started Volumio Backend Module. Aug 28 05:21:57 volumio systemd[1]: Started dynamicswap service. Aug 28 05:21:57 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:21:59 volumio volumio[24515]: info: ------------------------------------------- Aug 28 05:21:59 volumio volumio[24515]: info: ----- Volumio3 ---- Aug 28 05:21:59 volumio volumio[24515]: info: ------------------------------------------- Aug 28 05:21:59 volumio volumio[24515]: info: ----- System startup ---- Aug 28 05:21:59 volumio volumio[24515]: info: ------------------------------------------- Aug 28 05:22:00 volumio volumio[24515]: info: MYVOLUMIO Environment detected Aug 28 05:22:00 volumio volumio[24515]: info: Plugin folders cleanup Aug 28 05:22:00 volumio volumio[24515]: info: Scanning into folder /volumio/app/plugins/ Aug 28 05:22:00 volumio volumio[24515]: info: Scanning category audio_interface Aug 28 05:22:00 volumio volumio[24515]: info: Scanning category miscellanea Aug 28 05:22:00 volumio volumio[24515]: info: Scanning category music_service Aug 28 05:22:00 volumio volumio[24515]: info: Scanning category plugins.json Aug 28 05:22:00 volumio volumio[24515]: info: Scanning category system_controller Aug 28 05:22:00 volumio volumio[24515]: info: Scanning category user_interface Aug 28 05:22:00 volumio volumio[24515]: info: Scanning into folder /data/plugins/ Aug 28 05:22:00 volumio volumio[24515]: info: Plugin folders cleanup completed Aug 28 05:22:00 volumio volumio[24515]: info: ------------------------------------------- Aug 28 05:22:00 volumio volumio[24515]: info: ----- Core plugins startup ---- Aug 28 05:22:00 volumio volumio[24515]: info: ------------------------------------------- Aug 28 05:22:00 volumio volumio[24515]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 05:22:00 volumio volumio[24515]: info: Adding plugin upnp to MyMusic Plugins Aug 28 05:22:00 volumio volumio[24515]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 05:22:00 volumio volumio[24515]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 05:22:00 volumio volumio[24515]: info: Loading plugins from folder /data/plugins/ Aug 28 05:22:00 volumio volumio[24515]: info: Loading plugin "system"... Aug 28 05:22:00 volumio volumio[24515]: info: Loading plugin "appearance"... Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "network"... Aug 28 05:22:01 volumio volumio[24515]: info: Refreshing Cached IP Addresses Aug 28 05:22:01 volumio sudo[24540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 05:22:01 volumio sudo[24540]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:01 volumio sudo[24540]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:01 volumio sudo[24542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 05:22:01 volumio sudo[24542]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "services"... Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "alsa_controller"... Aug 28 05:22:01 volumio sudo[24542]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:01 volumio sudo[24545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 05:22:01 volumio sudo[24545]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:01 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "wizard"... Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "volumio_command_line_client"... Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "upnp"... Aug 28 05:22:01 volumio volumio[24515]: info: [1724815321603] Starting Upmpd Daemon Aug 28 05:22:01 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "my_music"... Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "mpd"... Aug 28 05:22:01 volumio volumio[24515]: info: Creating MPD Configuration file Aug 28 05:22:01 volumio volumio[24515]: info: Loading plugin "upnp_browser"... Aug 28 05:22:01 volumio sudo[24553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:22:01 volumio sudo[24553]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:02 volumio sudo[24553]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:02 volumio sudo[24555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:22:02 volumio sudo[24555]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:02 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:22:02 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:22:02 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:22:02 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:22:02 volumio volumio[24515]: info: Loading plugin "networkfs"... Aug 28 05:22:02 volumio volumio-remote-updater[472]: [2024-08-28 05:22:02] [connect] Successful connection Aug 28 05:22:02 volumio volumio[24515]: info: Starting Udev Watcher for removable devices Aug 28 05:22:02 volumio volumio[24515]: info: Ignoring mount for partition: boot Aug 28 05:22:02 volumio volumio[24515]: info: Ignoring mount for partition: volumio Aug 28 05:22:02 volumio volumio[24515]: info: Ignoring mount for partition: volumio_data Aug 28 05:22:02 volumio volumio[24515]: info: Mounting Device INTENSO Aug 28 05:22:02 volumio sudo[24566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Aug 28 05:22:02 volumio sudo[24566]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:03 volumio sudo[24566]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:03 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "alarm-clock"... Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "airplay_emulation"... Aug 28 05:22:03 volumio volumio[24515]: info: Starting Shairport Sync Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "last_100"... Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "webradio"... Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "i2s_dacs"... Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "volumiodiscovery"... Aug 28 05:22:03 volumio volumio[24515]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:03 volumio volumio[24515]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:03 volumio volumio[24515]: *** WARNING *** For more information see Aug 28 05:22:03 volumio volumio[24515]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:03 volumio volumio[24515]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:03 volumio volumio[24515]: *** WARNING *** For more information see Aug 28 05:22:03 volumio node[24515]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:03 volumio node[24515]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:03 volumio node[24515]: *** WARNING *** For more information see Aug 28 05:22:03 volumio node[24515]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:03 volumio node[24515]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:03 volumio node[24515]: *** WARNING *** For more information see Aug 28 05:22:03 volumio volumio[24515]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 05:22:03 volumio volumio[24515]: info: Discovery: Started advertising with name: undefined Aug 28 05:22:03 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "outputs"... Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "albumart"... Aug 28 05:22:03 volumio mpd[24560]: Aug 28 05:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:22:03 volumio volumio[24515]: info: Plugin example_plugin is not enabled Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "inputs"... Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "updater_comm"... Aug 28 05:22:03 volumio mpd[24560]: Aug 28 05:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:22:03 volumio mpd[24560]: Aug 28 05:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:22:03 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:22:03 volumio sudo[24555]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:03 volumio volumio[24515]: info: Plugin mpdemulation is not enabled Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "rest_api"... Aug 28 05:22:03 volumio volumio[24515]: info: Loading plugin "websocket"... Aug 28 05:22:03 volumio volumio[24515]: info: Loading i18n strings for locale de Aug 28 05:22:03 volumio volumio[24515]: Updating browse sources language Aug 28 05:22:03 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:04 volumio volumio[24515]: Forking 3 albumart workers Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::initPlayerControls Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: Express server listening on port 3000 Aug 28 05:22:04 volumio volumio[24515]: [Metrics] WebUI: 5s 165.92ms Aug 28 05:22:04 volumio volumio[24515]: info: CoreStateMachine::resetVolumioState Aug 28 05:22:04 volumio volumio[24515]: info: CoreStateMachine::getcurrentVolume Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:22:04 volumio volumio[24515]: info: MPD Permissions set Aug 28 05:22:04 volumio volumio-remote-updater[472]: [2024-08-28 05:22:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724815322 101 Aug 28 05:22:04 volumio volumio[24515]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 28 05:22:04 volumio volumio[24515]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:22:04 volumio volumio[24515]: info: MPD running with PID24560 Aug 28 05:22:04 volumio volumio[24515]: ,establishing connection Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: Cannot read play queue from file Aug 28 05:22:04 volumio volumio[24515]: error: updateQueue error: null Aug 28 05:22:04 volumio volumio[24515]: info: CoreStateMachine::setRepeat false single undefined Aug 28 05:22:04 volumio volumio[24515]: info: CoreStateMachine::pushState Aug 28 05:22:04 volumio volumio[24515]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:04 volumio volumio[24515]: info: CoreStateMachine::setRandom null Aug 28 05:22:04 volumio volumio[24515]: info: CoreStateMachine::pushState Aug 28 05:22:04 volumio volumio[24515]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:04 volumio volumio[24515]: info: Setting Device type: Raspberry PI Aug 28 05:22:04 volumio volumio[24515]: info: Completed loading Core Plugins Aug 28 05:22:04 volumio volumio[24515]: info: Preparing to generate the ALSA configuration file Aug 28 05:22:04 volumio volumio[24515]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:22:04 volumio volumio[24515]: info: CoreStateMachine::pushState Aug 28 05:22:04 volumio volumio[24515]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:04 volumio volumio[24515]: info: CoreStateMachine::updateTrackBlock Aug 28 05:22:04 volumio volumio[24515]: info: CorePlayQueue::getTrackBlock Aug 28 05:22:04 volumio sudo[24545]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:22:04 volumio volumio[24515]: error: updateQueue error: null Aug 28 05:22:04 volumio volumio[24515]: info: Asound.conf file unchanged, so no further update is needed Aug 28 05:22:04 volumio volumio[24515]: info: Output device has changed, restarting MPD Aug 28 05:22:04 volumio volumio[24515]: info: Output device has changed, restarting Shairport Sync Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:04 volumio sudo[24622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:22:04 volumio sudo[24620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:22:04 volumio sudo[24622]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:04 volumio sudo[24620]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:04 volumio sudo[24620]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:04 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:22:04 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:22:04 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:22:04 volumio volumio[24515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:04 volumio volumio[24515]: info: ___________ START PLUGINS ___________ Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:04 volumio volumio[24515]: info: [1724815324930] CoreMusicLibrary::Adding element Media Servers Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:04 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:04 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:22:05 volumio volumio[24515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: Starting albumart workers Aug 28 05:22:05 volumio volumio[24515]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:05 volumio volumio[24515]: info: [1724815325153] CoreMusicLibrary::Adding element Last_100 Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:05 volumio volumio[24515]: info: [1724815325157] CoreMusicLibrary::Adding element Webradio Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:22:05 volumio volumio[24515]: info: Volumio Calling Home Aug 28 05:22:05 volumio volumio[24515]: Starting albumart workers Aug 28 05:22:05 volumio volumio[24515]: info: Completed starting Core Plugins Aug 28 05:22:05 volumio volumio[24515]: info: ------------------------------------------- Aug 28 05:22:05 volumio volumio[24515]: info: ----- MyVolumio plugins startup ---- Aug 28 05:22:05 volumio volumio[24515]: info: ------------------------------------------- Aug 28 05:22:05 volumio volumio[24515]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 05:22:05 volumio volumio[24515]: Starting albumart workers Aug 28 05:22:05 volumio volumio[24515]: info: MPD Permissions set Aug 28 05:22:05 volumio volumio[24515]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:22:05 volumio volumio[24515]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:05 volumio volumio[24515]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:22:05 volumio volumio[24515]: info: CoreStateMachine::pushState Aug 28 05:22:05 volumio volumio[24515]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:05 volumio volumio[24515]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:05 volumio volumio[24515]: info: Starting Shairport Sync Aug 28 05:22:05 volumio volumio[24515]: info: Starting Shairport Sync Aug 28 05:22:05 volumio volumio[24515]: info: Starting Shairport Sync Aug 28 05:22:05 volumio sudo[24646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:05 volumio sudo[24646]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:05 volumio sudo[24654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:05 volumio sudo[24654]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:22:06 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:22:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:06 volumio sudo[24652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:06 volumio sudo[24652]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:06 volumio sudo[24646]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:06 volumio sudo[24654]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:22:06 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 28 05:22:06 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:22:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:06 volumio volumio[24515]: info: Shairport-Sync Started Aug 28 05:22:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:06 volumio volumio[24515]: Error adding Membership: Error: addMembership EINVAL Aug 28 05:22:06 volumio volumio[24515]: info: Shairport-Sync Started Aug 28 05:22:06 volumio sudo[24652]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:06 volumio volumio[24515]: info: CoreCommandRouter::volumioGetState Aug 28 05:22:06 volumio volumio[24515]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:06 volumio volumio[24515]: info: Shairport-Sync Started Aug 28 05:22:06 volumio mpd[24638]: Aug 28 05:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:22:06 volumio mpd[24638]: Aug 28 05:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:22:06 volumio mpd[24638]: Aug 28 05:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:22:06 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:22:06 volumio sudo[24622]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:06 volumio volumio[24515]: error: updateQueue error: null Aug 28 05:22:08 volumio volumio[24515]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Aug 28 05:22:08 volumio volumio[24515]: info: Discovery: Started advertising with name: undefined Aug 28 05:22:11 volumio volumio[24515]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:22:11 volumio volumio[24515]: TypeError: Cannot read property 'charAt' of undefined Aug 28 05:22:11 volumio volumio[24515]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 28 05:22:11 volumio volumio[24515]: at listOnTimeout (internal/timers.js:554:17) Aug 28 05:22:11 volumio volumio[24515]: at processTimers (internal/timers.js:497:7) Aug 28 05:22:11 volumio volumio[24515]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:22:12 volumio sudo[24692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 05:21 Aug 28 05:22:12 volumio sudo[24692]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:12 volumio sudo[24692]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:12 volumio volumio-remote-updater[472]: [2024-08-28 05:22:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 28 05:22:12 volumio volumio-remote-updater[472]: [2024-08-28 05:22:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 28 05:22:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 28 05:22:12 volumio systemd[1]: media-INTENSO.mount: Succeeded. Aug 28 05:22:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 28 05:22:12 volumio systemd[1]: Started dynamicswap service. Aug 28 05:22:12 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:22:12 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 05:22:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 244. Aug 28 05:22:12 volumio systemd[1]: Started dynamicswap service. Aug 28 05:22:12 volumio systemd[1]: Stopped Volumio Backend Module. Aug 28 05:22:12 volumio systemd[1]: Started Volumio Backend Module. Aug 28 05:22:12 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:22:14 volumio volumio[24707]: info: ------------------------------------------- Aug 28 05:22:14 volumio volumio[24707]: info: ----- Volumio3 ---- Aug 28 05:22:14 volumio volumio[24707]: info: ------------------------------------------- Aug 28 05:22:14 volumio volumio[24707]: info: ----- System startup ---- Aug 28 05:22:14 volumio volumio[24707]: info: ------------------------------------------- Aug 28 05:22:15 volumio volumio[24707]: info: MYVOLUMIO Environment detected Aug 28 05:22:15 volumio volumio[24707]: info: Plugin folders cleanup Aug 28 05:22:15 volumio volumio[24707]: info: Scanning into folder /volumio/app/plugins/ Aug 28 05:22:15 volumio volumio[24707]: info: Scanning category audio_interface Aug 28 05:22:15 volumio volumio[24707]: info: Scanning category miscellanea Aug 28 05:22:15 volumio volumio[24707]: info: Scanning category music_service Aug 28 05:22:15 volumio volumio[24707]: info: Scanning category plugins.json Aug 28 05:22:15 volumio volumio[24707]: info: Scanning category system_controller Aug 28 05:22:15 volumio volumio[24707]: info: Scanning category user_interface Aug 28 05:22:15 volumio volumio[24707]: info: Scanning into folder /data/plugins/ Aug 28 05:22:15 volumio volumio[24707]: info: Plugin folders cleanup completed Aug 28 05:22:15 volumio volumio[24707]: info: ------------------------------------------- Aug 28 05:22:15 volumio volumio[24707]: info: ----- Core plugins startup ---- Aug 28 05:22:15 volumio volumio[24707]: info: ------------------------------------------- Aug 28 05:22:15 volumio volumio[24707]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 05:22:15 volumio volumio[24707]: info: Adding plugin upnp to MyMusic Plugins Aug 28 05:22:15 volumio volumio[24707]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 05:22:15 volumio volumio[24707]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 05:22:15 volumio volumio[24707]: info: Loading plugins from folder /data/plugins/ Aug 28 05:22:15 volumio volumio[24707]: info: Loading plugin "system"... Aug 28 05:22:15 volumio volumio[24707]: info: Loading plugin "appearance"... Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "network"... Aug 28 05:22:16 volumio volumio[24707]: info: Refreshing Cached IP Addresses Aug 28 05:22:16 volumio sudo[24730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 05:22:16 volumio sudo[24730]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:16 volumio sudo[24730]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:16 volumio sudo[24732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 05:22:16 volumio sudo[24732]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "services"... Aug 28 05:22:16 volumio sudo[24732]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "alsa_controller"... Aug 28 05:22:16 volumio sudo[24735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 05:22:16 volumio sudo[24735]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:16 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "wizard"... Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "volumio_command_line_client"... Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "upnp"... Aug 28 05:22:16 volumio volumio[24707]: info: [1724815336514] Starting Upmpd Daemon Aug 28 05:22:16 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "my_music"... Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "mpd"... Aug 28 05:22:16 volumio volumio[24707]: info: Creating MPD Configuration file Aug 28 05:22:16 volumio volumio[24707]: info: Loading plugin "upnp_browser"... Aug 28 05:22:16 volumio sudo[24743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:22:16 volumio sudo[24743]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:16 volumio sudo[24743]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:16 volumio sudo[24745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:22:16 volumio sudo[24745]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:16 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:22:16 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:22:16 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:22:17 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:22:17 volumio volumio[24707]: info: Loading plugin "networkfs"... Aug 28 05:22:17 volumio volumio-remote-updater[472]: [2024-08-28 05:22:17] [connect] Successful connection Aug 28 05:22:17 volumio volumio[24707]: info: Starting Udev Watcher for removable devices Aug 28 05:22:17 volumio volumio[24707]: info: Ignoring mount for partition: boot Aug 28 05:22:17 volumio volumio[24707]: info: Ignoring mount for partition: volumio Aug 28 05:22:17 volumio volumio[24707]: info: Ignoring mount for partition: volumio_data Aug 28 05:22:17 volumio volumio[24707]: info: Mounting Device INTENSO Aug 28 05:22:17 volumio sudo[24756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Aug 28 05:22:17 volumio sudo[24756]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:17 volumio sudo[24756]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:17 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:17 volumio volumio[24707]: info: Loading plugin "alarm-clock"... Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "airplay_emulation"... Aug 28 05:22:18 volumio volumio[24707]: info: Starting Shairport Sync Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "last_100"... Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "webradio"... Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "i2s_dacs"... Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "volumiodiscovery"... Aug 28 05:22:18 volumio volumio[24707]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:18 volumio node[24707]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:18 volumio volumio[24707]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:18 volumio node[24707]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:18 volumio volumio[24707]: *** WARNING *** For more information see Aug 28 05:22:18 volumio node[24707]: *** WARNING *** For more information see Aug 28 05:22:18 volumio volumio[24707]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:18 volumio node[24707]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:18 volumio volumio[24707]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:18 volumio node[24707]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:18 volumio volumio[24707]: *** WARNING *** For more information see Aug 28 05:22:18 volumio node[24707]: *** WARNING *** For more information see Aug 28 05:22:18 volumio volumio[24707]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 05:22:18 volumio volumio[24707]: info: Discovery: Started advertising with name: undefined Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "outputs"... Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "albumart"... Aug 28 05:22:18 volumio volumio[24707]: info: Plugin example_plugin is not enabled Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "inputs"... Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "updater_comm"... Aug 28 05:22:18 volumio mpd[24750]: Aug 28 05:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:22:18 volumio mpd[24750]: Aug 28 05:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:22:18 volumio mpd[24750]: Aug 28 05:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:22:18 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:22:18 volumio sudo[24745]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:18 volumio volumio[24707]: info: Plugin mpdemulation is not enabled Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "rest_api"... Aug 28 05:22:18 volumio volumio[24707]: info: Loading plugin "websocket"... Aug 28 05:22:18 volumio volumio[24707]: info: Loading i18n strings for locale de Aug 28 05:22:18 volumio volumio[24707]: Updating browse sources language Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:18 volumio volumio[24707]: Forking 3 albumart workers Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::initPlayerControls Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:18 volumio volumio[24707]: Express server listening on port 3000 Aug 28 05:22:18 volumio volumio[24707]: [Metrics] WebUI: 5s 86.34ms Aug 28 05:22:19 volumio volumio[24707]: info: CoreStateMachine::resetVolumioState Aug 28 05:22:19 volumio volumio[24707]: info: CoreStateMachine::getcurrentVolume Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:22:19 volumio volumio[24707]: info: MPD Permissions set Aug 28 05:22:19 volumio volumio-remote-updater[472]: [2024-08-28 05:22:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724815337 101 Aug 28 05:22:19 volumio volumio[24707]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 28 05:22:19 volumio volumio[24707]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:22:19 volumio volumio[24707]: info: MPD running with PID24750 Aug 28 05:22:19 volumio volumio[24707]: ,establishing connection Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: Cannot read play queue from file Aug 28 05:22:19 volumio volumio[24707]: error: updateQueue error: null Aug 28 05:22:19 volumio volumio[24707]: info: CoreStateMachine::setRepeat false single undefined Aug 28 05:22:19 volumio volumio[24707]: info: CoreStateMachine::pushState Aug 28 05:22:19 volumio volumio[24707]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:19 volumio volumio[24707]: info: CoreStateMachine::setRandom null Aug 28 05:22:19 volumio volumio[24707]: info: CoreStateMachine::pushState Aug 28 05:22:19 volumio volumio[24707]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:19 volumio volumio[24707]: info: Setting Device type: Raspberry PI Aug 28 05:22:19 volumio volumio[24707]: info: Completed loading Core Plugins Aug 28 05:22:19 volumio volumio[24707]: info: Preparing to generate the ALSA configuration file Aug 28 05:22:19 volumio volumio[24707]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:22:19 volumio volumio[24707]: info: CoreStateMachine::pushState Aug 28 05:22:19 volumio volumio[24707]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:19 volumio volumio[24707]: info: CoreStateMachine::updateTrackBlock Aug 28 05:22:19 volumio volumio[24707]: info: CorePlayQueue::getTrackBlock Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:22:19 volumio volumio[24707]: error: updateQueue error: null Aug 28 05:22:19 volumio volumio[24707]: info: Asound.conf file unchanged, so no further update is needed Aug 28 05:22:19 volumio volumio[24707]: info: Output device has changed, restarting MPD Aug 28 05:22:19 volumio volumio[24707]: info: Output device has changed, restarting Shairport Sync Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:19 volumio sudo[24809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:22:19 volumio sudo[24809]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:19 volumio sudo[24809]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:19 volumio sudo[24735]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:19 volumio sudo[24812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:22:19 volumio sudo[24812]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:19 volumio volumio[24707]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:19 volumio volumio[24707]: info: ___________ START PLUGINS ___________ Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:19 volumio volumio[24707]: info: [1724815339712] CoreMusicLibrary::Adding element Media Servers Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:19 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:19 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:22:19 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:22:19 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:22:19 volumio volumio[24707]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:19 volumio volumio[24707]: Starting albumart workers Aug 28 05:22:20 volumio volumio[24707]: Starting albumart workers Aug 28 05:22:20 volumio volumio[24707]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:20 volumio volumio[24707]: info: [1724815340072] CoreMusicLibrary::Adding element Last_100 Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:20 volumio volumio[24707]: info: [1724815340078] CoreMusicLibrary::Adding element Webradio Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:22:20 volumio volumio[24707]: info: Volumio Calling Home Aug 28 05:22:20 volumio volumio[24707]: Starting albumart workers Aug 28 05:22:20 volumio volumio[24707]: info: Completed starting Core Plugins Aug 28 05:22:20 volumio volumio[24707]: info: ------------------------------------------- Aug 28 05:22:20 volumio volumio[24707]: info: ----- MyVolumio plugins startup ---- Aug 28 05:22:20 volumio volumio[24707]: info: ------------------------------------------- Aug 28 05:22:20 volumio volumio[24707]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 05:22:20 volumio volumio[24707]: info: MPD Permissions set Aug 28 05:22:20 volumio volumio[24707]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:22:20 volumio volumio[24707]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:20 volumio volumio[24707]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:22:20 volumio volumio[24707]: info: CoreStateMachine::pushState Aug 28 05:22:20 volumio volumio[24707]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:20 volumio volumio[24707]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:20 volumio volumio[24707]: info: Starting Shairport Sync Aug 28 05:22:20 volumio volumio[24707]: info: Starting Shairport Sync Aug 28 05:22:20 volumio volumio[24707]: info: Starting Shairport Sync Aug 28 05:22:20 volumio sudo[24843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:20 volumio sudo[24843]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:20 volumio sudo[24846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:22:20 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:22:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:21 volumio sudo[24846]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:21 volumio sudo[24848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:21 volumio sudo[24848]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:21 volumio sudo[24843]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:22:21 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:22:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:21 volumio volumio[24707]: info: Shairport-Sync Started Aug 28 05:22:21 volumio volumio[24707]: Error adding Membership: Error: addMembership EINVAL Aug 28 05:22:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:21 volumio sudo[24846]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:21 volumio sudo[24848]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:21 volumio volumio[24707]: info: CoreCommandRouter::volumioGetState Aug 28 05:22:21 volumio volumio[24707]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:21 volumio volumio[24707]: info: Shairport-Sync Started Aug 28 05:22:21 volumio volumio[24707]: info: Shairport-Sync Started Aug 28 05:22:21 volumio mpd[24826]: Aug 28 05:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:22:21 volumio mpd[24826]: Aug 28 05:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:22:21 volumio mpd[24826]: Aug 28 05:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:22:21 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:22:21 volumio sudo[24812]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:21 volumio volumio[24707]: error: updateQueue error: null Aug 28 05:22:23 volumio volumio[24707]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Aug 28 05:22:23 volumio volumio[24707]: info: Discovery: Started advertising with name: undefined Aug 28 05:22:26 volumio volumio[24707]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:22:26 volumio volumio[24707]: TypeError: Cannot read property 'charAt' of undefined Aug 28 05:22:26 volumio volumio[24707]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 28 05:22:26 volumio volumio[24707]: at listOnTimeout (internal/timers.js:554:17) Aug 28 05:22:26 volumio volumio[24707]: at processTimers (internal/timers.js:497:7) Aug 28 05:22:26 volumio volumio[24707]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:22:27 volumio sudo[24885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 05:21 Aug 28 05:22:27 volumio sudo[24885]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:27 volumio sudo[24885]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:27 volumio volumio-remote-updater[472]: [2024-08-28 05:22:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 28 05:22:27 volumio volumio-remote-updater[472]: [2024-08-28 05:22:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 28 05:22:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 28 05:22:27 volumio systemd[1]: media-INTENSO.mount: Succeeded. Aug 28 05:22:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 28 05:22:27 volumio systemd[1]: Started dynamicswap service. Aug 28 05:22:27 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:22:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 05:22:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 245. Aug 28 05:22:27 volumio systemd[1]: Stopped Volumio Backend Module. Aug 28 05:22:27 volumio systemd[1]: Started Volumio Backend Module. Aug 28 05:22:27 volumio systemd[1]: Started dynamicswap service. Aug 28 05:22:27 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:22:29 volumio volumio[24899]: info: ------------------------------------------- Aug 28 05:22:29 volumio volumio[24899]: info: ----- Volumio3 ---- Aug 28 05:22:29 volumio volumio[24899]: info: ------------------------------------------- Aug 28 05:22:29 volumio volumio[24899]: info: ----- System startup ---- Aug 28 05:22:29 volumio volumio[24899]: info: ------------------------------------------- Aug 28 05:22:30 volumio volumio[24899]: info: MYVOLUMIO Environment detected Aug 28 05:22:30 volumio volumio[24899]: info: Plugin folders cleanup Aug 28 05:22:30 volumio volumio[24899]: info: Scanning into folder /volumio/app/plugins/ Aug 28 05:22:30 volumio volumio[24899]: info: Scanning category audio_interface Aug 28 05:22:30 volumio volumio[24899]: info: Scanning category miscellanea Aug 28 05:22:30 volumio volumio[24899]: info: Scanning category music_service Aug 28 05:22:30 volumio volumio[24899]: info: Scanning category plugins.json Aug 28 05:22:30 volumio volumio[24899]: info: Scanning category system_controller Aug 28 05:22:30 volumio volumio[24899]: info: Scanning category user_interface Aug 28 05:22:30 volumio volumio[24899]: info: Scanning into folder /data/plugins/ Aug 28 05:22:30 volumio volumio[24899]: info: Plugin folders cleanup completed Aug 28 05:22:30 volumio volumio[24899]: info: ------------------------------------------- Aug 28 05:22:30 volumio volumio[24899]: info: ----- Core plugins startup ---- Aug 28 05:22:30 volumio volumio[24899]: info: ------------------------------------------- Aug 28 05:22:30 volumio volumio[24899]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 05:22:30 volumio volumio[24899]: info: Adding plugin upnp to MyMusic Plugins Aug 28 05:22:30 volumio volumio[24899]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 05:22:30 volumio volumio[24899]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 05:22:30 volumio volumio[24899]: info: Loading plugins from folder /data/plugins/ Aug 28 05:22:30 volumio volumio[24899]: info: Loading plugin "system"... Aug 28 05:22:30 volumio volumio[24899]: info: Loading plugin "appearance"... Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "network"... Aug 28 05:22:31 volumio volumio[24899]: info: Refreshing Cached IP Addresses Aug 28 05:22:31 volumio sudo[24923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 05:22:31 volumio sudo[24923]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:31 volumio sudo[24923]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:31 volumio sudo[24925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 05:22:31 volumio sudo[24925]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "services"... Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "alsa_controller"... Aug 28 05:22:31 volumio sudo[24925]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:31 volumio sudo[24928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 05:22:31 volumio sudo[24928]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:31 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "wizard"... Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "volumio_command_line_client"... Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "upnp"... Aug 28 05:22:31 volumio volumio[24899]: info: [1724815351555] Starting Upmpd Daemon Aug 28 05:22:31 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "my_music"... Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "mpd"... Aug 28 05:22:31 volumio volumio[24899]: info: Creating MPD Configuration file Aug 28 05:22:31 volumio volumio[24899]: info: Loading plugin "upnp_browser"... Aug 28 05:22:31 volumio sudo[24936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:22:31 volumio sudo[24936]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:31 volumio sudo[24936]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:31 volumio sudo[24938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:22:31 volumio sudo[24938]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:31 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:22:32 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:22:32 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:22:32 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:22:32 volumio volumio[24899]: info: Loading plugin "networkfs"... Aug 28 05:22:32 volumio volumio-remote-updater[472]: [2024-08-28 05:22:32] [connect] Successful connection Aug 28 05:22:32 volumio volumio[24899]: info: Starting Udev Watcher for removable devices Aug 28 05:22:32 volumio volumio[24899]: info: Ignoring mount for partition: boot Aug 28 05:22:32 volumio volumio[24899]: info: Ignoring mount for partition: volumio Aug 28 05:22:32 volumio volumio[24899]: info: Ignoring mount for partition: volumio_data Aug 28 05:22:32 volumio volumio[24899]: info: Mounting Device INTENSO Aug 28 05:22:32 volumio sudo[24949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Aug 28 05:22:32 volumio sudo[24949]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:32 volumio sudo[24949]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:32 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:32 volumio volumio[24899]: info: Loading plugin "alarm-clock"... Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "airplay_emulation"... Aug 28 05:22:33 volumio volumio[24899]: info: Starting Shairport Sync Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "last_100"... Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "webradio"... Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "i2s_dacs"... Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "volumiodiscovery"... Aug 28 05:22:33 volumio volumio[24899]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:33 volumio volumio[24899]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:33 volumio volumio[24899]: *** WARNING *** For more information see Aug 28 05:22:33 volumio node[24899]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:33 volumio volumio[24899]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:33 volumio volumio[24899]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:33 volumio volumio[24899]: *** WARNING *** For more information see Aug 28 05:22:33 volumio node[24899]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:33 volumio node[24899]: *** WARNING *** For more information see Aug 28 05:22:33 volumio node[24899]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:33 volumio node[24899]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:33 volumio node[24899]: *** WARNING *** For more information see Aug 28 05:22:33 volumio volumio[24899]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 05:22:33 volumio volumio[24899]: info: Discovery: Started advertising with name: undefined Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "outputs"... Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "albumart"... Aug 28 05:22:33 volumio volumio[24899]: info: Plugin example_plugin is not enabled Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "inputs"... Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "updater_comm"... Aug 28 05:22:33 volumio mpd[24943]: Aug 28 05:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:22:33 volumio volumio[24899]: info: Plugin mpdemulation is not enabled Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "rest_api"... Aug 28 05:22:33 volumio mpd[24943]: Aug 28 05:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:22:33 volumio mpd[24943]: Aug 28 05:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:22:33 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:22:33 volumio sudo[24938]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:33 volumio volumio[24899]: info: Loading plugin "websocket"... Aug 28 05:22:33 volumio volumio[24899]: info: Loading i18n strings for locale de Aug 28 05:22:33 volumio volumio[24899]: Updating browse sources language Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:33 volumio volumio[24899]: Forking 3 albumart workers Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::initPlayerControls Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:33 volumio volumio[24899]: Express server listening on port 3000 Aug 28 05:22:33 volumio volumio[24899]: [Metrics] WebUI: 5s 11.56ms Aug 28 05:22:33 volumio volumio[24899]: info: CoreStateMachine::resetVolumioState Aug 28 05:22:33 volumio volumio[24899]: info: CoreStateMachine::getcurrentVolume Aug 28 05:22:33 volumio volumio[24899]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:22:34 volumio volumio[24899]: info: MPD Permissions set Aug 28 05:22:34 volumio volumio-remote-updater[472]: [2024-08-28 05:22:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724815352 101 Aug 28 05:22:34 volumio volumio[24899]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 28 05:22:34 volumio volumio[24899]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:22:34 volumio volumio[24899]: info: MPD running with PID24943 Aug 28 05:22:34 volumio volumio[24899]: ,establishing connection Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: Cannot read play queue from file Aug 28 05:22:34 volumio volumio[24899]: error: updateQueue error: null Aug 28 05:22:34 volumio volumio[24899]: info: CoreStateMachine::setRepeat false single undefined Aug 28 05:22:34 volumio volumio[24899]: info: CoreStateMachine::pushState Aug 28 05:22:34 volumio volumio[24899]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:34 volumio volumio[24899]: info: CoreStateMachine::setRandom null Aug 28 05:22:34 volumio volumio[24899]: info: CoreStateMachine::pushState Aug 28 05:22:34 volumio volumio[24899]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:34 volumio volumio[24899]: info: Setting Device type: Raspberry PI Aug 28 05:22:34 volumio volumio[24899]: info: Completed loading Core Plugins Aug 28 05:22:34 volumio volumio[24899]: info: Preparing to generate the ALSA configuration file Aug 28 05:22:34 volumio volumio[24899]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:22:34 volumio volumio[24899]: info: CoreStateMachine::pushState Aug 28 05:22:34 volumio volumio[24899]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:34 volumio volumio[24899]: info: CoreStateMachine::updateTrackBlock Aug 28 05:22:34 volumio volumio[24899]: info: CorePlayQueue::getTrackBlock Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:22:34 volumio volumio[24899]: error: updateQueue error: null Aug 28 05:22:34 volumio volumio[24899]: info: Asound.conf file unchanged, so no further update is needed Aug 28 05:22:34 volumio volumio[24899]: info: Output device has changed, restarting MPD Aug 28 05:22:34 volumio volumio[24899]: info: Output device has changed, restarting Shairport Sync Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:34 volumio sudo[25003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:22:34 volumio sudo[25003]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:34 volumio sudo[25003]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:34 volumio sudo[25004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:22:34 volumio sudo[25004]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:34 volumio volumio[24899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:34 volumio volumio[24899]: info: ___________ START PLUGINS ___________ Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:34 volumio volumio[24899]: info: [1724815354682] CoreMusicLibrary::Adding element Media Servers Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:34 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:22:34 volumio sudo[24928]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:34 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:22:34 volumio volumio[24899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:34 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:22:34 volumio wpa_supplicant[813]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:a6:2f:c6:e6:0d reason=0 locally_generated=1 Aug 28 05:22:34 volumio volumio[24899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:34 volumio volumio[24899]: info: [1724815354911] CoreMusicLibrary::Adding element Last_100 Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:34 volumio dhcpcd[615]: wlan0: carrier lost Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:34 volumio volumio[24899]: info: [1724815354916] CoreMusicLibrary::Adding element Webradio Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:34 volumio wpa_supplicant[813]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 28 05:22:34 volumio wpa_supplicant[813]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Aug 28 05:22:34 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:22:34 volumio volumio[24899]: info: Volumio Calling Home Aug 28 05:22:34 volumio volumio[24899]: Starting albumart workers Aug 28 05:22:35 volumio wpa_supplicant[813]: wlan0: Trying to associate with SSID 'G Fritz Zintl' Aug 28 05:22:35 volumio avahi-daemon[467]: Withdrawing address record for 192.168.178.24 on wlan0. Aug 28 05:22:35 volumio avahi-daemon[467]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.24. Aug 28 05:22:35 volumio dhcpcd[615]: wlan0: deleting route to 192.168.178.0/24 Aug 28 05:22:35 volumio dhcpcd[615]: wlan0: deleting default route via 192.168.178.1 Aug 28 05:22:35 volumio avahi-daemon[467]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 28 05:22:35 volumio volumio[24899]: info: Completed starting Core Plugins Aug 28 05:22:35 volumio volumio[24899]: info: ------------------------------------------- Aug 28 05:22:35 volumio volumio[24899]: info: ----- MyVolumio plugins startup ---- Aug 28 05:22:35 volumio volumio[24899]: info: ------------------------------------------- Aug 28 05:22:35 volumio volumio[24899]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 05:22:35 volumio systemd[1]: Stopping Network Time Service... Aug 28 05:22:35 volumio ntpd[9069]: ntpd exiting on signal 15 (Terminated) Aug 28 05:22:35 volumio ntpd[9069]: 192.168.178.1 local addr 192.168.178.24 -> Aug 28 05:22:35 volumio ntpd[9069]: 217.197.91.176 local addr 192.168.178.24 -> Aug 28 05:22:35 volumio ntpd[9069]: 162.159.200.123 local addr 192.168.178.24 -> Aug 28 05:22:35 volumio ntpd[9069]: 79.133.44.139 local addr 192.168.178.24 -> Aug 28 05:22:35 volumio ntpd[9069]: 62.128.1.19 local addr 192.168.178.24 -> Aug 28 05:22:35 volumio ntpd[9069]: 85.215.189.120 local addr 192.168.178.24 -> Aug 28 05:22:35 volumio systemd[1]: ntp.service: Succeeded. Aug 28 05:22:35 volumio systemd[1]: Stopped Network Time Service. Aug 28 05:22:35 volumio volumio[24899]: Starting albumart workers Aug 28 05:22:35 volumio kernel: EXT4-fs error (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #1306980: comm systemd: Directory block failed checksum Aug 28 05:22:35 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_empty_dir:3093: inode #1306980: comm systemd: Directory block failed checksum Aug 28 05:22:35 volumio volumio[24899]: Starting albumart workers Aug 28 05:22:35 volumio systemd[1]: Starting Network Time Service... Aug 28 05:22:35 volumio volumio[24899]: info: MPD Permissions set Aug 28 05:22:35 volumio volumio[24899]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:22:35 volumio ntpd[25067]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 28 05:22:35 volumio ntpd[25067]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Aug 28 05:22:35 volumio systemd[1]: Started Network Time Service. Aug 28 05:22:35 volumio ntpd[25074]: proto: precision = 0.729 usec (-20) Aug 28 05:22:35 volumio ntpd[25074]: format error frequency file /var/lib/ntp/ntp.drift Aug 28 05:22:35 volumio ntpd[25074]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 28 05:22:35 volumio ntpd[25074]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 28 05:22:35 volumio ntpd[25074]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 245 days ago Aug 28 05:22:35 volumio ntpd[25074]: Listen and drop on 0 v6wildcard [::]:123 Aug 28 05:22:35 volumio ntpd[25074]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 28 05:22:35 volumio ntpd[25074]: Listen normally on 2 lo 127.0.0.1:123 Aug 28 05:22:35 volumio ntpd[25074]: Listening on routing socket on fd #19 for interface updates Aug 28 05:22:35 volumio ntpd[25074]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 05:22:35 volumio ntpd[25074]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 05:22:35 volumio volumio[24899]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:35 volumio volumio[24899]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:22:35 volumio volumio[24899]: info: CoreStateMachine::pushState Aug 28 05:22:35 volumio volumio[24899]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:35 volumio volumio[24899]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:35 volumio volumio[24899]: info: Starting Shairport Sync Aug 28 05:22:35 volumio volumio[24899]: info: Starting Shairport Sync Aug 28 05:22:35 volumio volumio[24899]: info: Starting Shairport Sync Aug 28 05:22:35 volumio sudo[25081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:35 volumio sudo[25084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:35 volumio sudo[25085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:35 volumio sudo[25085]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:35 volumio sudo[25084]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:35 volumio sudo[25081]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:22:35 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:22:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:36 volumio sudo[25084]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:36 volumio sudo[25085]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:36 volumio sudo[25081]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:36 volumio volumio[24899]: info: CoreCommandRouter::volumioGetState Aug 28 05:22:36 volumio volumio[24899]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:36 volumio volumio[24899]: info: Shairport-Sync Started Aug 28 05:22:36 volumio volumio[24899]: Error adding Membership: Error: addMembership EINVAL Aug 28 05:22:36 volumio volumio[24899]: info: Shairport-Sync Started Aug 28 05:22:36 volumio volumio[24899]: info: Shairport-Sync Started Aug 28 05:22:36 volumio ntpd[25074]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 28 05:22:36 volumio mpd[25066]: Aug 28 05:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:22:36 volumio mpd[25066]: Aug 28 05:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:22:36 volumio mpd[25066]: Aug 28 05:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:22:36 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:22:36 volumio sudo[25004]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:36 volumio volumio[24899]: error: updateQueue error: null Aug 28 05:22:37 volumio ntpd[25074]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 28 05:22:38 volumio wpa_supplicant[813]: wlan0: Associated with 3c:a6:2f:c6:e6:0e Aug 28 05:22:38 volumio wpa_supplicant[813]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:c6:e6:0e completed [id=0 id_str=] Aug 28 05:22:38 volumio dhcpcd[615]: wlan0: carrier acquired Aug 28 05:22:38 volumio wpa_supplicant[813]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 28 05:22:38 volumio wpa_supplicant[813]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 28 05:22:38 volumio dhcpcd[615]: wlan0: IAID eb:0d:0b:6e Aug 28 05:22:38 volumio wpa_supplicant[813]: RRM: Ignoring radio measurement request: Not RRM network Aug 28 05:22:38 volumio volumio[24899]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Aug 28 05:22:38 volumio volumio[24899]: info: Discovery: Started advertising with name: undefined Aug 28 05:22:38 volumio ntpd[25074]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 28 05:22:38 volumio dhcpcd[615]: wlan0: soliciting an IPv6 router Aug 28 05:22:38 volumio dhcpcd[615]: wlan0: soliciting a DHCP lease Aug 28 05:22:39 volumio dhcpcd[615]: wlan0: offered 192.168.178.24 from 192.168.178.1 Aug 28 05:22:39 volumio dhcpcd[615]: wlan0: probing address 192.168.178.24/24 Aug 28 05:22:39 volumio ntpd[25074]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 28 05:22:40 volumio wpa_supplicant[813]: RRM: Ignoring radio measurement request: Not RRM network Aug 28 05:22:40 volumio dhcpcd[615]: wlan0: carrier lost Aug 28 05:22:40 volumio wpa_supplicant[813]: wlan0: Associated with 3c:a6:2f:c6:e6:0d Aug 28 05:22:40 volumio wpa_supplicant[813]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:c6:e6:0d completed [id=0 id_str=] Aug 28 05:22:40 volumio wpa_supplicant[813]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 28 05:22:40 volumio dhcpcd[615]: wlan0: carrier acquired Aug 28 05:22:40 volumio dhcpcd[615]: wlan0: IAID eb:0d:0b:6e Aug 28 05:22:41 volumio dhcpcd[615]: wlan0: soliciting a DHCP lease Aug 28 05:22:41 volumio dhcpcd[615]: wlan0: offered 192.168.178.24 from 192.168.178.1 Aug 28 05:22:41 volumio dhcpcd[615]: wlan0: probing address 192.168.178.24/24 Aug 28 05:22:41 volumio volumio[24899]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:22:41 volumio volumio[24899]: TypeError: Cannot read property 'charAt' of undefined Aug 28 05:22:41 volumio volumio[24899]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 28 05:22:41 volumio volumio[24899]: at listOnTimeout (internal/timers.js:554:17) Aug 28 05:22:41 volumio volumio[24899]: at processTimers (internal/timers.js:497:7) Aug 28 05:22:41 volumio volumio[24899]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:22:41 volumio dhcpcd[615]: wlan0: soliciting an IPv6 router Aug 28 05:22:42 volumio sudo[25152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 05:21 Aug 28 05:22:42 volumio sudo[25152]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:42 volumio sudo[25152]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:42 volumio volumio-remote-updater[472]: [2024-08-28 05:22:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 28 05:22:42 volumio volumio-remote-updater[472]: [2024-08-28 05:22:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 28 05:22:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 28 05:22:42 volumio systemd[1]: media-INTENSO.mount: Succeeded. Aug 28 05:22:42 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 28 05:22:42 volumio systemd[1]: Started dynamicswap service. Aug 28 05:22:42 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:22:42 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 28 05:22:42 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 246. Aug 28 05:22:42 volumio systemd[1]: Started dynamicswap service. Aug 28 05:22:42 volumio systemd[1]: Stopped Volumio Backend Module. Aug 28 05:22:42 volumio systemd[1]: Started Volumio Backend Module. Aug 28 05:22:42 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 28 05:22:44 volumio volumio[25168]: info: ------------------------------------------- Aug 28 05:22:44 volumio volumio[25168]: info: ----- Volumio3 ---- Aug 28 05:22:44 volumio volumio[25168]: info: ------------------------------------------- Aug 28 05:22:44 volumio volumio[25168]: info: ----- System startup ---- Aug 28 05:22:44 volumio volumio[25168]: info: ------------------------------------------- Aug 28 05:22:45 volumio volumio[25168]: info: MYVOLUMIO Environment detected Aug 28 05:22:45 volumio volumio[25168]: info: Plugin folders cleanup Aug 28 05:22:45 volumio volumio[25168]: info: Scanning into folder /volumio/app/plugins/ Aug 28 05:22:45 volumio volumio[25168]: info: Scanning category audio_interface Aug 28 05:22:45 volumio volumio[25168]: info: Scanning category miscellanea Aug 28 05:22:45 volumio volumio[25168]: info: Scanning category music_service Aug 28 05:22:45 volumio volumio[25168]: info: Scanning category plugins.json Aug 28 05:22:45 volumio volumio[25168]: info: Scanning category system_controller Aug 28 05:22:45 volumio volumio[25168]: info: Scanning category user_interface Aug 28 05:22:45 volumio volumio[25168]: info: Scanning into folder /data/plugins/ Aug 28 05:22:45 volumio volumio[25168]: info: Plugin folders cleanup completed Aug 28 05:22:45 volumio volumio[25168]: info: ------------------------------------------- Aug 28 05:22:45 volumio volumio[25168]: info: ----- Core plugins startup ---- Aug 28 05:22:45 volumio volumio[25168]: info: ------------------------------------------- Aug 28 05:22:45 volumio volumio[25168]: info: Loading plugins from folder /volumio/app/plugins/ Aug 28 05:22:45 volumio volumio[25168]: info: Adding plugin upnp to MyMusic Plugins Aug 28 05:22:45 volumio volumio[25168]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 28 05:22:45 volumio volumio[25168]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 28 05:22:45 volumio volumio[25168]: info: Loading plugins from folder /data/plugins/ Aug 28 05:22:45 volumio volumio[25168]: info: Loading plugin "system"... Aug 28 05:22:45 volumio volumio[25168]: info: Loading plugin "appearance"... Aug 28 05:22:45 volumio dhcpcd[615]: wlan0: leased 192.168.178.24 for 864000 seconds Aug 28 05:22:45 volumio avahi-daemon[467]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.24. Aug 28 05:22:45 volumio dhcpcd[615]: wlan0: adding route to 192.168.178.0/24 Aug 28 05:22:45 volumio dhcpcd[615]: wlan0: adding default route via 192.168.178.1 Aug 28 05:22:45 volumio avahi-daemon[467]: New relevant interface wlan0.IPv4 for mDNS. Aug 28 05:22:45 volumio avahi-daemon[467]: Registering new address record for 192.168.178.24 on wlan0.IPv4. Aug 28 05:22:46 volumio ntpd[25074]: ntpd exiting on signal 15 (Terminated) Aug 28 05:22:46 volumio systemd[1]: Stopping Network Time Service... Aug 28 05:22:46 volumio systemd[1]: ntp.service: Succeeded. Aug 28 05:22:46 volumio systemd[1]: Stopped Network Time Service. Aug 28 05:22:46 volumio systemd[1]: Starting Network Time Service... Aug 28 05:22:46 volumio ntpd[25223]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 28 05:22:46 volumio ntpd[25223]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Aug 28 05:22:46 volumio ntpd[25229]: proto: precision = 0.677 usec (-20) Aug 28 05:22:46 volumio ntpd[25229]: format error frequency file /var/lib/ntp/ntp.drift Aug 28 05:22:46 volumio ntpd[25229]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 28 05:22:46 volumio ntpd[25229]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 28 05:22:46 volumio ntpd[25229]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 245 days ago Aug 28 05:22:46 volumio systemd[1]: Started Network Time Service. Aug 28 05:22:46 volumio ntpd[25229]: Listen and drop on 0 v6wildcard [::]:123 Aug 28 05:22:46 volumio ntpd[25229]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 28 05:22:46 volumio ntpd[25229]: Listen normally on 2 lo 127.0.0.1:123 Aug 28 05:22:46 volumio ntpd[25229]: Listen normally on 3 wlan0 192.168.178.24:123 Aug 28 05:22:46 volumio ntpd[25229]: Listening on routing socket on fd #20 for interface updates Aug 28 05:22:46 volumio ntpd[25229]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 05:22:46 volumio ntpd[25229]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "network"... Aug 28 05:22:46 volumio volumio[25168]: info: Refreshing Cached IP Addresses Aug 28 05:22:46 volumio sudo[25232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 05:22:46 volumio sudo[25232]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:46 volumio sudo[25232]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "services"... Aug 28 05:22:46 volumio sudo[25234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 05:22:46 volumio sudo[25234]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "alsa_controller"... Aug 28 05:22:46 volumio sudo[25234]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:46 volumio sudo[25237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 28 05:22:46 volumio sudo[25237]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:46 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "wizard"... Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "volumio_command_line_client"... Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "upnp"... Aug 28 05:22:46 volumio volumio[25168]: info: [1724815366510] Starting Upmpd Daemon Aug 28 05:22:46 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "my_music"... Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "mpd"... Aug 28 05:22:46 volumio volumio[25168]: info: Creating MPD Configuration file Aug 28 05:22:46 volumio volumio[25168]: info: Loading plugin "upnp_browser"... Aug 28 05:22:46 volumio sudo[25245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:22:46 volumio sudo[25245]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:46 volumio sudo[25245]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:46 volumio sudo[25247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:22:46 volumio sudo[25247]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:46 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:22:46 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:22:46 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:22:47 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:22:47 volumio volumio[25168]: info: Loading plugin "networkfs"... Aug 28 05:22:47 volumio wpa_supplicant[813]: RRM: Ignoring radio measurement request: Not RRM network Aug 28 05:22:47 volumio volumio-remote-updater[472]: [2024-08-28 05:22:47] [connect] Successful connection Aug 28 05:22:47 volumio ntpd[25229]: Soliciting pool server 194.50.19.204 Aug 28 05:22:47 volumio volumio[25168]: info: Starting Udev Watcher for removable devices Aug 28 05:22:47 volumio volumio[25168]: info: Ignoring mount for partition: boot Aug 28 05:22:47 volumio volumio[25168]: info: Ignoring mount for partition: volumio Aug 28 05:22:47 volumio volumio[25168]: info: Ignoring mount for partition: volumio_data Aug 28 05:22:47 volumio volumio[25168]: info: Mounting Device INTENSO Aug 28 05:22:47 volumio sudo[25258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Aug 28 05:22:47 volumio sudo[25258]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:47 volumio sudo[25258]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:47 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:47 volumio volumio[25168]: info: Loading plugin "alarm-clock"... Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "airplay_emulation"... Aug 28 05:22:48 volumio volumio[25168]: info: Starting Shairport Sync Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "last_100"... Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "webradio"... Aug 28 05:22:48 volumio ntpd[25229]: Soliciting pool server 178.215.228.24 Aug 28 05:22:48 volumio ntpd[25229]: Soliciting pool server 194.36.144.87 Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "i2s_dacs"... Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "volumiodiscovery"... Aug 28 05:22:48 volumio volumio[25168]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:48 volumio node[25168]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:48 volumio volumio[25168]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:48 volumio node[25168]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:48 volumio volumio[25168]: *** WARNING *** For more information see Aug 28 05:22:48 volumio node[25168]: *** WARNING *** For more information see Aug 28 05:22:48 volumio volumio[25168]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:48 volumio node[25168]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 05:22:48 volumio volumio[25168]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:48 volumio node[25168]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 05:22:48 volumio volumio[25168]: *** WARNING *** For more information see Aug 28 05:22:48 volumio node[25168]: *** WARNING *** For more information see Aug 28 05:22:48 volumio volumio[25168]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 05:22:48 volumio volumio[25168]: info: Discovery: Started advertising with name: undefined Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "outputs"... Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "albumart"... Aug 28 05:22:48 volumio volumio[25168]: info: Plugin example_plugin is not enabled Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "inputs"... Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "updater_comm"... Aug 28 05:22:48 volumio mpd[25252]: Aug 28 05:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:22:48 volumio mpd[25252]: Aug 28 05:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:22:48 volumio mpd[25252]: Aug 28 05:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:22:48 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:22:48 volumio sudo[25247]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:48 volumio volumio[25168]: info: Plugin mpdemulation is not enabled Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "rest_api"... Aug 28 05:22:48 volumio volumio[25168]: info: Loading plugin "websocket"... Aug 28 05:22:48 volumio volumio[25168]: info: Loading i18n strings for locale de Aug 28 05:22:48 volumio volumio[25168]: Updating browse sources language Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:48 volumio volumio[25168]: Forking 3 albumart workers Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::initPlayerControls Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:48 volumio volumio[25168]: Express server listening on port 3000 Aug 28 05:22:48 volumio volumio[25168]: [Metrics] WebUI: 5s 74.44ms Aug 28 05:22:49 volumio volumio[25168]: info: CoreStateMachine::resetVolumioState Aug 28 05:22:49 volumio volumio[25168]: info: CoreStateMachine::getcurrentVolume Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:22:49 volumio volumio[25168]: info: MPD Permissions set Aug 28 05:22:49 volumio volumio-remote-updater[472]: [2024-08-28 05:22:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724815367 101 Aug 28 05:22:49 volumio volumio[25168]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 28 05:22:49 volumio volumio[25168]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:22:49 volumio ntpd[25229]: Soliciting pool server 217.160.214.61 Aug 28 05:22:49 volumio ntpd[25229]: Soliciting pool server 62.75.236.38 Aug 28 05:22:49 volumio ntpd[25229]: Soliciting pool server 167.71.55.144 Aug 28 05:22:49 volumio volumio[25168]: info: MPD running with PID25252 Aug 28 05:22:49 volumio volumio[25168]: ,establishing connection Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: Cannot read play queue from file Aug 28 05:22:49 volumio volumio[25168]: error: updateQueue error: null Aug 28 05:22:49 volumio volumio[25168]: info: Setting Device type: Raspberry PI Aug 28 05:22:49 volumio volumio[25168]: info: CoreStateMachine::setRepeat false single undefined Aug 28 05:22:49 volumio volumio[25168]: info: CoreStateMachine::pushState Aug 28 05:22:49 volumio volumio[25168]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:49 volumio wpa_supplicant[813]: RRM: Ignoring radio measurement request: Not RRM network Aug 28 05:22:49 volumio volumio[25168]: info: CoreStateMachine::setRandom null Aug 28 05:22:49 volumio volumio[25168]: info: CoreStateMachine::pushState Aug 28 05:22:49 volumio volumio[25168]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:49 volumio volumio[25168]: info: Completed loading Core Plugins Aug 28 05:22:49 volumio volumio[25168]: info: Preparing to generate the ALSA configuration file Aug 28 05:22:49 volumio volumio[25168]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:22:49 volumio volumio[25168]: info: CoreStateMachine::pushState Aug 28 05:22:49 volumio volumio[25168]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:49 volumio volumio[25168]: info: CoreStateMachine::updateTrackBlock Aug 28 05:22:49 volumio volumio[25168]: info: CorePlayQueue::getTrackBlock Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 05:22:49 volumio volumio[25168]: error: updateQueue error: null Aug 28 05:22:49 volumio volumio[25168]: info: Asound.conf file unchanged, so no further update is needed Aug 28 05:22:49 volumio volumio[25168]: info: Output device has changed, restarting MPD Aug 28 05:22:49 volumio volumio[25168]: info: Output device has changed, restarting Shairport Sync Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:49 volumio sudo[25312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 05:22:49 volumio sudo[25312]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:49 volumio sudo[25312]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:49 volumio sudo[25237]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:49 volumio sudo[25313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 05:22:49 volumio sudo[25313]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:49 volumio volumio[25168]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:49 volumio volumio[25168]: info: ___________ START PLUGINS ___________ Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:49 volumio volumio[25168]: info: [1724815369692] CoreMusicLibrary::Adding element Media Servers Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:49 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 05:22:49 volumio systemd[1]: mpd.service: Succeeded. Aug 28 05:22:49 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:49 volumio systemd[1]: Starting Music Player Daemon... Aug 28 05:22:49 volumio volumio[25168]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:49 volumio volumio[25168]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:49 volumio volumio[25168]: info: [1724815369903] CoreMusicLibrary::Adding element Last_100 Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 05:22:49 volumio volumio[25168]: info: [1724815369908] CoreMusicLibrary::Adding element Webradio Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 05:22:49 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 05:22:49 volumio volumio[25168]: info: Volumio Calling Home Aug 28 05:22:50 volumio volumio[25168]: Starting albumart workers Aug 28 05:22:50 volumio volumio[25168]: info: Completed starting Core Plugins Aug 28 05:22:50 volumio volumio[25168]: info: ------------------------------------------- Aug 28 05:22:50 volumio volumio[25168]: info: ----- MyVolumio plugins startup ---- Aug 28 05:22:50 volumio volumio[25168]: info: ------------------------------------------- Aug 28 05:22:50 volumio volumio[25168]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 05:22:50 volumio volumio[25168]: Starting albumart workers Aug 28 05:22:50 volumio volumio[25168]: Starting albumart workers Aug 28 05:22:50 volumio ntpd[25229]: Soliciting pool server 49.13.14.46 Aug 28 05:22:50 volumio ntpd[25229]: Soliciting pool server 49.13.14.46 Aug 28 05:22:50 volumio ntpd[25229]: Soliciting pool server 46.4.54.78 Aug 28 05:22:50 volumio ntpd[25229]: Soliciting pool server 81.3.27.46 Aug 28 05:22:50 volumio volumio[25168]: info: MPD Permissions set Aug 28 05:22:50 volumio volumio[25168]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Aug 28 05:22:50 volumio volumio[25168]: info: Volumio called home Aug 28 05:22:50 volumio volumio[25168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 05:22:50 volumio volumio[25168]: info: VolumeController:: Volume=undefined Mute =false Aug 28 05:22:50 volumio volumio[25168]: info: CoreStateMachine::pushState Aug 28 05:22:50 volumio volumio[25168]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::volumioPushState Aug 28 05:22:50 volumio volumio[25168]: info: Starting Shairport Sync Aug 28 05:22:50 volumio volumio[25168]: info: Starting Shairport Sync Aug 28 05:22:50 volumio volumio[25168]: info: Starting Shairport Sync Aug 28 05:22:50 volumio sudo[25338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:50 volumio sudo[25338]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:50 volumio sudo[25340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:22:50 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:22:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:50 volumio sudo[25340]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:50 volumio sudo[25342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 05:22:50 volumio sudo[25342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 05:22:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:50 volumio sudo[25338]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 28 05:22:50 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 28 05:22:50 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 28 05:22:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 28 05:22:50 volumio sudo[25340]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:50 volumio sudo[25342]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:50 volumio volumio[25168]: info: Shairport-Sync Started Aug 28 05:22:50 volumio volumio[25168]: Error adding Membership: Error: addMembership EINVAL Aug 28 05:22:50 volumio volumio[25168]: info: Shairport-Sync Started Aug 28 05:22:50 volumio volumio[25168]: info: Shairport-Sync Started Aug 28 05:22:50 volumio volumio[25168]: info: CoreCommandRouter::volumioGetState Aug 28 05:22:50 volumio volumio[25168]: info: CorePlayQueue::getTrack 0 Aug 28 05:22:51 volumio ntpd[25229]: Soliciting pool server 81.169.204.195 Aug 28 05:22:51 volumio ntpd[25229]: Soliciting pool server 116.202.14.29 Aug 28 05:22:51 volumio mpd[25330]: Aug 28 05:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 05:22:51 volumio mpd[25330]: Aug 28 05:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 28 05:22:51 volumio mpd[25330]: Aug 28 05:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 28 05:22:51 volumio systemd[1]: Started Music Player Daemon. Aug 28 05:22:51 volumio sudo[25313]: pam_unix(sudo:session): session closed for user root Aug 28 05:22:51 volumio volumio[25168]: error: updateQueue error: null Aug 28 05:22:52 volumio ntpd[25229]: Soliciting pool server 51.75.67.47 Aug 28 05:22:52 volumio ntpd[25229]: Soliciting pool server 85.215.166.214 Aug 28 05:22:53 volumio ntpd[25229]: Soliciting pool server 89.58.51.72 Aug 28 05:22:53 volumio ntpd[25229]: Soliciting pool server 2a01:4f9:c012:5e8a::1 Aug 28 05:22:53 volumio volumio[25168]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Aug 28 05:22:53 volumio volumio[25168]: info: Discovery: Started advertising with name: undefined Aug 28 05:22:56 volumio volumio[25168]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:22:56 volumio volumio[25168]: TypeError: Cannot read property 'charAt' of undefined Aug 28 05:22:56 volumio volumio[25168]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Aug 28 05:22:56 volumio volumio[25168]: at listOnTimeout (internal/timers.js:554:17) Aug 28 05:22:56 volumio volumio[25168]: at processTimers (internal/timers.js:497:7) Aug 28 05:22:56 volumio volumio[25168]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 05:22:57 volumio sudo[25384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 05:21 Aug 28 05:22:57 volumio sudo[25384]: 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"