-- Logs begin at Thu 2024-04-11 05:30:11 CEST, end at Thu 2024-04-11 06:02:34 CEST. -- Apr 11 06:01:01 volumio volumio[14888]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 11 06:01:01 volumio volumio[14888]: info: Discovery: Started advertising with name: undefined Apr 11 06:01:04 volumio volumio[14888]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:01:04 volumio volumio[14888]: TypeError: Cannot read property 'charAt' of undefined Apr 11 06:01:04 volumio volumio[14888]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 11 06:01:04 volumio volumio[14888]: at listOnTimeout (internal/timers.js:554:17) Apr 11 06:01:04 volumio volumio[14888]: at processTimers (internal/timers.js:497:7) Apr 11 06:01:04 volumio volumio[14888]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:01:04 volumio sudo[15066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 06:00 Apr 11 06:01:04 volumio sudo[15066]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:04 volumio sudo[15066]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:05 volumio volumio-remote-updater[543]: [2024-04-11 06:01:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 06:01:05 volumio volumio-remote-updater[543]: [2024-04-11 06:01:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 06:01:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 06:01:05 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 11 06:01:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 06:01:05 volumio systemd[1]: Started dynamicswap service. Apr 11 06:01:05 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:01:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 11 06:01:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 335. Apr 11 06:01:05 volumio systemd[1]: Started dynamicswap service. Apr 11 06:01:05 volumio systemd[1]: Stopped Volumio Backend Module. Apr 11 06:01:05 volumio systemd[1]: Started Volumio Backend Module. Apr 11 06:01:05 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:01:07 volumio volumio[15081]: info: ------------------------------------------- Apr 11 06:01:07 volumio volumio[15081]: info: ----- Volumio3 ---- Apr 11 06:01:07 volumio volumio[15081]: info: ------------------------------------------- Apr 11 06:01:07 volumio volumio[15081]: info: ----- System startup ---- Apr 11 06:01:07 volumio volumio[15081]: info: ------------------------------------------- Apr 11 06:01:07 volumio volumio[15081]: info: MYVOLUMIO Environment detected Apr 11 06:01:07 volumio volumio[15081]: info: Plugin folders cleanup Apr 11 06:01:07 volumio volumio[15081]: info: Scanning into folder /volumio/app/plugins/ Apr 11 06:01:07 volumio volumio[15081]: info: Scanning category audio_interface Apr 11 06:01:07 volumio volumio[15081]: info: Scanning category miscellanea Apr 11 06:01:07 volumio volumio[15081]: info: Scanning category music_service Apr 11 06:01:07 volumio volumio[15081]: info: Scanning category plugins.json Apr 11 06:01:07 volumio volumio[15081]: info: Scanning category system_controller Apr 11 06:01:07 volumio volumio[15081]: info: Scanning category user_interface Apr 11 06:01:07 volumio volumio[15081]: info: Scanning into folder /data/plugins/ Apr 11 06:01:07 volumio volumio[15081]: info: Plugin folders cleanup completed Apr 11 06:01:07 volumio volumio[15081]: info: ------------------------------------------- Apr 11 06:01:07 volumio volumio[15081]: info: ----- Core plugins startup ---- Apr 11 06:01:07 volumio volumio[15081]: info: ------------------------------------------- Apr 11 06:01:07 volumio volumio[15081]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 06:01:07 volumio volumio[15081]: info: Adding plugin upnp to MyMusic Plugins Apr 11 06:01:07 volumio volumio[15081]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 06:01:07 volumio volumio[15081]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 06:01:07 volumio volumio[15081]: info: Loading plugins from folder /data/plugins/ Apr 11 06:01:07 volumio volumio[15081]: info: Loading plugin "system"... Apr 11 06:01:07 volumio volumio[15081]: info: Loading plugin "appearance"... Apr 11 06:01:08 volumio volumio[15081]: info: Loading plugin "network"... Apr 11 06:01:08 volumio volumio[15081]: info: Refreshing Cached IP Addresses Apr 11 06:01:08 volumio sudo[15105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 06:01:08 volumio sudo[15105]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:08 volumio sudo[15105]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:08 volumio volumio[15081]: info: Loading plugin "services"... Apr 11 06:01:08 volumio sudo[15107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 06:01:09 volumio sudo[15107]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:09 volumio volumio[15081]: info: Loading plugin "alsa_controller"... Apr 11 06:01:09 volumio sudo[15107]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:09 volumio sudo[15110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 06:01:09 volumio sudo[15110]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:09 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:01:09 volumio volumio[15081]: info: Loading plugin "wizard"... Apr 11 06:01:09 volumio volumio[15081]: info: Loading plugin "volumio_command_line_client"... Apr 11 06:01:09 volumio volumio[15081]: info: Loading plugin "upnp"... Apr 11 06:01:09 volumio volumio[15081]: info: [1712808069134] Starting Upmpd Daemon Apr 11 06:01:09 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:09 volumio volumio[15081]: info: Loading plugin "my_music"... Apr 11 06:01:09 volumio volumio[15081]: info: Loading plugin "mpd"... Apr 11 06:01:09 volumio volumio[15081]: info: Creating MPD Configuration file Apr 11 06:01:09 volumio volumio[15081]: info: Loading plugin "upnp_browser"... Apr 11 06:01:09 volumio sudo[15118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:01:09 volumio sudo[15118]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:09 volumio sudo[15118]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:09 volumio sudo[15120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:01:09 volumio sudo[15120]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:09 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:01:09 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:01:09 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:01:09 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:01:09 volumio volumio[15081]: info: Loading plugin "networkfs"... Apr 11 06:01:10 volumio volumio-remote-updater[543]: [2024-04-11 06:01:10] [connect] Successful connection Apr 11 06:01:10 volumio volumio[15081]: info: Starting Udev Watcher for removable devices Apr 11 06:01:10 volumio volumio[15081]: info: Ignoring mount for partition: boot Apr 11 06:01:10 volumio volumio[15081]: info: Ignoring mount for partition: volumio Apr 11 06:01:10 volumio volumio[15081]: info: Ignoring mount for partition: volumio_data Apr 11 06:01:10 volumio volumio[15081]: info: Mounting Device INTENSO Apr 11 06:01:10 volumio sudo[15131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 11 06:01:10 volumio sudo[15131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:10 volumio sudo[15131]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:10 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:10 volumio volumio[15081]: info: Loading plugin "alarm-clock"... Apr 11 06:01:10 volumio volumio[15081]: info: Loading plugin "airplay_emulation"... Apr 11 06:01:10 volumio volumio[15081]: info: Starting Shairport Sync Apr 11 06:01:10 volumio volumio[15081]: info: Loading plugin "last_100"... Apr 11 06:01:10 volumio volumio[15081]: info: Loading plugin "webradio"... Apr 11 06:01:10 volumio volumio[15081]: info: Loading plugin "i2s_dacs"... Apr 11 06:01:10 volumio volumio[15081]: info: Loading plugin "volumiodiscovery"... Apr 11 06:01:10 volumio volumio[15081]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:10 volumio node[15081]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:10 volumio volumio[15081]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:10 volumio node[15081]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:10 volumio volumio[15081]: *** WARNING *** For more information see Apr 11 06:01:10 volumio node[15081]: *** WARNING *** For more information see Apr 11 06:01:10 volumio volumio[15081]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:10 volumio node[15081]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:10 volumio volumio[15081]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:10 volumio node[15081]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:10 volumio volumio[15081]: *** WARNING *** For more information see Apr 11 06:01:10 volumio node[15081]: *** WARNING *** For more information see Apr 11 06:01:10 volumio volumio[15081]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 06:01:10 volumio volumio[15081]: info: Discovery: Started advertising with name: undefined Apr 11 06:01:10 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:10 volumio volumio[15081]: info: Loading plugin "outputs"... Apr 11 06:01:11 volumio volumio[15081]: info: Loading plugin "albumart"... Apr 11 06:01:11 volumio volumio[15081]: info: Plugin example_plugin is not enabled Apr 11 06:01:11 volumio volumio[15081]: info: Loading plugin "inputs"... Apr 11 06:01:11 volumio volumio[15081]: info: Loading plugin "updater_comm"... Apr 11 06:01:11 volumio mpd[15125]: Apr 11 06:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:01:11 volumio volumio[15081]: info: Plugin mpdemulation is not enabled Apr 11 06:01:11 volumio volumio[15081]: info: Loading plugin "rest_api"... Apr 11 06:01:11 volumio volumio[15081]: info: Loading plugin "websocket"... Apr 11 06:01:11 volumio mpd[15125]: Apr 11 06:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:01:11 volumio mpd[15125]: Apr 11 06:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:01:11 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:01:11 volumio sudo[15120]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:11 volumio volumio[15081]: info: Loading i18n strings for locale de Apr 11 06:01:11 volumio volumio[15081]: Updating browse sources language Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:11 volumio volumio[15081]: Forking 3 albumart workers Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::initPlayerControls Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: Express server listening on port 3000 Apr 11 06:01:11 volumio volumio[15081]: [Metrics] WebUI: 5s 19.46ms Apr 11 06:01:11 volumio volumio[15081]: info: CoreStateMachine::resetVolumioState Apr 11 06:01:11 volumio volumio[15081]: info: CoreStateMachine::getcurrentVolume Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:01:11 volumio volumio[15081]: info: MPD Permissions set Apr 11 06:01:11 volumio volumio-remote-updater[543]: [2024-04-11 06:01:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712808070 101 Apr 11 06:01:11 volumio volumio[15081]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 11 06:01:11 volumio volumio[15081]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:01:11 volumio volumio[15081]: info: MPD running with PID15125 Apr 11 06:01:11 volumio volumio[15081]: ,establishing connection Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:11 volumio volumio[15081]: info: Cannot read play queue from file Apr 11 06:01:11 volumio volumio[15081]: error: updateQueue error: null Apr 11 06:01:12 volumio volumio[15081]: info: CoreStateMachine::setRepeat false single undefined Apr 11 06:01:12 volumio volumio[15081]: info: CoreStateMachine::pushState Apr 11 06:01:12 volumio volumio[15081]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:12 volumio volumio[15081]: info: CoreStateMachine::setRandom null Apr 11 06:01:12 volumio volumio[15081]: info: CoreStateMachine::pushState Apr 11 06:01:12 volumio volumio[15081]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:12 volumio volumio[15081]: info: Setting Device type: Raspberry PI Apr 11 06:01:12 volumio volumio[15081]: info: Completed loading Core Plugins Apr 11 06:01:12 volumio volumio[15081]: info: Preparing to generate the ALSA configuration file Apr 11 06:01:12 volumio volumio[15081]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:01:12 volumio volumio[15081]: info: CoreStateMachine::pushState Apr 11 06:01:12 volumio volumio[15081]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:12 volumio volumio[15081]: info: CoreStateMachine::updateTrackBlock Apr 11 06:01:12 volumio volumio[15081]: info: CorePlayQueue::getTrackBlock Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:01:12 volumio volumio[15081]: error: updateQueue error: null Apr 11 06:01:12 volumio volumio[15081]: info: Asound.conf file unchanged, so no further update is needed Apr 11 06:01:12 volumio volumio[15081]: info: Output device has changed, restarting MPD Apr 11 06:01:12 volumio volumio[15081]: info: Output device has changed, restarting Shairport Sync Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:12 volumio sudo[15185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:01:12 volumio sudo[15185]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:12 volumio sudo[15185]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:12 volumio sudo[15187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:01:12 volumio sudo[15187]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:12 volumio sudo[15110]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:12 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:01:12 volumio volumio[15081]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:12 volumio volumio[15081]: info: ___________ START PLUGINS ___________ Apr 11 06:01:12 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:01:12 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:12 volumio volumio[15081]: info: [1712808072360] CoreMusicLibrary::Adding element Media Servers Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:12 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:01:12 volumio volumio[15081]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:12 volumio volumio[15081]: info: [1712808072547] CoreMusicLibrary::Adding element Last_100 Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:12 volumio volumio[15081]: info: [1712808072557] CoreMusicLibrary::Adding element Webradio Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:01:12 volumio volumio[15081]: info: Volumio Calling Home Apr 11 06:01:12 volumio volumio[15081]: Starting albumart workers Apr 11 06:01:12 volumio volumio[15081]: Starting albumart workers Apr 11 06:01:12 volumio volumio[15081]: Starting albumart workers Apr 11 06:01:12 volumio volumio[15081]: info: Completed starting Core Plugins Apr 11 06:01:12 volumio volumio[15081]: info: ------------------------------------------- Apr 11 06:01:12 volumio volumio[15081]: info: ----- MyVolumio plugins startup ---- Apr 11 06:01:12 volumio volumio[15081]: info: ------------------------------------------- Apr 11 06:01:12 volumio volumio[15081]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 06:01:12 volumio volumio[15081]: info: MPD Permissions set Apr 11 06:01:12 volumio volumio[15081]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:01:12 volumio volumio[15081]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 11 06:01:12 volumio volumio[15081]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:01:12 volumio volumio[15081]: info: CoreStateMachine::pushState Apr 11 06:01:12 volumio volumio[15081]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:12 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:13 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:13 volumio volumio[15081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:13 volumio volumio[15081]: info: Starting Shairport Sync Apr 11 06:01:13 volumio volumio[15081]: info: Starting Shairport Sync Apr 11 06:01:13 volumio volumio[15081]: info: Starting Shairport Sync Apr 11 06:01:13 volumio sudo[15211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:13 volumio sudo[15211]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:13 volumio sudo[15213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:13 volumio sudo[15213]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:13 volumio sudo[15214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:13 volumio sudo[15214]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:01:13 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:01:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:13 volumio sudo[15213]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:13 volumio sudo[15211]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:01:13 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 11 06:01:13 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:01:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:13 volumio volumio[15081]: info: CoreCommandRouter::volumioGetState Apr 11 06:01:13 volumio volumio[15081]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:13 volumio sudo[15214]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:13 volumio volumio[15081]: info: Shairport-Sync Started Apr 11 06:01:13 volumio volumio[15081]: Error adding Membership: Error: addMembership EINVAL Apr 11 06:01:13 volumio volumio[15081]: info: Shairport-Sync Started Apr 11 06:01:13 volumio volumio[15081]: info: Shairport-Sync Started Apr 11 06:01:14 volumio mpd[15203]: Apr 11 06:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:01:14 volumio mpd[15203]: Apr 11 06:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:01:14 volumio mpd[15203]: Apr 11 06:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:01:14 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:01:14 volumio sudo[15187]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:14 volumio volumio[15081]: error: updateQueue error: null Apr 11 06:01:15 volumio volumio[15081]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 11 06:01:15 volumio volumio[15081]: info: Discovery: Started advertising with name: undefined Apr 11 06:01:19 volumio volumio[15081]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:01:19 volumio volumio[15081]: TypeError: Cannot read property 'charAt' of undefined Apr 11 06:01:19 volumio volumio[15081]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 11 06:01:19 volumio volumio[15081]: at listOnTimeout (internal/timers.js:554:17) Apr 11 06:01:19 volumio volumio[15081]: at processTimers (internal/timers.js:497:7) Apr 11 06:01:19 volumio volumio[15081]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:01:19 volumio sudo[15257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 06:00 Apr 11 06:01:19 volumio sudo[15257]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:19 volumio sudo[15257]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:19 volumio volumio-remote-updater[543]: [2024-04-11 06:01:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 06:01:19 volumio volumio-remote-updater[543]: [2024-04-11 06:01:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 06:01:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 06:01:19 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 11 06:01:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 06:01:20 volumio systemd[1]: Started dynamicswap service. Apr 11 06:01:20 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:01:20 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 11 06:01:20 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 336. Apr 11 06:01:20 volumio systemd[1]: Stopped Volumio Backend Module. Apr 11 06:01:20 volumio systemd[1]: Started Volumio Backend Module. Apr 11 06:01:20 volumio systemd[1]: Started dynamicswap service. Apr 11 06:01:20 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:01:22 volumio volumio[15271]: info: ------------------------------------------- Apr 11 06:01:22 volumio volumio[15271]: info: ----- Volumio3 ---- Apr 11 06:01:22 volumio volumio[15271]: info: ------------------------------------------- Apr 11 06:01:22 volumio volumio[15271]: info: ----- System startup ---- Apr 11 06:01:22 volumio volumio[15271]: info: ------------------------------------------- Apr 11 06:01:22 volumio volumio[15271]: info: MYVOLUMIO Environment detected Apr 11 06:01:22 volumio volumio[15271]: info: Plugin folders cleanup Apr 11 06:01:22 volumio volumio[15271]: info: Scanning into folder /volumio/app/plugins/ Apr 11 06:01:22 volumio volumio[15271]: info: Scanning category audio_interface Apr 11 06:01:22 volumio volumio[15271]: info: Scanning category miscellanea Apr 11 06:01:22 volumio volumio[15271]: info: Scanning category music_service Apr 11 06:01:22 volumio volumio[15271]: info: Scanning category plugins.json Apr 11 06:01:22 volumio volumio[15271]: info: Scanning category system_controller Apr 11 06:01:22 volumio volumio[15271]: info: Scanning category user_interface Apr 11 06:01:22 volumio volumio[15271]: info: Scanning into folder /data/plugins/ Apr 11 06:01:22 volumio volumio[15271]: info: Plugin folders cleanup completed Apr 11 06:01:22 volumio volumio[15271]: info: ------------------------------------------- Apr 11 06:01:22 volumio volumio[15271]: info: ----- Core plugins startup ---- Apr 11 06:01:22 volumio volumio[15271]: info: ------------------------------------------- Apr 11 06:01:22 volumio volumio[15271]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 06:01:22 volumio volumio[15271]: info: Adding plugin upnp to MyMusic Plugins Apr 11 06:01:22 volumio volumio[15271]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 06:01:22 volumio volumio[15271]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 06:01:22 volumio volumio[15271]: info: Loading plugins from folder /data/plugins/ Apr 11 06:01:22 volumio volumio[15271]: info: Loading plugin "system"... Apr 11 06:01:22 volumio volumio[15271]: info: Loading plugin "appearance"... Apr 11 06:01:23 volumio volumio[15271]: info: Loading plugin "network"... Apr 11 06:01:23 volumio volumio[15271]: info: Refreshing Cached IP Addresses Apr 11 06:01:24 volumio sudo[15295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 06:01:24 volumio sudo[15295]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:24 volumio sudo[15295]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:24 volumio sudo[15297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 06:01:24 volumio sudo[15297]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "services"... Apr 11 06:01:24 volumio sudo[15297]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "alsa_controller"... Apr 11 06:01:24 volumio sudo[15300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 06:01:24 volumio sudo[15300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:24 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "wizard"... Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "volumio_command_line_client"... Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "upnp"... Apr 11 06:01:24 volumio volumio[15271]: info: [1712808084181] Starting Upmpd Daemon Apr 11 06:01:24 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "my_music"... Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "mpd"... Apr 11 06:01:24 volumio volumio[15271]: info: Creating MPD Configuration file Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "upnp_browser"... Apr 11 06:01:24 volumio sudo[15308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:01:24 volumio sudo[15308]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:24 volumio sudo[15308]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:24 volumio sudo[15310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:01:24 volumio sudo[15310]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:24 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:01:24 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:01:24 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:01:24 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:01:24 volumio volumio[15271]: info: Loading plugin "networkfs"... Apr 11 06:01:24 volumio volumio-remote-updater[543]: [2024-04-11 06:01:24] [connect] Successful connection Apr 11 06:01:25 volumio volumio[15271]: info: Starting Udev Watcher for removable devices Apr 11 06:01:25 volumio volumio[15271]: info: Ignoring mount for partition: boot Apr 11 06:01:25 volumio volumio[15271]: info: Ignoring mount for partition: volumio Apr 11 06:01:25 volumio volumio[15271]: info: Ignoring mount for partition: volumio_data Apr 11 06:01:25 volumio volumio[15271]: info: Mounting Device INTENSO Apr 11 06:01:25 volumio sudo[15321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 11 06:01:25 volumio sudo[15321]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:25 volumio sudo[15321]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:25 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:25 volumio volumio[15271]: info: Loading plugin "alarm-clock"... Apr 11 06:01:25 volumio volumio[15271]: info: Loading plugin "airplay_emulation"... Apr 11 06:01:25 volumio volumio[15271]: info: Starting Shairport Sync Apr 11 06:01:25 volumio volumio[15271]: info: Loading plugin "last_100"... Apr 11 06:01:25 volumio volumio[15271]: info: Loading plugin "webradio"... Apr 11 06:01:25 volumio volumio[15271]: info: Loading plugin "i2s_dacs"... Apr 11 06:01:25 volumio volumio[15271]: info: Loading plugin "volumiodiscovery"... Apr 11 06:01:26 volumio volumio[15271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:26 volumio node[15271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:26 volumio volumio[15271]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:26 volumio node[15271]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:26 volumio volumio[15271]: *** WARNING *** For more information see Apr 11 06:01:26 volumio node[15271]: *** WARNING *** For more information see Apr 11 06:01:26 volumio volumio[15271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:26 volumio node[15271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:26 volumio volumio[15271]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:26 volumio node[15271]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:26 volumio volumio[15271]: *** WARNING *** For more information see Apr 11 06:01:26 volumio node[15271]: *** WARNING *** For more information see Apr 11 06:01:26 volumio volumio[15271]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 06:01:26 volumio volumio[15271]: info: Discovery: Started advertising with name: undefined Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:26 volumio volumio[15271]: info: Loading plugin "outputs"... Apr 11 06:01:26 volumio volumio[15271]: info: Loading plugin "albumart"... Apr 11 06:01:26 volumio volumio[15271]: info: Plugin example_plugin is not enabled Apr 11 06:01:26 volumio volumio[15271]: info: Loading plugin "inputs"... Apr 11 06:01:26 volumio volumio[15271]: info: Loading plugin "updater_comm"... Apr 11 06:01:26 volumio mpd[15315]: Apr 11 06:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:01:26 volumio mpd[15315]: Apr 11 06:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:01:26 volumio mpd[15315]: Apr 11 06:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:01:26 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:01:26 volumio sudo[15310]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:26 volumio volumio[15271]: info: Plugin mpdemulation is not enabled Apr 11 06:01:26 volumio volumio[15271]: info: Loading plugin "rest_api"... Apr 11 06:01:26 volumio volumio[15271]: info: Loading plugin "websocket"... Apr 11 06:01:26 volumio volumio[15271]: info: Loading i18n strings for locale de Apr 11 06:01:26 volumio volumio[15271]: Updating browse sources language Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:26 volumio volumio[15271]: Forking 3 albumart workers Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::initPlayerControls Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: Express server listening on port 3000 Apr 11 06:01:26 volumio volumio[15271]: [Metrics] WebUI: 5s 38.49ms Apr 11 06:01:26 volumio volumio[15271]: info: CoreStateMachine::resetVolumioState Apr 11 06:01:26 volumio volumio[15271]: info: CoreStateMachine::getcurrentVolume Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:01:26 volumio volumio[15271]: info: MPD Permissions set Apr 11 06:01:26 volumio volumio-remote-updater[543]: [2024-04-11 06:01:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712808084 101 Apr 11 06:01:26 volumio volumio[15271]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 11 06:01:26 volumio volumio[15271]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:01:26 volumio volumio[15271]: info: MPD running with PID15315 Apr 11 06:01:26 volumio volumio[15271]: ,establishing connection Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:26 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: info: Cannot read play queue from file Apr 11 06:01:27 volumio volumio[15271]: error: updateQueue error: null Apr 11 06:01:27 volumio volumio[15271]: info: CoreStateMachine::setRepeat false single undefined Apr 11 06:01:27 volumio volumio[15271]: info: CoreStateMachine::pushState Apr 11 06:01:27 volumio volumio[15271]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:27 volumio volumio[15271]: info: CoreStateMachine::setRandom null Apr 11 06:01:27 volumio volumio[15271]: info: CoreStateMachine::pushState Apr 11 06:01:27 volumio volumio[15271]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:27 volumio volumio[15271]: info: Setting Device type: Raspberry PI Apr 11 06:01:27 volumio volumio[15271]: info: Completed loading Core Plugins Apr 11 06:01:27 volumio volumio[15271]: info: Preparing to generate the ALSA configuration file Apr 11 06:01:27 volumio volumio[15271]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:01:27 volumio volumio[15271]: info: CoreStateMachine::pushState Apr 11 06:01:27 volumio volumio[15271]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:27 volumio volumio[15271]: info: CoreStateMachine::updateTrackBlock Apr 11 06:01:27 volumio volumio[15271]: info: CorePlayQueue::getTrackBlock Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:01:27 volumio volumio[15271]: error: updateQueue error: null Apr 11 06:01:27 volumio volumio[15271]: info: Asound.conf file unchanged, so no further update is needed Apr 11 06:01:27 volumio volumio[15271]: info: Output device has changed, restarting MPD Apr 11 06:01:27 volumio volumio[15271]: info: Output device has changed, restarting Shairport Sync Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:27 volumio sudo[15375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:01:27 volumio sudo[15375]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:27 volumio sudo[15377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:01:27 volumio sudo[15377]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:27 volumio sudo[15300]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:27 volumio sudo[15375]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:27 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:01:27 volumio volumio[15271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:27 volumio volumio[15271]: info: ___________ START PLUGINS ___________ Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:27 volumio volumio[15271]: info: [1712808087366] CoreMusicLibrary::Adding element Media Servers Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:27 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:01:27 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:27 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:01:27 volumio volumio[15271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:27 volumio volumio[15271]: Starting albumart workers Apr 11 06:01:27 volumio volumio[15271]: Starting albumart workers Apr 11 06:01:27 volumio volumio[15271]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:27 volumio volumio[15271]: info: [1712808087651] CoreMusicLibrary::Adding element Last_100 Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:27 volumio volumio[15271]: info: [1712808087656] CoreMusicLibrary::Adding element Webradio Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:27 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:01:27 volumio volumio[15271]: Starting albumart workers Apr 11 06:01:27 volumio volumio[15271]: info: Volumio Calling Home Apr 11 06:01:27 volumio volumio[15271]: info: Completed starting Core Plugins Apr 11 06:01:28 volumio volumio[15271]: info: ------------------------------------------- Apr 11 06:01:28 volumio volumio[15271]: info: ----- MyVolumio plugins startup ---- Apr 11 06:01:28 volumio volumio[15271]: info: ------------------------------------------- Apr 11 06:01:28 volumio volumio[15271]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 06:01:28 volumio volumio[15271]: info: MPD Permissions set Apr 11 06:01:28 volumio volumio[15271]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:01:28 volumio volumio[15271]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:28 volumio volumio[15271]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:01:28 volumio volumio[15271]: info: CoreStateMachine::pushState Apr 11 06:01:28 volumio volumio[15271]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:28 volumio volumio[15271]: info: Starting Shairport Sync Apr 11 06:01:28 volumio volumio[15271]: info: Starting Shairport Sync Apr 11 06:01:28 volumio sudo[15417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:28 volumio sudo[15417]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:28 volumio volumio[15271]: info: Starting Shairport Sync Apr 11 06:01:28 volumio sudo[15426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:28 volumio sudo[15426]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:01:28 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:01:28 volumio sudo[15429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:28 volumio sudo[15429]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:28 volumio volumio[15271]: info: CoreCommandRouter::volumioGetState Apr 11 06:01:28 volumio sudo[15417]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:28 volumio volumio[15271]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:28 volumio volumio[15271]: info: Shairport-Sync Started Apr 11 06:01:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:01:28 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:01:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:28 volumio volumio[15271]: Error adding Membership: Error: addMembership EINVAL Apr 11 06:01:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:28 volumio sudo[15426]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:28 volumio sudo[15429]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:28 volumio volumio[15271]: info: Shairport-Sync Started Apr 11 06:01:28 volumio volumio[15271]: info: Shairport-Sync Started Apr 11 06:01:29 volumio mpd[15392]: Apr 11 06:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:01:29 volumio mpd[15392]: Apr 11 06:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:01:29 volumio mpd[15392]: Apr 11 06:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:01:29 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:01:29 volumio sudo[15377]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:29 volumio volumio[15271]: error: updateQueue error: null Apr 11 06:01:31 volumio volumio[15271]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 11 06:01:31 volumio volumio[15271]: info: Discovery: Started advertising with name: undefined Apr 11 06:01:34 volumio volumio[15271]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:01:34 volumio volumio[15271]: TypeError: Cannot read property 'charAt' of undefined Apr 11 06:01:34 volumio volumio[15271]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 11 06:01:34 volumio volumio[15271]: at listOnTimeout (internal/timers.js:554:17) Apr 11 06:01:34 volumio volumio[15271]: at processTimers (internal/timers.js:497:7) Apr 11 06:01:34 volumio volumio[15271]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:01:34 volumio sudo[15450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 06:00 Apr 11 06:01:34 volumio sudo[15450]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:34 volumio sudo[15450]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:35 volumio volumio-remote-updater[543]: [2024-04-11 06:01:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 06:01:35 volumio volumio-remote-updater[543]: [2024-04-11 06:01:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 06:01:35 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 06:01:35 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 11 06:01:35 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 06:01:35 volumio systemd[1]: Started dynamicswap service. Apr 11 06:01:35 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:01:35 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 11 06:01:35 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 337. Apr 11 06:01:35 volumio systemd[1]: Stopped Volumio Backend Module. Apr 11 06:01:35 volumio systemd[1]: Started Volumio Backend Module. Apr 11 06:01:35 volumio systemd[1]: Started dynamicswap service. Apr 11 06:01:35 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:01:37 volumio volumio[15464]: info: ------------------------------------------- Apr 11 06:01:37 volumio volumio[15464]: info: ----- Volumio3 ---- Apr 11 06:01:37 volumio volumio[15464]: info: ------------------------------------------- Apr 11 06:01:37 volumio volumio[15464]: info: ----- System startup ---- Apr 11 06:01:37 volumio volumio[15464]: info: ------------------------------------------- Apr 11 06:01:37 volumio volumio[15464]: info: MYVOLUMIO Environment detected Apr 11 06:01:37 volumio volumio[15464]: info: Plugin folders cleanup Apr 11 06:01:37 volumio volumio[15464]: info: Scanning into folder /volumio/app/plugins/ Apr 11 06:01:37 volumio volumio[15464]: info: Scanning category audio_interface Apr 11 06:01:37 volumio volumio[15464]: info: Scanning category miscellanea Apr 11 06:01:37 volumio volumio[15464]: info: Scanning category music_service Apr 11 06:01:37 volumio volumio[15464]: info: Scanning category plugins.json Apr 11 06:01:37 volumio volumio[15464]: info: Scanning category system_controller Apr 11 06:01:37 volumio volumio[15464]: info: Scanning category user_interface Apr 11 06:01:37 volumio volumio[15464]: info: Scanning into folder /data/plugins/ Apr 11 06:01:37 volumio volumio[15464]: info: Plugin folders cleanup completed Apr 11 06:01:37 volumio volumio[15464]: info: ------------------------------------------- Apr 11 06:01:37 volumio volumio[15464]: info: ----- Core plugins startup ---- Apr 11 06:01:37 volumio volumio[15464]: info: ------------------------------------------- Apr 11 06:01:37 volumio volumio[15464]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 06:01:37 volumio volumio[15464]: info: Adding plugin upnp to MyMusic Plugins Apr 11 06:01:37 volumio volumio[15464]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 06:01:37 volumio volumio[15464]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 06:01:37 volumio volumio[15464]: info: Loading plugins from folder /data/plugins/ Apr 11 06:01:37 volumio volumio[15464]: info: Loading plugin "system"... Apr 11 06:01:37 volumio volumio[15464]: info: Loading plugin "appearance"... Apr 11 06:01:38 volumio volumio[15464]: info: Loading plugin "network"... Apr 11 06:01:38 volumio volumio[15464]: info: Refreshing Cached IP Addresses Apr 11 06:01:39 volumio sudo[15488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 06:01:39 volumio sudo[15488]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:39 volumio sudo[15488]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:39 volumio sudo[15490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 06:01:39 volumio sudo[15490]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "services"... Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "alsa_controller"... Apr 11 06:01:39 volumio sudo[15490]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:39 volumio sudo[15493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 06:01:39 volumio sudo[15493]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:39 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "wizard"... Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "volumio_command_line_client"... Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "upnp"... Apr 11 06:01:39 volumio volumio[15464]: info: [1712808099179] Starting Upmpd Daemon Apr 11 06:01:39 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "my_music"... Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "mpd"... Apr 11 06:01:39 volumio volumio[15464]: info: Creating MPD Configuration file Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "upnp_browser"... Apr 11 06:01:39 volumio sudo[15501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:01:39 volumio sudo[15501]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:39 volumio sudo[15501]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:39 volumio sudo[15503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:01:39 volumio sudo[15503]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:39 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:01:39 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:01:39 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:01:39 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:01:39 volumio volumio[15464]: info: Loading plugin "networkfs"... Apr 11 06:01:40 volumio volumio-remote-updater[543]: [2024-04-11 06:01:40] [connect] Successful connection Apr 11 06:01:40 volumio volumio[15464]: info: Starting Udev Watcher for removable devices Apr 11 06:01:40 volumio volumio[15464]: info: Ignoring mount for partition: boot Apr 11 06:01:40 volumio volumio[15464]: info: Ignoring mount for partition: volumio Apr 11 06:01:40 volumio volumio[15464]: info: Ignoring mount for partition: volumio_data Apr 11 06:01:40 volumio volumio[15464]: info: Mounting Device INTENSO Apr 11 06:01:40 volumio sudo[15514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 11 06:01:40 volumio sudo[15514]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:40 volumio sudo[15514]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:40 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:40 volumio volumio[15464]: info: Loading plugin "alarm-clock"... Apr 11 06:01:40 volumio volumio[15464]: info: Loading plugin "airplay_emulation"... Apr 11 06:01:40 volumio volumio[15464]: info: Starting Shairport Sync Apr 11 06:01:40 volumio volumio[15464]: info: Loading plugin "last_100"... Apr 11 06:01:40 volumio volumio[15464]: info: Loading plugin "webradio"... Apr 11 06:01:40 volumio volumio[15464]: info: Loading plugin "i2s_dacs"... Apr 11 06:01:41 volumio volumio[15464]: info: Loading plugin "volumiodiscovery"... Apr 11 06:01:41 volumio volumio[15464]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:41 volumio node[15464]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:41 volumio volumio[15464]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:41 volumio node[15464]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:41 volumio volumio[15464]: *** WARNING *** For more information see Apr 11 06:01:41 volumio node[15464]: *** WARNING *** For more information see Apr 11 06:01:41 volumio volumio[15464]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:41 volumio node[15464]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:41 volumio volumio[15464]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:41 volumio node[15464]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:41 volumio volumio[15464]: *** WARNING *** For more information see Apr 11 06:01:41 volumio node[15464]: *** WARNING *** For more information see Apr 11 06:01:41 volumio volumio[15464]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 06:01:41 volumio volumio[15464]: info: Discovery: Started advertising with name: undefined Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:41 volumio volumio[15464]: info: Loading plugin "outputs"... Apr 11 06:01:41 volumio volumio[15464]: info: Loading plugin "albumart"... Apr 11 06:01:41 volumio volumio[15464]: info: Plugin example_plugin is not enabled Apr 11 06:01:41 volumio volumio[15464]: info: Loading plugin "inputs"... Apr 11 06:01:41 volumio volumio[15464]: info: Loading plugin "updater_comm"... Apr 11 06:01:41 volumio mpd[15508]: Apr 11 06:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:01:41 volumio volumio[15464]: info: Plugin mpdemulation is not enabled Apr 11 06:01:41 volumio volumio[15464]: info: Loading plugin "rest_api"... Apr 11 06:01:41 volumio mpd[15508]: Apr 11 06:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:01:41 volumio mpd[15508]: Apr 11 06:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:01:41 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:01:41 volumio sudo[15503]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:41 volumio volumio[15464]: info: Loading plugin "websocket"... Apr 11 06:01:41 volumio volumio[15464]: info: Loading i18n strings for locale de Apr 11 06:01:41 volumio volumio[15464]: Updating browse sources language Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:41 volumio volumio[15464]: Forking 3 albumart workers Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::initPlayerControls Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:41 volumio volumio[15464]: Express server listening on port 3000 Apr 11 06:01:41 volumio volumio[15464]: [Metrics] WebUI: 5s 90.52ms Apr 11 06:01:41 volumio volumio[15464]: info: CoreStateMachine::resetVolumioState Apr 11 06:01:41 volumio volumio[15464]: info: CoreStateMachine::getcurrentVolume Apr 11 06:01:41 volumio volumio[15464]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:01:41 volumio volumio[15464]: info: MPD Permissions set Apr 11 06:01:41 volumio volumio-remote-updater[543]: [2024-04-11 06:01:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712808100 101 Apr 11 06:01:41 volumio volumio[15464]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 11 06:01:41 volumio volumio[15464]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:01:41 volumio volumio[15464]: info: MPD running with PID15508 Apr 11 06:01:41 volumio volumio[15464]: ,establishing connection Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: Cannot read play queue from file Apr 11 06:01:42 volumio volumio[15464]: error: updateQueue error: null Apr 11 06:01:42 volumio volumio[15464]: info: Setting Device type: Raspberry PI Apr 11 06:01:42 volumio volumio[15464]: info: CoreStateMachine::setRepeat false single undefined Apr 11 06:01:42 volumio volumio[15464]: info: CoreStateMachine::pushState Apr 11 06:01:42 volumio volumio[15464]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:42 volumio volumio[15464]: info: CoreStateMachine::setRandom null Apr 11 06:01:42 volumio volumio[15464]: info: CoreStateMachine::pushState Apr 11 06:01:42 volumio volumio[15464]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:42 volumio volumio[15464]: info: Completed loading Core Plugins Apr 11 06:01:42 volumio volumio[15464]: info: Preparing to generate the ALSA configuration file Apr 11 06:01:42 volumio volumio[15464]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:01:42 volumio volumio[15464]: info: CoreStateMachine::pushState Apr 11 06:01:42 volumio volumio[15464]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:42 volumio volumio[15464]: info: CoreStateMachine::updateTrackBlock Apr 11 06:01:42 volumio volumio[15464]: info: CorePlayQueue::getTrackBlock Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:01:42 volumio volumio[15464]: error: updateQueue error: null Apr 11 06:01:42 volumio volumio[15464]: info: Asound.conf file unchanged, so no further update is needed Apr 11 06:01:42 volumio volumio[15464]: info: Output device has changed, restarting MPD Apr 11 06:01:42 volumio sudo[15493]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:42 volumio volumio[15464]: info: Output device has changed, restarting Shairport Sync Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:42 volumio sudo[15568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:01:42 volumio sudo[15568]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:42 volumio sudo[15568]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:42 volumio sudo[15570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:01:42 volumio sudo[15570]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:42 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:01:42 volumio volumio[15464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:42 volumio volumio[15464]: info: ___________ START PLUGINS ___________ Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:42 volumio volumio[15464]: info: [1712808102428] CoreMusicLibrary::Adding element Media Servers Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:42 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:01:42 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:42 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:01:42 volumio volumio[15464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:42 volumio volumio[15464]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:42 volumio volumio[15464]: info: [1712808102623] CoreMusicLibrary::Adding element Last_100 Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:42 volumio volumio[15464]: info: [1712808102628] CoreMusicLibrary::Adding element Webradio Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:42 volumio volumio[15464]: Starting albumart workers Apr 11 06:01:42 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:01:42 volumio volumio[15464]: info: Volumio Calling Home Apr 11 06:01:42 volumio volumio[15464]: Starting albumart workers Apr 11 06:01:42 volumio volumio[15464]: info: Completed starting Core Plugins Apr 11 06:01:42 volumio volumio[15464]: info: ------------------------------------------- Apr 11 06:01:42 volumio volumio[15464]: Starting albumart workers Apr 11 06:01:42 volumio volumio[15464]: info: ----- MyVolumio plugins startup ---- Apr 11 06:01:42 volumio volumio[15464]: info: ------------------------------------------- Apr 11 06:01:42 volumio volumio[15464]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 06:01:43 volumio volumio[15464]: info: MPD Permissions set Apr 11 06:01:43 volumio volumio[15464]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:01:43 volumio volumio[15464]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:43 volumio volumio[15464]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:01:43 volumio volumio[15464]: info: CoreStateMachine::pushState Apr 11 06:01:43 volumio volumio[15464]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:43 volumio volumio[15464]: info: Starting Shairport Sync Apr 11 06:01:43 volumio volumio[15464]: info: Starting Shairport Sync Apr 11 06:01:43 volumio volumio[15464]: info: Starting Shairport Sync Apr 11 06:01:43 volumio sudo[15594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:43 volumio sudo[15594]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:43 volumio sudo[15596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:43 volumio sudo[15596]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:01:43 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:01:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:43 volumio sudo[15598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:43 volumio sudo[15598]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:43 volumio sudo[15594]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:01:43 volumio volumio[15464]: info: CoreCommandRouter::volumioGetState Apr 11 06:01:43 volumio volumio[15464]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:43 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:01:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:43 volumio sudo[15596]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:43 volumio sudo[15598]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:43 volumio volumio[15464]: info: Shairport-Sync Started Apr 11 06:01:43 volumio volumio[15464]: Error adding Membership: Error: addMembership EINVAL Apr 11 06:01:43 volumio volumio[15464]: info: Shairport-Sync Started Apr 11 06:01:43 volumio volumio[15464]: info: Shairport-Sync Started Apr 11 06:01:44 volumio mpd[15586]: Apr 11 06:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:01:44 volumio mpd[15586]: Apr 11 06:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:01:44 volumio mpd[15586]: Apr 11 06:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:01:44 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:01:44 volumio sudo[15570]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:44 volumio volumio[15464]: error: updateQueue error: null Apr 11 06:01:46 volumio volumio[15464]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 11 06:01:46 volumio volumio[15464]: info: Discovery: Started advertising with name: undefined Apr 11 06:01:49 volumio volumio[15464]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:01:49 volumio volumio[15464]: TypeError: Cannot read property 'charAt' of undefined Apr 11 06:01:49 volumio volumio[15464]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 11 06:01:49 volumio volumio[15464]: at listOnTimeout (internal/timers.js:554:17) Apr 11 06:01:49 volumio volumio[15464]: at processTimers (internal/timers.js:497:7) Apr 11 06:01:49 volumio volumio[15464]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:01:49 volumio sudo[15644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 06:00 Apr 11 06:01:49 volumio sudo[15644]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:49 volumio sudo[15644]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:50 volumio volumio-remote-updater[543]: [2024-04-11 06:01:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 06:01:50 volumio volumio-remote-updater[543]: [2024-04-11 06:01:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 06:01:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 06:01:50 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 11 06:01:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 06:01:50 volumio systemd[1]: Started dynamicswap service. Apr 11 06:01:50 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:01:50 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 11 06:01:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 338. Apr 11 06:01:50 volumio systemd[1]: Started dynamicswap service. Apr 11 06:01:50 volumio systemd[1]: Stopped Volumio Backend Module. Apr 11 06:01:50 volumio systemd[1]: Started Volumio Backend Module. Apr 11 06:01:50 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:01:52 volumio volumio[15659]: info: ------------------------------------------- Apr 11 06:01:52 volumio volumio[15659]: info: ----- Volumio3 ---- Apr 11 06:01:52 volumio volumio[15659]: info: ------------------------------------------- Apr 11 06:01:52 volumio volumio[15659]: info: ----- System startup ---- Apr 11 06:01:52 volumio volumio[15659]: info: ------------------------------------------- Apr 11 06:01:52 volumio volumio[15659]: info: MYVOLUMIO Environment detected Apr 11 06:01:52 volumio volumio[15659]: info: Plugin folders cleanup Apr 11 06:01:52 volumio volumio[15659]: info: Scanning into folder /volumio/app/plugins/ Apr 11 06:01:52 volumio volumio[15659]: info: Scanning category audio_interface Apr 11 06:01:52 volumio volumio[15659]: info: Scanning category miscellanea Apr 11 06:01:52 volumio volumio[15659]: info: Scanning category music_service Apr 11 06:01:52 volumio volumio[15659]: info: Scanning category plugins.json Apr 11 06:01:52 volumio volumio[15659]: info: Scanning category system_controller Apr 11 06:01:52 volumio volumio[15659]: info: Scanning category user_interface Apr 11 06:01:52 volumio volumio[15659]: info: Scanning into folder /data/plugins/ Apr 11 06:01:52 volumio volumio[15659]: info: Plugin folders cleanup completed Apr 11 06:01:52 volumio volumio[15659]: info: ------------------------------------------- Apr 11 06:01:52 volumio volumio[15659]: info: ----- Core plugins startup ---- Apr 11 06:01:52 volumio volumio[15659]: info: ------------------------------------------- Apr 11 06:01:52 volumio volumio[15659]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 06:01:52 volumio volumio[15659]: info: Adding plugin upnp to MyMusic Plugins Apr 11 06:01:52 volumio volumio[15659]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 06:01:52 volumio volumio[15659]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 06:01:52 volumio volumio[15659]: info: Loading plugins from folder /data/plugins/ Apr 11 06:01:52 volumio volumio[15659]: info: Loading plugin "system"... Apr 11 06:01:52 volumio volumio[15659]: info: Loading plugin "appearance"... Apr 11 06:01:53 volumio volumio[15659]: info: Loading plugin "network"... Apr 11 06:01:53 volumio volumio[15659]: info: Refreshing Cached IP Addresses Apr 11 06:01:54 volumio sudo[15682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 06:01:54 volumio sudo[15682]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:54 volumio sudo[15682]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:54 volumio sudo[15684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 06:01:54 volumio sudo[15684]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "services"... Apr 11 06:01:54 volumio sudo[15684]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "alsa_controller"... Apr 11 06:01:54 volumio sudo[15687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 06:01:54 volumio sudo[15687]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:54 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "wizard"... Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "volumio_command_line_client"... Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "upnp"... Apr 11 06:01:54 volumio volumio[15659]: info: [1712808114172] Starting Upmpd Daemon Apr 11 06:01:54 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "my_music"... Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "mpd"... Apr 11 06:01:54 volumio volumio[15659]: info: Creating MPD Configuration file Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "upnp_browser"... Apr 11 06:01:54 volumio sudo[15695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:01:54 volumio sudo[15695]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:54 volumio sudo[15695]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:54 volumio sudo[15697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:01:54 volumio sudo[15697]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:54 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:01:54 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:01:54 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:01:54 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:01:54 volumio volumio[15659]: info: Loading plugin "networkfs"... Apr 11 06:01:55 volumio volumio-remote-updater[543]: [2024-04-11 06:01:55] [connect] Successful connection Apr 11 06:01:55 volumio volumio[15659]: info: Starting Udev Watcher for removable devices Apr 11 06:01:55 volumio volumio[15659]: info: Ignoring mount for partition: boot Apr 11 06:01:55 volumio volumio[15659]: info: Ignoring mount for partition: volumio Apr 11 06:01:55 volumio volumio[15659]: info: Ignoring mount for partition: volumio_data Apr 11 06:01:55 volumio volumio[15659]: info: Mounting Device INTENSO Apr 11 06:01:55 volumio sudo[15708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 11 06:01:55 volumio sudo[15708]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:55 volumio sudo[15708]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:55 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:55 volumio volumio[15659]: info: Loading plugin "alarm-clock"... Apr 11 06:01:55 volumio volumio[15659]: info: Loading plugin "airplay_emulation"... Apr 11 06:01:55 volumio volumio[15659]: info: Starting Shairport Sync Apr 11 06:01:55 volumio volumio[15659]: info: Loading plugin "last_100"... Apr 11 06:01:55 volumio volumio[15659]: info: Loading plugin "webradio"... Apr 11 06:01:56 volumio volumio[15659]: info: Loading plugin "i2s_dacs"... Apr 11 06:01:56 volumio volumio[15659]: info: Loading plugin "volumiodiscovery"... Apr 11 06:01:56 volumio volumio[15659]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:56 volumio volumio[15659]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:56 volumio volumio[15659]: *** WARNING *** For more information see Apr 11 06:01:56 volumio node[15659]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:56 volumio volumio[15659]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:56 volumio volumio[15659]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:56 volumio volumio[15659]: *** WARNING *** For more information see Apr 11 06:01:56 volumio node[15659]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:56 volumio node[15659]: *** WARNING *** For more information see Apr 11 06:01:56 volumio node[15659]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:01:56 volumio node[15659]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:01:56 volumio node[15659]: *** WARNING *** For more information see Apr 11 06:01:56 volumio volumio[15659]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 06:01:56 volumio volumio[15659]: info: Discovery: Started advertising with name: undefined Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:01:56 volumio volumio[15659]: info: Loading plugin "outputs"... Apr 11 06:01:56 volumio volumio[15659]: info: Loading plugin "albumart"... Apr 11 06:01:56 volumio volumio[15659]: info: Plugin example_plugin is not enabled Apr 11 06:01:56 volumio volumio[15659]: info: Loading plugin "inputs"... Apr 11 06:01:56 volumio volumio[15659]: info: Loading plugin "updater_comm"... Apr 11 06:01:56 volumio mpd[15702]: Apr 11 06:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:01:56 volumio mpd[15702]: Apr 11 06:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:01:56 volumio mpd[15702]: Apr 11 06:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:01:56 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:01:56 volumio sudo[15697]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:56 volumio volumio[15659]: info: Plugin mpdemulation is not enabled Apr 11 06:01:56 volumio volumio[15659]: info: Loading plugin "rest_api"... Apr 11 06:01:56 volumio volumio[15659]: info: Loading plugin "websocket"... Apr 11 06:01:56 volumio volumio[15659]: info: Loading i18n strings for locale de Apr 11 06:01:56 volumio volumio[15659]: Updating browse sources language Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:56 volumio volumio[15659]: Forking 3 albumart workers Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::initPlayerControls Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: Express server listening on port 3000 Apr 11 06:01:56 volumio volumio[15659]: [Metrics] WebUI: 5s 34.78ms Apr 11 06:01:56 volumio volumio[15659]: info: CoreStateMachine::resetVolumioState Apr 11 06:01:56 volumio volumio[15659]: info: CoreStateMachine::getcurrentVolume Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:01:56 volumio volumio[15659]: info: MPD Permissions set Apr 11 06:01:56 volumio volumio-remote-updater[543]: [2024-04-11 06:01:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712808115 101 Apr 11 06:01:56 volumio volumio[15659]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 11 06:01:56 volumio volumio[15659]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:01:56 volumio volumio[15659]: info: MPD running with PID15702 Apr 11 06:01:56 volumio volumio[15659]: ,establishing connection Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:56 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:57 volumio volumio[15659]: info: Cannot read play queue from file Apr 11 06:01:57 volumio volumio[15659]: error: updateQueue error: null Apr 11 06:01:57 volumio volumio[15659]: info: CoreStateMachine::setRepeat false single undefined Apr 11 06:01:57 volumio volumio[15659]: info: CoreStateMachine::pushState Apr 11 06:01:57 volumio volumio[15659]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:57 volumio volumio[15659]: info: CoreStateMachine::setRandom null Apr 11 06:01:57 volumio volumio[15659]: info: CoreStateMachine::pushState Apr 11 06:01:57 volumio volumio[15659]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:57 volumio volumio[15659]: info: Setting Device type: Raspberry PI Apr 11 06:01:57 volumio volumio[15659]: info: Completed loading Core Plugins Apr 11 06:01:57 volumio volumio[15659]: info: Preparing to generate the ALSA configuration file Apr 11 06:01:57 volumio volumio[15659]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:01:57 volumio volumio[15659]: info: CoreStateMachine::pushState Apr 11 06:01:57 volumio volumio[15659]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:57 volumio volumio[15659]: info: CoreStateMachine::updateTrackBlock Apr 11 06:01:57 volumio volumio[15659]: info: CorePlayQueue::getTrackBlock Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:01:57 volumio volumio[15659]: error: updateQueue error: null Apr 11 06:01:57 volumio volumio[15659]: info: Asound.conf file unchanged, so no further update is needed Apr 11 06:01:57 volumio volumio[15659]: info: Output device has changed, restarting MPD Apr 11 06:01:57 volumio volumio[15659]: info: Output device has changed, restarting Shairport Sync Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:57 volumio sudo[15762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:01:57 volumio sudo[15762]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:57 volumio sudo[15762]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:57 volumio sudo[15764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:01:57 volumio sudo[15764]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:57 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:01:57 volumio sudo[15687]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:57 volumio volumio[15659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:57 volumio volumio[15659]: info: ___________ START PLUGINS ___________ Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:01:57 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:57 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:01:57 volumio volumio[15659]: info: [1712808117361] CoreMusicLibrary::Adding element Media Servers Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:57 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:01:57 volumio volumio[15659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:57 volumio volumio[15659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:57 volumio volumio[15659]: info: [1712808117578] CoreMusicLibrary::Adding element Last_100 Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:01:57 volumio volumio[15659]: info: [1712808117583] CoreMusicLibrary::Adding element Webradio Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:01:57 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:01:57 volumio volumio[15659]: info: Volumio Calling Home Apr 11 06:01:57 volumio volumio[15659]: Starting albumart workers Apr 11 06:01:57 volumio volumio[15659]: Starting albumart workers Apr 11 06:01:57 volumio volumio[15659]: Starting albumart workers Apr 11 06:01:57 volumio volumio[15659]: info: Completed starting Core Plugins Apr 11 06:01:57 volumio volumio[15659]: info: ------------------------------------------- Apr 11 06:01:57 volumio volumio[15659]: info: ----- MyVolumio plugins startup ---- Apr 11 06:01:57 volumio volumio[15659]: info: ------------------------------------------- Apr 11 06:01:57 volumio volumio[15659]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 06:01:58 volumio volumio[15659]: info: MPD Permissions set Apr 11 06:01:58 volumio volumio[15659]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:01:58 volumio volumio[15659]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:01:58 volumio volumio[15659]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:01:58 volumio volumio[15659]: info: CoreStateMachine::pushState Apr 11 06:01:58 volumio volumio[15659]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::volumioPushState Apr 11 06:01:58 volumio volumio[15659]: info: Starting Shairport Sync Apr 11 06:01:58 volumio volumio[15659]: info: Starting Shairport Sync Apr 11 06:01:58 volumio volumio[15659]: info: Starting Shairport Sync Apr 11 06:01:58 volumio sudo[15790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:58 volumio sudo[15790]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:58 volumio sudo[15792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:58 volumio sudo[15792]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:01:58 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:01:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:58 volumio sudo[15794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:01:58 volumio sudo[15794]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:01:58 volumio volumio[15659]: info: CoreCommandRouter::volumioGetState Apr 11 06:01:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:58 volumio volumio[15659]: info: CorePlayQueue::getTrack 0 Apr 11 06:01:58 volumio sudo[15792]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:58 volumio sudo[15790]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:01:58 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 11 06:01:58 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:01:58 volumio volumio[15659]: info: Shairport-Sync Started Apr 11 06:01:58 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:58 volumio volumio[15659]: Error adding Membership: Error: addMembership EINVAL Apr 11 06:01:58 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:01:58 volumio volumio[15659]: info: Shairport-Sync Started Apr 11 06:01:58 volumio sudo[15794]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:58 volumio volumio[15659]: info: Shairport-Sync Started Apr 11 06:01:59 volumio mpd[15781]: Apr 11 06:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:01:59 volumio mpd[15781]: Apr 11 06:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:01:59 volumio mpd[15781]: Apr 11 06:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:01:59 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:01:59 volumio sudo[15764]: pam_unix(sudo:session): session closed for user root Apr 11 06:01:59 volumio volumio[15659]: error: updateQueue error: null Apr 11 06:02:01 volumio volumio[15659]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 11 06:02:01 volumio volumio[15659]: info: Discovery: Started advertising with name: undefined Apr 11 06:02:04 volumio volumio[15659]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:02:04 volumio volumio[15659]: TypeError: Cannot read property 'charAt' of undefined Apr 11 06:02:04 volumio volumio[15659]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 11 06:02:04 volumio volumio[15659]: at listOnTimeout (internal/timers.js:554:17) Apr 11 06:02:04 volumio volumio[15659]: at processTimers (internal/timers.js:497:7) Apr 11 06:02:04 volumio volumio[15659]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:02:04 volumio sudo[15836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 06:01 Apr 11 06:02:04 volumio sudo[15836]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:04 volumio sudo[15836]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:05 volumio volumio-remote-updater[543]: [2024-04-11 06:02:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 06:02:05 volumio volumio-remote-updater[543]: [2024-04-11 06:02:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 06:02:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 06:02:05 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 11 06:02:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 06:02:05 volumio systemd[1]: Started dynamicswap service. Apr 11 06:02:05 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:02:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 11 06:02:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 339. Apr 11 06:02:05 volumio systemd[1]: Stopped Volumio Backend Module. Apr 11 06:02:05 volumio systemd[1]: Started Volumio Backend Module. Apr 11 06:02:05 volumio systemd[1]: Started dynamicswap service. Apr 11 06:02:05 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:02:07 volumio volumio[15850]: info: ------------------------------------------- Apr 11 06:02:07 volumio volumio[15850]: info: ----- Volumio3 ---- Apr 11 06:02:07 volumio volumio[15850]: info: ------------------------------------------- Apr 11 06:02:07 volumio volumio[15850]: info: ----- System startup ---- Apr 11 06:02:07 volumio volumio[15850]: info: ------------------------------------------- Apr 11 06:02:07 volumio volumio[15850]: info: MYVOLUMIO Environment detected Apr 11 06:02:07 volumio volumio[15850]: info: Plugin folders cleanup Apr 11 06:02:07 volumio volumio[15850]: info: Scanning into folder /volumio/app/plugins/ Apr 11 06:02:07 volumio volumio[15850]: info: Scanning category audio_interface Apr 11 06:02:07 volumio volumio[15850]: info: Scanning category miscellanea Apr 11 06:02:07 volumio volumio[15850]: info: Scanning category music_service Apr 11 06:02:07 volumio volumio[15850]: info: Scanning category plugins.json Apr 11 06:02:07 volumio volumio[15850]: info: Scanning category system_controller Apr 11 06:02:07 volumio volumio[15850]: info: Scanning category user_interface Apr 11 06:02:07 volumio volumio[15850]: info: Scanning into folder /data/plugins/ Apr 11 06:02:07 volumio volumio[15850]: info: Plugin folders cleanup completed Apr 11 06:02:07 volumio volumio[15850]: info: ------------------------------------------- Apr 11 06:02:07 volumio volumio[15850]: info: ----- Core plugins startup ---- Apr 11 06:02:07 volumio volumio[15850]: info: ------------------------------------------- Apr 11 06:02:07 volumio volumio[15850]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 06:02:07 volumio volumio[15850]: info: Adding plugin upnp to MyMusic Plugins Apr 11 06:02:07 volumio volumio[15850]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 06:02:07 volumio volumio[15850]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 06:02:07 volumio volumio[15850]: info: Loading plugins from folder /data/plugins/ Apr 11 06:02:07 volumio volumio[15850]: info: Loading plugin "system"... Apr 11 06:02:08 volumio volumio[15850]: info: Loading plugin "appearance"... Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "network"... Apr 11 06:02:09 volumio volumio[15850]: info: Refreshing Cached IP Addresses Apr 11 06:02:09 volumio sudo[15875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 06:02:09 volumio sudo[15875]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:09 volumio sudo[15875]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:09 volumio sudo[15877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "services"... Apr 11 06:02:09 volumio sudo[15877]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "alsa_controller"... Apr 11 06:02:09 volumio sudo[15877]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:09 volumio sudo[15880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 06:02:09 volumio sudo[15880]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:09 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "wizard"... Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "volumio_command_line_client"... Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "upnp"... Apr 11 06:02:09 volumio volumio[15850]: info: [1712808129217] Starting Upmpd Daemon Apr 11 06:02:09 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "my_music"... Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "mpd"... Apr 11 06:02:09 volumio volumio[15850]: info: Creating MPD Configuration file Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "upnp_browser"... Apr 11 06:02:09 volumio sudo[15888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:02:09 volumio sudo[15888]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:09 volumio sudo[15888]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:09 volumio sudo[15890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:02:09 volumio sudo[15890]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:09 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:02:09 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:02:09 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:02:09 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:02:09 volumio volumio[15850]: info: Loading plugin "networkfs"... Apr 11 06:02:10 volumio volumio-remote-updater[543]: [2024-04-11 06:02:10] [connect] Successful connection Apr 11 06:02:10 volumio volumio[15850]: info: Starting Udev Watcher for removable devices Apr 11 06:02:10 volumio volumio[15850]: info: Ignoring mount for partition: boot Apr 11 06:02:10 volumio volumio[15850]: info: Ignoring mount for partition: volumio Apr 11 06:02:10 volumio volumio[15850]: info: Ignoring mount for partition: volumio_data Apr 11 06:02:10 volumio volumio[15850]: info: Mounting Device INTENSO Apr 11 06:02:10 volumio sudo[15901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 11 06:02:10 volumio sudo[15901]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:10 volumio sudo[15901]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:10 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:02:10 volumio volumio[15850]: info: Loading plugin "alarm-clock"... Apr 11 06:02:10 volumio volumio[15850]: info: Loading plugin "airplay_emulation"... Apr 11 06:02:10 volumio volumio[15850]: info: Starting Shairport Sync Apr 11 06:02:10 volumio volumio[15850]: info: Loading plugin "last_100"... Apr 11 06:02:10 volumio volumio[15850]: info: Loading plugin "webradio"... Apr 11 06:02:11 volumio volumio[15850]: info: Loading plugin "i2s_dacs"... Apr 11 06:02:11 volumio volumio[15850]: info: Loading plugin "volumiodiscovery"... Apr 11 06:02:11 volumio volumio[15850]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:02:11 volumio volumio[15850]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:02:11 volumio node[15850]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:02:11 volumio volumio[15850]: *** WARNING *** For more information see Apr 11 06:02:11 volumio volumio[15850]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:02:11 volumio volumio[15850]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:02:11 volumio volumio[15850]: *** WARNING *** For more information see Apr 11 06:02:11 volumio node[15850]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:02:11 volumio node[15850]: *** WARNING *** For more information see Apr 11 06:02:11 volumio node[15850]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:02:11 volumio node[15850]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:02:11 volumio node[15850]: *** WARNING *** For more information see Apr 11 06:02:11 volumio volumio[15850]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 06:02:11 volumio volumio[15850]: info: Discovery: Started advertising with name: undefined Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:02:11 volumio volumio[15850]: info: Loading plugin "outputs"... Apr 11 06:02:11 volumio volumio[15850]: info: Loading plugin "albumart"... Apr 11 06:02:11 volumio volumio[15850]: info: Plugin example_plugin is not enabled Apr 11 06:02:11 volumio volumio[15850]: info: Loading plugin "inputs"... Apr 11 06:02:11 volumio volumio[15850]: info: Loading plugin "updater_comm"... Apr 11 06:02:11 volumio mpd[15895]: Apr 11 06:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:02:11 volumio mpd[15895]: Apr 11 06:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:02:11 volumio mpd[15895]: Apr 11 06:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:02:11 volumio volumio[15850]: info: Plugin mpdemulation is not enabled Apr 11 06:02:11 volumio volumio[15850]: info: Loading plugin "rest_api"... Apr 11 06:02:11 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:02:11 volumio sudo[15890]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:11 volumio volumio[15850]: info: Loading plugin "websocket"... Apr 11 06:02:11 volumio volumio[15850]: info: Loading i18n strings for locale de Apr 11 06:02:11 volumio volumio[15850]: Updating browse sources language Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:02:11 volumio volumio[15850]: Forking 3 albumart workers Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::initPlayerControls Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:02:11 volumio volumio[15850]: Express server listening on port 3000 Apr 11 06:02:11 volumio volumio[15850]: [Metrics] WebUI: 5s 91.23ms Apr 11 06:02:11 volumio volumio[15850]: info: CoreStateMachine::resetVolumioState Apr 11 06:02:11 volumio volumio[15850]: info: CoreStateMachine::getcurrentVolume Apr 11 06:02:11 volumio volumio[15850]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:02:11 volumio volumio[15850]: info: MPD Permissions set Apr 11 06:02:11 volumio volumio-remote-updater[543]: [2024-04-11 06:02:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712808130 101 Apr 11 06:02:11 volumio volumio[15850]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 11 06:02:11 volumio volumio[15850]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:02:11 volumio volumio[15850]: info: MPD running with PID15895 Apr 11 06:02:11 volumio volumio[15850]: ,establishing connection Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio wpa_supplicant[815]: wlan0: CTRL-EVENT-DISCONNECTED bssid=3c:a6:2f:c6:e6:0d reason=0 locally_generated=1 Apr 11 06:02:12 volumio dhcpcd[615]: wlan0: carrier lost Apr 11 06:02:12 volumio wpa_supplicant[815]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 11 06:02:12 volumio wpa_supplicant[815]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: Cannot read play queue from file Apr 11 06:02:12 volumio volumio[15850]: error: updateQueue error: null Apr 11 06:02:12 volumio volumio[15850]: info: CoreStateMachine::setRepeat false single undefined Apr 11 06:02:12 volumio volumio[15850]: info: CoreStateMachine::pushState Apr 11 06:02:12 volumio volumio[15850]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::volumioPushState Apr 11 06:02:12 volumio volumio[15850]: info: CoreStateMachine::setRandom null Apr 11 06:02:12 volumio volumio[15850]: info: CoreStateMachine::pushState Apr 11 06:02:12 volumio volumio[15850]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::volumioPushState Apr 11 06:02:12 volumio volumio[15850]: info: Setting Device type: Raspberry PI Apr 11 06:02:12 volumio volumio[15850]: info: Completed loading Core Plugins Apr 11 06:02:12 volumio volumio[15850]: info: Preparing to generate the ALSA configuration file Apr 11 06:02:12 volumio volumio[15850]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:02:12 volumio volumio[15850]: info: CoreStateMachine::pushState Apr 11 06:02:12 volumio volumio[15850]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::volumioPushState Apr 11 06:02:12 volumio volumio[15850]: info: CoreStateMachine::updateTrackBlock Apr 11 06:02:12 volumio volumio[15850]: info: CorePlayQueue::getTrackBlock Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:02:12 volumio avahi-daemon[545]: Withdrawing address record for 192.168.178.24 on wlan0. Apr 11 06:02:12 volumio avahi-daemon[545]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.24. Apr 11 06:02:12 volumio dhcpcd[615]: wlan0: deleting route to 192.168.178.0/24 Apr 11 06:02:12 volumio dhcpcd[615]: wlan0: deleting default route via 192.168.178.1 Apr 11 06:02:12 volumio avahi-daemon[545]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 11 06:02:12 volumio wpa_supplicant[815]: wlan0: Trying to associate with SSID 'G Fritz Zintl' Apr 11 06:02:12 volumio volumio[15850]: error: updateQueue error: null Apr 11 06:02:12 volumio volumio[15850]: info: Asound.conf file unchanged, so no further update is needed Apr 11 06:02:12 volumio volumio[15850]: info: Output device has changed, restarting MPD Apr 11 06:02:12 volumio sudo[15880]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:12 volumio ntpd[14444]: ntpd exiting on signal 15 (Terminated) Apr 11 06:02:12 volumio ntpd[14444]: 192.168.178.1 local addr 192.168.178.24 -> Apr 11 06:02:12 volumio ntpd[14444]: 5.9.122.119 local addr 192.168.178.24 -> Apr 11 06:02:12 volumio ntpd[14444]: 213.239.234.28 local addr 192.168.178.24 -> Apr 11 06:02:12 volumio ntpd[14444]: 80.153.195.191 local addr 192.168.178.24 -> Apr 11 06:02:12 volumio ntpd[14444]: 162.159.200.123 local addr 192.168.178.24 -> Apr 11 06:02:12 volumio ntpd[14444]: 79.133.44.138 local addr 192.168.178.24 -> Apr 11 06:02:12 volumio systemd[1]: Stopping Network Time Service... Apr 11 06:02:12 volumio systemd[1]: ntp.service: Succeeded. Apr 11 06:02:12 volumio systemd[1]: Stopped Network Time Service. Apr 11 06:02:12 volumio volumio[15850]: info: Output device has changed, restarting Shairport Sync Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:12 volumio sudo[16001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:02:12 volumio systemd[1]: Starting Network Time Service... Apr 11 06:02:12 volumio sudo[16001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:12 volumio sudo[16001]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:12 volumio sudo[16003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:02:12 volumio sudo[16003]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:12 volumio ntpd[16002]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 11 06:02:12 volumio ntpd[16002]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Apr 11 06:02:12 volumio systemd[1]: Started Network Time Service. Apr 11 06:02:12 volumio ntpd[16015]: proto: precision = 0.678 usec (-20) Apr 11 06:02:12 volumio ntpd[16015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 11 06:02:12 volumio ntpd[16015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 11 06:02:12 volumio ntpd[16015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 106 days ago Apr 11 06:02:12 volumio ntpd[16015]: Listen and drop on 0 v6wildcard [::]:123 Apr 11 06:02:12 volumio ntpd[16015]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 11 06:02:12 volumio ntpd[16015]: Listen normally on 2 lo 127.0.0.1:123 Apr 11 06:02:12 volumio ntpd[16015]: Listening on routing socket on fd #19 for interface updates Apr 11 06:02:12 volumio ntpd[16015]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 11 06:02:12 volumio ntpd[16015]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 11 06:02:12 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:02:12 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:02:12 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:02:12 volumio volumio[15850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:02:12 volumio volumio[15850]: info: ___________ START PLUGINS ___________ Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:02:12 volumio volumio[15850]: info: [1712808132726] CoreMusicLibrary::Adding element Media Servers Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:02:12 volumio volumio[15850]: Starting albumart workers Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:12 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:02:12 volumio volumio[15850]: Starting albumart workers Apr 11 06:02:12 volumio volumio[15850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:12 volumio volumio[15850]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:02:12 volumio volumio[15850]: info: [1712808132877] CoreMusicLibrary::Adding element Last_100 Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:02:12 volumio volumio[15850]: info: [1712808132882] CoreMusicLibrary::Adding element Webradio Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:02:12 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:02:12 volumio volumio[15850]: info: Volumio Calling Home Apr 11 06:02:13 volumio volumio[15850]: Starting albumart workers Apr 11 06:02:13 volumio volumio[15850]: info: Completed starting Core Plugins Apr 11 06:02:13 volumio volumio[15850]: info: ------------------------------------------- Apr 11 06:02:13 volumio volumio[15850]: info: ----- MyVolumio plugins startup ---- Apr 11 06:02:13 volumio volumio[15850]: info: ------------------------------------------- Apr 11 06:02:13 volumio volumio[15850]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 06:02:13 volumio volumio[15850]: Unhandled rejection Error: No sockets available, cannot start. Apr 11 06:02:13 volumio volumio[15850]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 11 06:02:13 volumio volumio[15850]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 11 06:02:13 volumio volumio[15850]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 11 06:02:13 volumio volumio[15850]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 11 06:02:13 volumio volumio[15850]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 11 06:02:13 volumio volumio[15850]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 11 06:02:13 volumio volumio[15850]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 11 06:02:13 volumio volumio[15850]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 11 06:02:13 volumio volumio[15850]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 11 06:02:13 volumio volumio[15850]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 11 06:02:13 volumio volumio[15850]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 11 06:02:13 volumio volumio[15850]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 11 06:02:13 volumio volumio[15850]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 11 06:02:13 volumio volumio[15850]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 11 06:02:13 volumio volumio[15850]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 11 06:02:13 volumio volumio[15850]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 11 06:02:13 volumio volumio[15850]: info: MPD Permissions set Apr 11 06:02:13 volumio volumio[15850]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:02:13 volumio volumio[15850]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 11 06:02:13 volumio volumio[15850]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:02:13 volumio volumio[15850]: info: CoreStateMachine::pushState Apr 11 06:02:13 volumio volumio[15850]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::volumioPushState Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:13 volumio volumio[15850]: info: Starting Shairport Sync Apr 11 06:02:13 volumio volumio[15850]: info: Starting Shairport Sync Apr 11 06:02:13 volumio volumio[15850]: info: Starting Shairport Sync Apr 11 06:02:13 volumio ntpd[16015]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 11 06:02:13 volumio sudo[16036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:02:13 volumio sudo[16036]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:13 volumio sudo[16038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:02:13 volumio sudo[16039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:02:13 volumio sudo[16039]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:13 volumio sudo[16038]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:02:13 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:02:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:13 volumio sudo[16036]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:13 volumio volumio[15850]: info: CoreCommandRouter::volumioGetState Apr 11 06:02:13 volumio volumio[15850]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:02:13 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 11 06:02:13 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:02:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:13 volumio sudo[16038]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:13 volumio sudo[16039]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:13 volumio volumio[15850]: info: Shairport-Sync Started Apr 11 06:02:13 volumio volumio[15850]: Error adding Membership: Error: addMembership EINVAL Apr 11 06:02:13 volumio volumio[15850]: info: Shairport-Sync Started Apr 11 06:02:13 volumio volumio[15850]: info: Shairport-Sync Started Apr 11 06:02:14 volumio ntpd[16015]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 11 06:02:14 volumio mpd[16028]: Apr 11 06:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:02:14 volumio mpd[16028]: Apr 11 06:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:02:14 volumio mpd[16028]: Apr 11 06:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:02:14 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:02:14 volumio sudo[16003]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:14 volumio volumio[15850]: error: updateQueue error: null Apr 11 06:02:15 volumio wpa_supplicant[815]: wlan0: Associated with 3c:a6:2f:c6:e6:0e Apr 11 06:02:15 volumio wpa_supplicant[815]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:c6:e6:0e completed [id=0 id_str=] Apr 11 06:02:15 volumio dhcpcd[615]: wlan0: carrier acquired Apr 11 06:02:15 volumio wpa_supplicant[815]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 11 06:02:15 volumio wpa_supplicant[815]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Apr 11 06:02:15 volumio dhcpcd[615]: wlan0: IAID eb:0d:0b:6e Apr 11 06:02:15 volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network Apr 11 06:02:15 volumio ntpd[16015]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 11 06:02:16 volumio volumio[15850]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 11 06:02:16 volumio volumio[15850]: info: Discovery: Started advertising with name: undefined Apr 11 06:02:16 volumio dhcpcd[615]: wlan0: soliciting a DHCP lease Apr 11 06:02:16 volumio dhcpcd[615]: wlan0: soliciting an IPv6 router Apr 11 06:02:16 volumio dhcpcd[615]: wlan0: offered 192.168.178.24 from 192.168.178.1 Apr 11 06:02:16 volumio dhcpcd[615]: wlan0: probing address 192.168.178.24/24 Apr 11 06:02:16 volumio ntpd[16015]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 11 06:02:17 volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network Apr 11 06:02:17 volumio dhcpcd[615]: wlan0: carrier lost Apr 11 06:02:17 volumio wpa_supplicant[815]: wlan0: Associated with 3c:a6:2f:c6:e6:0d Apr 11 06:02:17 volumio wpa_supplicant[815]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:a6:2f:c6:e6:0d completed [id=0 id_str=] Apr 11 06:02:17 volumio wpa_supplicant[815]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 11 06:02:17 volumio dhcpcd[615]: wlan0: carrier acquired Apr 11 06:02:17 volumio dhcpcd[615]: wlan0: IAID eb:0d:0b:6e Apr 11 06:02:18 volumio dhcpcd[615]: wlan0: soliciting a DHCP lease Apr 11 06:02:18 volumio dhcpcd[615]: wlan0: offered 192.168.178.24 from 192.168.178.1 Apr 11 06:02:18 volumio dhcpcd[615]: wlan0: probing address 192.168.178.24/24 Apr 11 06:02:18 volumio dhcpcd[615]: wlan0: soliciting an IPv6 router Apr 11 06:02:19 volumio volumio[15850]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:02:19 volumio volumio[15850]: TypeError: Cannot read property 'charAt' of undefined Apr 11 06:02:19 volumio volumio[15850]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 11 06:02:19 volumio volumio[15850]: at listOnTimeout (internal/timers.js:554:17) Apr 11 06:02:19 volumio volumio[15850]: at processTimers (internal/timers.js:497:7) Apr 11 06:02:19 volumio volumio[15850]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:02:19 volumio sudo[16106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 06:01 Apr 11 06:02:19 volumio sudo[16106]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:19 volumio sudo[16106]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:20 volumio volumio-remote-updater[543]: [2024-04-11 06:02:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 11 06:02:20 volumio volumio-remote-updater[543]: [2024-04-11 06:02:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 11 06:02:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 11 06:02:20 volumio systemd[1]: media-INTENSO.mount: Succeeded. Apr 11 06:02:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 11 06:02:20 volumio systemd[1]: Started dynamicswap service. Apr 11 06:02:20 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:02:20 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 11 06:02:20 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 340. Apr 11 06:02:20 volumio systemd[1]: Stopped Volumio Backend Module. Apr 11 06:02:20 volumio systemd[1]: Started Volumio Backend Module. Apr 11 06:02:20 volumio systemd[1]: Started dynamicswap service. Apr 11 06:02:20 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 11 06:02:22 volumio volumio[16120]: info: ------------------------------------------- Apr 11 06:02:22 volumio volumio[16120]: info: ----- Volumio3 ---- Apr 11 06:02:22 volumio volumio[16120]: info: ------------------------------------------- Apr 11 06:02:22 volumio volumio[16120]: info: ----- System startup ---- Apr 11 06:02:22 volumio volumio[16120]: info: ------------------------------------------- Apr 11 06:02:22 volumio dhcpcd[615]: wlan0: leased 192.168.178.24 for 864000 seconds Apr 11 06:02:22 volumio avahi-daemon[545]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.24. Apr 11 06:02:22 volumio dhcpcd[615]: wlan0: adding route to 192.168.178.0/24 Apr 11 06:02:22 volumio dhcpcd[615]: wlan0: adding default route via 192.168.178.1 Apr 11 06:02:22 volumio avahi-daemon[545]: New relevant interface wlan0.IPv4 for mDNS. Apr 11 06:02:22 volumio avahi-daemon[545]: Registering new address record for 192.168.178.24 on wlan0.IPv4. Apr 11 06:02:22 volumio ntpd[16015]: ntpd exiting on signal 15 (Terminated) Apr 11 06:02:22 volumio systemd[1]: Stopping Network Time Service... Apr 11 06:02:22 volumio systemd[1]: ntp.service: Succeeded. Apr 11 06:02:22 volumio systemd[1]: Stopped Network Time Service. Apr 11 06:02:22 volumio systemd[1]: Starting Network Time Service... Apr 11 06:02:22 volumio ntpd[16173]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 11 06:02:22 volumio ntpd[16173]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Apr 11 06:02:22 volumio systemd[1]: Started Network Time Service. Apr 11 06:02:22 volumio ntpd[16180]: proto: precision = 0.677 usec (-20) Apr 11 06:02:22 volumio ntpd[16180]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 11 06:02:22 volumio ntpd[16180]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 11 06:02:22 volumio ntpd[16180]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 106 days ago Apr 11 06:02:22 volumio ntpd[16180]: Listen and drop on 0 v6wildcard [::]:123 Apr 11 06:02:22 volumio ntpd[16180]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 11 06:02:22 volumio ntpd[16180]: Listen normally on 2 lo 127.0.0.1:123 Apr 11 06:02:22 volumio ntpd[16180]: Listen normally on 3 wlan0 192.168.178.24:123 Apr 11 06:02:22 volumio ntpd[16180]: Listening on routing socket on fd #20 for interface updates Apr 11 06:02:22 volumio ntpd[16180]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 11 06:02:22 volumio ntpd[16180]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 11 06:02:22 volumio volumio[16120]: info: MYVOLUMIO Environment detected Apr 11 06:02:22 volumio volumio[16120]: info: Plugin folders cleanup Apr 11 06:02:22 volumio volumio[16120]: info: Scanning into folder /volumio/app/plugins/ Apr 11 06:02:22 volumio volumio[16120]: info: Scanning category audio_interface Apr 11 06:02:22 volumio volumio[16120]: info: Scanning category miscellanea Apr 11 06:02:22 volumio volumio[16120]: info: Scanning category music_service Apr 11 06:02:22 volumio volumio[16120]: info: Scanning category plugins.json Apr 11 06:02:22 volumio volumio[16120]: info: Scanning category system_controller Apr 11 06:02:22 volumio volumio[16120]: info: Scanning category user_interface Apr 11 06:02:22 volumio volumio[16120]: info: Scanning into folder /data/plugins/ Apr 11 06:02:22 volumio volumio[16120]: info: Plugin folders cleanup completed Apr 11 06:02:22 volumio volumio[16120]: info: ------------------------------------------- Apr 11 06:02:22 volumio volumio[16120]: info: ----- Core plugins startup ---- Apr 11 06:02:22 volumio volumio[16120]: info: ------------------------------------------- Apr 11 06:02:22 volumio volumio[16120]: info: Loading plugins from folder /volumio/app/plugins/ Apr 11 06:02:22 volumio volumio[16120]: info: Adding plugin upnp to MyMusic Plugins Apr 11 06:02:22 volumio volumio[16120]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 11 06:02:22 volumio volumio[16120]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 11 06:02:22 volumio volumio[16120]: info: Loading plugins from folder /data/plugins/ Apr 11 06:02:22 volumio volumio[16120]: info: Loading plugin "system"... Apr 11 06:02:23 volumio volumio[16120]: info: Loading plugin "appearance"... Apr 11 06:02:23 volumio wpa_supplicant[815]: RRM: Ignoring radio measurement request: Not RRM network Apr 11 06:02:23 volumio volumio[16120]: info: Loading plugin "network"... Apr 11 06:02:24 volumio volumio[16120]: info: Refreshing Cached IP Addresses Apr 11 06:02:24 volumio sudo[16184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 06:02:24 volumio sudo[16184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:24 volumio sudo[16184]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:24 volumio volumio[16120]: info: Loading plugin "services"... Apr 11 06:02:24 volumio sudo[16186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 06:02:24 volumio sudo[16186]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:24 volumio volumio[16120]: info: Loading plugin "alsa_controller"... Apr 11 06:02:24 volumio sudo[16186]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:24 volumio sudo[16189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 11 06:02:24 volumio sudo[16189]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:24 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:02:24 volumio volumio[16120]: info: Loading plugin "wizard"... Apr 11 06:02:24 volumio volumio[16120]: info: Loading plugin "volumio_command_line_client"... Apr 11 06:02:24 volumio volumio[16120]: info: Loading plugin "upnp"... Apr 11 06:02:24 volumio volumio[16120]: info: [1712808144199] Starting Upmpd Daemon Apr 11 06:02:24 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:02:24 volumio volumio[16120]: info: Loading plugin "my_music"... Apr 11 06:02:24 volumio volumio[16120]: info: Loading plugin "mpd"... Apr 11 06:02:24 volumio volumio[16120]: info: Creating MPD Configuration file Apr 11 06:02:24 volumio volumio[16120]: info: Loading plugin "upnp_browser"... Apr 11 06:02:24 volumio sudo[16197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:02:24 volumio sudo[16197]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:24 volumio sudo[16197]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:24 volumio sudo[16199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:02:24 volumio sudo[16199]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:24 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:02:24 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:02:24 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:02:24 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:02:24 volumio ntpd[16180]: Soliciting pool server 144.91.116.85 Apr 11 06:02:24 volumio ntpd[16180]: Soliciting pool server 195.201.20.16 Apr 11 06:02:25 volumio volumio[16120]: info: Loading plugin "networkfs"... Apr 11 06:02:25 volumio volumio-remote-updater[543]: [2024-04-11 06:02:25] [connect] Successful connection Apr 11 06:02:25 volumio volumio[16120]: info: Starting Udev Watcher for removable devices Apr 11 06:02:25 volumio volumio[16120]: info: Ignoring mount for partition: boot Apr 11 06:02:25 volumio volumio[16120]: info: Ignoring mount for partition: volumio Apr 11 06:02:25 volumio volumio[16120]: info: Ignoring mount for partition: volumio_data Apr 11 06:02:25 volumio volumio[16120]: info: Mounting Device INTENSO Apr 11 06:02:25 volumio sudo[16210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/INTENSO -o noatime Apr 11 06:02:25 volumio sudo[16210]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:25 volumio sudo[16210]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:25 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:02:25 volumio volumio[16120]: info: Loading plugin "alarm-clock"... Apr 11 06:02:25 volumio ntpd[16180]: Soliciting pool server 185.232.69.65 Apr 11 06:02:25 volumio ntpd[16180]: Soliciting pool server 144.76.139.8 Apr 11 06:02:25 volumio volumio[16120]: info: Loading plugin "airplay_emulation"... Apr 11 06:02:25 volumio volumio[16120]: info: Starting Shairport Sync Apr 11 06:02:25 volumio volumio[16120]: info: Loading plugin "last_100"... Apr 11 06:02:25 volumio volumio[16120]: info: Loading plugin "webradio"... Apr 11 06:02:25 volumio ntpd[16180]: Soliciting pool server 193.203.3.171 Apr 11 06:02:26 volumio volumio[16120]: info: Loading plugin "i2s_dacs"... Apr 11 06:02:26 volumio volumio[16120]: info: Loading plugin "volumiodiscovery"... Apr 11 06:02:26 volumio volumio[16120]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:02:26 volumio volumio[16120]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:02:26 volumio node[16120]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 11 06:02:26 volumio volumio[16120]: *** WARNING *** For more information see Apr 11 06:02:26 volumio volumio[16120]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:02:26 volumio volumio[16120]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:02:26 volumio volumio[16120]: *** WARNING *** For more information see Apr 11 06:02:26 volumio node[16120]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:02:26 volumio node[16120]: *** WARNING *** For more information see Apr 11 06:02:26 volumio node[16120]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 11 06:02:26 volumio node[16120]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 11 06:02:26 volumio node[16120]: *** WARNING *** For more information see Apr 11 06:02:26 volumio volumio[16120]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 11 06:02:26 volumio volumio[16120]: info: Discovery: Started advertising with name: undefined Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 11 06:02:26 volumio volumio[16120]: info: Loading plugin "outputs"... Apr 11 06:02:26 volumio volumio[16120]: info: Loading plugin "albumart"... Apr 11 06:02:26 volumio volumio[16120]: info: Plugin example_plugin is not enabled Apr 11 06:02:26 volumio volumio[16120]: info: Loading plugin "inputs"... Apr 11 06:02:26 volumio volumio[16120]: info: Loading plugin "updater_comm"... Apr 11 06:02:26 volumio mpd[16204]: Apr 11 06:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:02:26 volumio mpd[16204]: Apr 11 06:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:02:26 volumio mpd[16204]: Apr 11 06:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:02:26 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:02:26 volumio sudo[16199]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:26 volumio volumio[16120]: info: Plugin mpdemulation is not enabled Apr 11 06:02:26 volumio volumio[16120]: info: Loading plugin "rest_api"... Apr 11 06:02:26 volumio volumio[16120]: info: Loading plugin "websocket"... Apr 11 06:02:26 volumio volumio[16120]: info: Loading i18n strings for locale de Apr 11 06:02:26 volumio volumio[16120]: Updating browse sources language Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:02:26 volumio volumio[16120]: Forking 3 albumart workers Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::initPlayerControls Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:02:26 volumio volumio[16120]: Express server listening on port 3000 Apr 11 06:02:26 volumio volumio[16120]: [Metrics] WebUI: 5s 155.54ms Apr 11 06:02:26 volumio volumio[16120]: info: CoreStateMachine::resetVolumioState Apr 11 06:02:26 volumio volumio[16120]: info: CoreStateMachine::getcurrentVolume Apr 11 06:02:26 volumio volumio[16120]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:02:26 volumio volumio[16120]: info: MPD Permissions set Apr 11 06:02:26 volumio volumio-remote-updater[543]: [2024-04-11 06:02:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712808145 101 Apr 11 06:02:26 volumio ntpd[16180]: Soliciting pool server 194.164.164.175 Apr 11 06:02:26 volumio ntpd[16180]: Soliciting pool server 129.70.132.37 Apr 11 06:02:26 volumio ntpd[16180]: Soliciting pool server 75.119.140.230 Apr 11 06:02:26 volumio volumio[16120]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 11 06:02:26 volumio volumio[16120]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:02:26 volumio volumio[16120]: info: MPD running with PID16204 Apr 11 06:02:26 volumio volumio[16120]: ,establishing connection Apr 11 06:02:27 volumio ntpd[16180]: Soliciting pool server 213.239.234.28 Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: Cannot read play queue from file Apr 11 06:02:27 volumio volumio[16120]: error: updateQueue error: null Apr 11 06:02:27 volumio volumio[16120]: info: CoreStateMachine::setRepeat false single undefined Apr 11 06:02:27 volumio volumio[16120]: info: CoreStateMachine::pushState Apr 11 06:02:27 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Apr 11 06:02:27 volumio volumio[16120]: info: CoreStateMachine::setRandom null Apr 11 06:02:27 volumio volumio[16120]: info: CoreStateMachine::pushState Apr 11 06:02:27 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Apr 11 06:02:27 volumio volumio[16120]: info: Setting Device type: Raspberry PI Apr 11 06:02:27 volumio volumio[16120]: info: Completed loading Core Plugins Apr 11 06:02:27 volumio volumio[16120]: info: Preparing to generate the ALSA configuration file Apr 11 06:02:27 volumio volumio[16120]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:02:27 volumio volumio[16120]: info: CoreStateMachine::pushState Apr 11 06:02:27 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Apr 11 06:02:27 volumio volumio[16120]: info: CoreStateMachine::updateTrackBlock Apr 11 06:02:27 volumio volumio[16120]: info: CorePlayQueue::getTrackBlock Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 06:02:27 volumio volumio[16120]: error: updateQueue error: null Apr 11 06:02:27 volumio volumio[16120]: info: Asound.conf file unchanged, so no further update is needed Apr 11 06:02:27 volumio volumio[16120]: info: Output device has changed, restarting MPD Apr 11 06:02:27 volumio volumio[16120]: info: Output device has changed, restarting Shairport Sync Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:27 volumio sudo[16264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 06:02:27 volumio sudo[16264]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:27 volumio sudo[16264]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:27 volumio sudo[16266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 06:02:27 volumio sudo[16266]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:27 volumio volumio[16120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:02:27 volumio volumio[16120]: info: ___________ START PLUGINS ___________ Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:02:27 volumio volumio[16120]: info: [1712808147438] CoreMusicLibrary::Adding element Media Servers Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:02:27 volumio systemd[1]: Stopping Music Player Daemon... Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:27 volumio systemd[1]: mpd.service: Succeeded. Apr 11 06:02:27 volumio systemd[1]: Stopped Music Player Daemon. Apr 11 06:02:27 volumio volumio[16120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:27 volumio systemd[1]: Starting Music Player Daemon... Apr 11 06:02:27 volumio volumio[16120]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:02:27 volumio volumio[16120]: info: [1712808147628] CoreMusicLibrary::Adding element Last_100 Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 11 06:02:27 volumio volumio[16120]: info: [1712808147634] CoreMusicLibrary::Adding element Webradio Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 06:02:27 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 11 06:02:27 volumio volumio[16120]: info: Volumio Calling Home Apr 11 06:02:27 volumio sudo[16189]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:27 volumio volumio[16120]: info: Completed starting Core Plugins Apr 11 06:02:27 volumio volumio[16120]: info: ------------------------------------------- Apr 11 06:02:27 volumio volumio[16120]: info: ----- MyVolumio plugins startup ---- Apr 11 06:02:27 volumio volumio[16120]: info: ------------------------------------------- Apr 11 06:02:27 volumio volumio[16120]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 11 06:02:27 volumio volumio[16120]: Starting albumart workers Apr 11 06:02:27 volumio volumio[16120]: Starting albumart workers Apr 11 06:02:27 volumio ntpd[16180]: Soliciting pool server 45.142.247.74 Apr 11 06:02:27 volumio ntpd[16180]: Soliciting pool server 144.76.43.40 Apr 11 06:02:27 volumio ntpd[16180]: Soliciting pool server 144.76.76.107 Apr 11 06:02:27 volumio ntpd[16180]: Soliciting pool server 81.3.27.46 Apr 11 06:02:27 volumio volumio[16120]: Starting albumart workers Apr 11 06:02:27 volumio volumio[16120]: info: MPD Permissions set Apr 11 06:02:27 volumio volumio[16120]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'HDMI',0 Apr 11 06:02:27 volumio volumio[16120]: info: Volumio called home Apr 11 06:02:28 volumio volumio[16120]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 06:02:28 volumio volumio[16120]: info: VolumeController:: Volume=undefined Mute =false Apr 11 06:02:28 volumio volumio[16120]: info: CoreStateMachine::pushState Apr 11 06:02:28 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::volumioPushState Apr 11 06:02:28 volumio volumio[16120]: info: Starting Shairport Sync Apr 11 06:02:28 volumio volumio[16120]: info: Starting Shairport Sync Apr 11 06:02:28 volumio volumio[16120]: info: Starting Shairport Sync Apr 11 06:02:28 volumio sudo[16290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:02:28 volumio sudo[16290]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:28 volumio sudo[16292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:02:28 volumio sudo[16292]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:02:28 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:02:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:28 volumio sudo[16290]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:02:28 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 11 06:02:28 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:02:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:28 volumio sudo[16294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 06:02:28 volumio sudo[16292]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:28 volumio sudo[16294]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 06:02:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 11 06:02:28 volumio volumio[16120]: info: Shairport-Sync Started Apr 11 06:02:28 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 11 06:02:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 11 06:02:28 volumio volumio[16120]: Error adding Membership: Error: addMembership EINVAL Apr 11 06:02:28 volumio volumio[16120]: info: Shairport-Sync Started Apr 11 06:02:28 volumio sudo[16294]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:28 volumio volumio[16120]: info: Shairport-Sync Started Apr 11 06:02:28 volumio volumio[16120]: info: CoreCommandRouter::volumioGetState Apr 11 06:02:28 volumio volumio[16120]: info: CorePlayQueue::getTrack 0 Apr 11 06:02:28 volumio ntpd[16180]: Soliciting pool server 85.215.189.120 Apr 11 06:02:28 volumio ntpd[16180]: Soliciting pool server 81.169.204.195 Apr 11 06:02:29 volumio mpd[16282]: Apr 11 06:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 06:02:29 volumio mpd[16282]: Apr 11 06:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 11 06:02:29 volumio mpd[16282]: Apr 11 06:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 11 06:02:29 volumio systemd[1]: Started Music Player Daemon. Apr 11 06:02:29 volumio sudo[16266]: pam_unix(sudo:session): session closed for user root Apr 11 06:02:29 volumio volumio[16120]: error: updateQueue error: null Apr 11 06:02:29 volumio ntpd[16180]: Soliciting pool server 2a02:8106:21:9400::7 Apr 11 06:02:31 volumio volumio[16120]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Apr 11 06:02:31 volumio volumio[16120]: info: Discovery: Started advertising with name: undefined Apr 11 06:02:34 volumio volumio[16120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:02:34 volumio volumio[16120]: TypeError: Cannot read property 'charAt' of undefined Apr 11 06:02:34 volumio volumio[16120]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Apr 11 06:02:34 volumio volumio[16120]: at listOnTimeout (internal/timers.js:554:17) Apr 11 06:02:34 volumio volumio[16120]: at processTimers (internal/timers.js:497:7) Apr 11 06:02:34 volumio volumio[16120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 06:02:34 volumio sudo[16340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 06:01 Apr 11 06:02:34 volumio sudo[16340]: 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"