-- Logs begin at Mon 2024-10-28 12:42:17 UTC, end at Mon 2024-10-28 12:47:14 UTC. -- Oct 28 12:46:00 volumio volumio[2797]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 12:46:00 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:00 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:00 volumio volumio[2797]: info: CoreStateMachine::getState Oct 28 12:46:00 volumio volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:00 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 12:46:00 volumio volumio[2797]: info: Listing playlists Oct 28 12:46:00 volumio volumio[2797]: info: Shairport-Sync Started Oct 28 12:46:00 volumio volumio[2797]: Error adding Membership: Error: addMembership EINVAL Oct 28 12:46:00 volumio volumio[2797]: info: Shairport-Sync Started Oct 28 12:46:00 volumio volumio-remote-updater[621]: [2024-10-28 12:46:00] [connect] Successful connection Oct 28 12:46:00 volumio volumio-remote-updater[621]: [2024-10-28 12:46:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730119560 101 Oct 28 12:46:01 volumio volumio[2797]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:01 volumio volumio[2797]: info: CoreStateMachine::getState Oct 28 12:46:01 volumio volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:02 volumio volumio[2797]: info: Oct 28 12:46:02 volumio volumio[2797]: ---------------------------- Client requests Volume NaN Oct 28 12:46:02 volumio volumio[2797]: info: VolumeController::SetAlsaVolumeNaN Oct 28 12:46:02 volumio volumio[2797]: info: CoreStateMachine::pushState Oct 28 12:46:02 volumio volumio[2797]: info: CoreStateMachine::getState Oct 28 12:46:02 volumio volumio[2797]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:02 volumio volumio[2797]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:02 volumio volumio[2797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:02 volumio volumio[2797]: info: Setting volume on startup at undefined Oct 28 12:46:03 volumio volumio[2797]: Discovery: ecc TypeError: argument 8 must be an integer (port) Oct 28 12:46:03 volumio volumio[2797]: Discovery: StartAdv! false Oct 28 12:46:03 volumio volumio[2797]: Discovery: Started advertising... undefined - false Oct 28 12:46:07 volumio volumio[2797]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:07 volumio volumio[2797]: TypeError: Cannot read property 'charAt' of undefined Oct 28 12:46:07 volumio volumio[2797]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Oct 28 12:46:07 volumio volumio[2797]: at ontimeout (timers.js:482:11) Oct 28 12:46:07 volumio volumio[2797]: at tryOnTimeout (timers.js:317:5) Oct 28 12:46:07 volumio volumio[2797]: at Timer.listOnTimeout (timers.js:277:5) Oct 28 12:46:07 volumio volumio[2797]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:08 volumio sudo[2891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 12:45 Oct 28 12:46:08 volumio sudo[2891]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:08 volumio sudo[2891]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:08 volumio volumio-remote-updater[621]: [2024-10-28 12:46:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 28 12:46:08 volumio volumio-remote-updater[621]: [2024-10-28 12:46:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 28 12:46:08 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Oct 28 12:46:08 volumio systemd[1]: Unit volumio.service entered failed state. Oct 28 12:46:08 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:08 volumio systemd[1]: Started dynamicswap service. Oct 28 12:46:08 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Oct 28 12:46:08 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:08 volumio systemd[1]: Started dynamicswap service. Oct 28 12:46:08 volumio systemd[1]: Stopping Volumio Backend Module... Oct 28 12:46:08 volumio systemd[1]: Starting Volumio Backend Module... Oct 28 12:46:08 volumio systemd[1]: Started Volumio Backend Module. Oct 28 12:46:09 volumio volumio[2913]: info: ------------------------------------------- Oct 28 12:46:09 volumio volumio[2913]: info: ----- Volumio2 ---- Oct 28 12:46:09 volumio volumio[2913]: info: ------------------------------------------- Oct 28 12:46:09 volumio volumio[2913]: info: ----- System startup ---- Oct 28 12:46:09 volumio volumio[2913]: info: ------------------------------------------- Oct 28 12:46:09 volumio volumio[2913]: info: MYVOLUMIO Environment detected Oct 28 12:46:10 volumio volumio[2913]: info: Plugin folders cleanup Oct 28 12:46:10 volumio volumio[2913]: info: Scanning into folder /volumio/app/plugins/ Oct 28 12:46:10 volumio volumio[2913]: info: Scanning category audio_interface Oct 28 12:46:10 volumio volumio[2913]: info: Scanning category miscellanea Oct 28 12:46:10 volumio volumio[2913]: info: Scanning category music_service Oct 28 12:46:10 volumio volumio[2913]: info: Scanning category plugins.json Oct 28 12:46:10 volumio volumio[2913]: info: Scanning category system_controller Oct 28 12:46:10 volumio volumio[2913]: info: Scanning category user_interface Oct 28 12:46:10 volumio volumio[2913]: info: Scanning into folder /data/plugins/ Oct 28 12:46:10 volumio volumio[2913]: info: Scanning category miscellanea Oct 28 12:46:10 volumio volumio[2913]: info: Scanning category music_service Oct 28 12:46:10 volumio volumio[2913]: info: Plugin folders cleanup completed Oct 28 12:46:10 volumio volumio[2913]: info: ------------------------------------------- Oct 28 12:46:10 volumio volumio[2913]: info: ----- Core plugins startup ---- Oct 28 12:46:10 volumio volumio[2913]: info: ------------------------------------------- Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 12:46:10 volumio volumio[2913]: info: Adding plugin upnp to MyMusic Plugins Oct 28 12:46:10 volumio volumio[2913]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 12:46:10 volumio volumio[2913]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugins from folder /data/plugins/ Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "system"... Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "appearance"... Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "network"... Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "services"... Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "alsa_controller"... Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "wizard"... Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "volumio_command_line_client"... Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "upnp"... Oct 28 12:46:10 volumio volumio[2913]: info: [1730119570874] Starting Upmpd Daemon Oct 28 12:46:10 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "my_music"... Oct 28 12:46:10 volumio volumio[2913]: info: Loading plugin "mpd"... Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "upnp_browser"... Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "networkfs"... Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "alarm-clock"... Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "airplay_emulation"... Oct 28 12:46:11 volumio volumio[2913]: info: Starting Shairport Sync Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "last_100"... Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "webradio"... Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "i2s_dacs"... Oct 28 12:46:11 volumio volumio[2913]: info: I2S DAC not set, start Auto-detection Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "volumiodiscovery"... Oct 28 12:46:11 volumio volumio[2913]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:11 volumio node[2913]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:11 volumio node[2913]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:11 volumio node[2913]: *** WARNING *** For more information see Oct 28 12:46:11 volumio node[2913]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:11 volumio node[2913]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:11 volumio node[2913]: *** WARNING *** For more information see Oct 28 12:46:11 volumio volumio[2913]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:11 volumio volumio[2913]: *** WARNING *** For more information see Oct 28 12:46:11 volumio volumio[2913]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:11 volumio volumio[2913]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:11 volumio volumio[2913]: *** WARNING *** For more information see Oct 28 12:46:11 volumio volumio[2913]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 12:46:11 volumio volumio[2913]: Discovery: StartAdv! undefined Oct 28 12:46:11 volumio volumio[2913]: Discovery: Started advertising... undefined - undefined Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:11 volumio volumio[2913]: info: Plugin now_playing is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Plugin bandcamp is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Plugin jellyfin is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "outputs"... Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "albumart"... Oct 28 12:46:11 volumio volumio[2913]: info: Plugin example_plugin is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "inputs"... Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "updater_comm"... Oct 28 12:46:11 volumio volumio[2913]: info: Plugin mpdemulation is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "rest_api"... Oct 28 12:46:11 volumio volumio[2913]: info: Loading plugin "websocket"... Oct 28 12:46:11 volumio volumio[2913]: info: Plugin lastfm is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Plugin personal_radio is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Plugin radio_paradise is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Plugin spop is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: Plugin swarmstation is not enabled Oct 28 12:46:11 volumio volumio[2913]: info: ___________ START PLUGINS ___________ Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:11 volumio volumio[2913]: info: [1730119571772] CoreMusicLibrary::Adding element Media Servers Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:11 volumio volumio[2913]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:46:11 volumio volumio[2913]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:11 volumio volumio[2913]: info: [1730119571838] CoreMusicLibrary::Adding element Last_100 Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:11 volumio volumio[2913]: info: [1730119571840] CoreMusicLibrary::Adding element Webradio Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 12:46:11 volumio volumio[2913]: info: ------------------------------------------- Oct 28 12:46:11 volumio volumio[2913]: info: ----- MyVolumio plugins startup ---- Oct 28 12:46:11 volumio volumio[2913]: info: ------------------------------------------- Oct 28 12:46:11 volumio volumio[2913]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 12:46:11 volumio volumio[2913]: info: Loading i18n strings for locale fr Oct 28 12:46:11 volumio volumio[2913]: Updating browse sources language Oct 28 12:46:11 volumio volumio[2913]: Cannot find translation for sourceMedia Servers Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:11 volumio volumio[2913]: Forking 1 albumart workers Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::initPlayerControls Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: BOOT COMPLETED Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:46:11 volumio volumio[2913]: Express server listening on port 3000 Oct 28 12:46:11 volumio volumio[2913]: info: Setting Device type: x86 Oct 28 12:46:11 volumio volumio[2913]: Volumio Calling Home Oct 28 12:46:11 volumio volumio[2913]: info: CoreStateMachine::resetVolumioState Oct 28 12:46:11 volumio volumio[2913]: info: CoreStateMachine::getcurrentVolume Oct 28 12:46:11 volumio volumio[2913]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 12:46:12 volumio volumio[2913]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 28 12:46:12 volumio volumio[2913]: info: MPD running with PID643 ,establishing connection Oct 28 12:46:12 volumio sudo[2974]: pam_unix(sudo:auth): conversation failed Oct 28 12:46:12 volumio sudo[2974]: pam_unix(sudo:auth): auth could not identify password for [volumio] Oct 28 12:46:12 volumio volumio[2913]: info: VolumeController:: Volume=0 Mute =false Oct 28 12:46:12 volumio volumio[2913]: info: CoreStateMachine::pushState Oct 28 12:46:12 volumio volumio[2913]: info: CoreStateMachine::getState Oct 28 12:46:12 volumio volumio[2913]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:12 volumio volumio[2913]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:12 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:12 volumio volumio[2913]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Oct 28 12:46:12 volumio volumio[2913]: sudo: no tty present and no askpass program specified Oct 28 12:46:12 volumio volumio[2913]: info: Reloading queue from file Oct 28 12:46:12 volumio volumio[2913]: info: Oct 28 12:46:12 volumio volumio[2913]: info: CoreStateMachine::setRepeat true single undefined Oct 28 12:46:12 volumio volumio[2913]: info: CoreStateMachine::pushState Oct 28 12:46:12 volumio volumio[2913]: info: CoreStateMachine::getState Oct 28 12:46:12 volumio volumio[2913]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:12 volumio volumio[2913]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:12 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:12 volumio volumio[2913]: info: CoreStateMachine::setRandom true Oct 28 12:46:12 volumio volumio[2913]: info: CoreStateMachine::pushState Oct 28 12:46:12 volumio volumio[2913]: info: CoreStateMachine::getState Oct 28 12:46:12 volumio volumio[2913]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:12 volumio volumio[2913]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:12 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:12 volumio sudo[2977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:46:12 volumio sudo[2977]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:12 volumio sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:46:12 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:12 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:46:12 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:46:12 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:46:12 volumio shairport-sync[2981]: Successful Startup Oct 28 12:46:12 volumio sudo[2977]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:12 volumio volumio[2913]: info: Shairport-Sync Started Oct 28 12:46:12 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:46:12 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:46:12 volumio volumio[2913]: Error adding Membership: Error: addMembership EINVAL Oct 28 12:46:12 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:46:12 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:12 volumio shairport-sync[2986]: Successful Startup Oct 28 12:46:12 volumio volumio[2913]: info: Shairport-Sync Started Oct 28 12:46:12 volumio volumio[2913]: Starting albumart workers Oct 28 12:46:13 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 12:46:13 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 12:46:13 volumio volumio-remote-updater[621]: [2024-10-28 12:46:13] [connect] Successful connection Oct 28 12:46:13 volumio volumio-remote-updater[621]: [2024-10-28 12:46:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730119573 101 Oct 28 12:46:13 volumio volumio[2913]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 12:46:13 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:13 volumio volumio[2913]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:13 volumio volumio[2913]: info: CoreStateMachine::getState Oct 28 12:46:13 volumio volumio[2913]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:13 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 12:46:13 volumio volumio[2913]: info: Listing playlists Oct 28 12:46:14 volumio volumio[2913]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:14 volumio volumio[2913]: info: CoreStateMachine::getState Oct 28 12:46:14 volumio volumio[2913]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:15 volumio volumio[2913]: info: Oct 28 12:46:15 volumio volumio[2913]: ---------------------------- Client requests Volume NaN Oct 28 12:46:15 volumio volumio[2913]: info: VolumeController::SetAlsaVolumeNaN Oct 28 12:46:15 volumio volumio[2913]: info: CoreStateMachine::pushState Oct 28 12:46:15 volumio volumio[2913]: info: CoreStateMachine::getState Oct 28 12:46:15 volumio volumio[2913]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:15 volumio volumio[2913]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:15 volumio volumio[2913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:15 volumio volumio[2913]: info: Setting volume on startup at undefined Oct 28 12:46:16 volumio volumio[2913]: Discovery: ecc TypeError: argument 8 must be an integer (port) Oct 28 12:46:16 volumio volumio[2913]: Discovery: StartAdv! false Oct 28 12:46:16 volumio volumio[2913]: Discovery: Started advertising... undefined - false Oct 28 12:46:20 volumio volumio[2913]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:20 volumio volumio[2913]: TypeError: Cannot read property 'charAt' of undefined Oct 28 12:46:20 volumio volumio[2913]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Oct 28 12:46:20 volumio volumio[2913]: at ontimeout (timers.js:482:11) Oct 28 12:46:20 volumio volumio[2913]: at tryOnTimeout (timers.js:317:5) Oct 28 12:46:20 volumio volumio[2913]: at Timer.listOnTimeout (timers.js:277:5) Oct 28 12:46:20 volumio volumio[2913]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:21 volumio sudo[3007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 12:45 Oct 28 12:46:21 volumio sudo[3007]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:21 volumio sudo[3007]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:21 volumio volumio-remote-updater[621]: [2024-10-28 12:46:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 28 12:46:21 volumio volumio-remote-updater[621]: [2024-10-28 12:46:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 28 12:46:21 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Oct 28 12:46:21 volumio systemd[1]: Unit volumio.service entered failed state. Oct 28 12:46:21 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:21 volumio systemd[1]: Started dynamicswap service. Oct 28 12:46:21 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Oct 28 12:46:21 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:21 volumio systemd[1]: Started dynamicswap service. Oct 28 12:46:21 volumio systemd[1]: Stopping Volumio Backend Module... Oct 28 12:46:21 volumio systemd[1]: Starting Volumio Backend Module... Oct 28 12:46:21 volumio systemd[1]: Started Volumio Backend Module. Oct 28 12:46:22 volumio volumio[3025]: info: ------------------------------------------- Oct 28 12:46:22 volumio volumio[3025]: info: ----- Volumio2 ---- Oct 28 12:46:22 volumio volumio[3025]: info: ------------------------------------------- Oct 28 12:46:22 volumio volumio[3025]: info: ----- System startup ---- Oct 28 12:46:22 volumio volumio[3025]: info: ------------------------------------------- Oct 28 12:46:22 volumio volumio[3025]: info: MYVOLUMIO Environment detected Oct 28 12:46:23 volumio volumio[3025]: info: Plugin folders cleanup Oct 28 12:46:23 volumio volumio[3025]: info: Scanning into folder /volumio/app/plugins/ Oct 28 12:46:23 volumio volumio[3025]: info: Scanning category audio_interface Oct 28 12:46:23 volumio volumio[3025]: info: Scanning category miscellanea Oct 28 12:46:23 volumio volumio[3025]: info: Scanning category music_service Oct 28 12:46:23 volumio volumio[3025]: info: Scanning category plugins.json Oct 28 12:46:23 volumio volumio[3025]: info: Scanning category system_controller Oct 28 12:46:23 volumio volumio[3025]: info: Scanning category user_interface Oct 28 12:46:23 volumio volumio[3025]: info: Scanning into folder /data/plugins/ Oct 28 12:46:23 volumio volumio[3025]: info: Scanning category miscellanea Oct 28 12:46:23 volumio volumio[3025]: info: Scanning category music_service Oct 28 12:46:23 volumio volumio[3025]: info: Plugin folders cleanup completed Oct 28 12:46:23 volumio volumio[3025]: info: ------------------------------------------- Oct 28 12:46:23 volumio volumio[3025]: info: ----- Core plugins startup ---- Oct 28 12:46:23 volumio volumio[3025]: info: ------------------------------------------- Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 12:46:23 volumio volumio[3025]: info: Adding plugin upnp to MyMusic Plugins Oct 28 12:46:23 volumio volumio[3025]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 12:46:23 volumio volumio[3025]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugins from folder /data/plugins/ Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "system"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "appearance"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "network"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "services"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "alsa_controller"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "wizard"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "volumio_command_line_client"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "upnp"... Oct 28 12:46:23 volumio volumio[3025]: info: [1730119583697] Starting Upmpd Daemon Oct 28 12:46:23 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "my_music"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "mpd"... Oct 28 12:46:23 volumio volumio[3025]: info: Loading plugin "upnp_browser"... Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "networkfs"... Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "alarm-clock"... Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "airplay_emulation"... Oct 28 12:46:24 volumio volumio[3025]: info: Starting Shairport Sync Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "last_100"... Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "webradio"... Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "i2s_dacs"... Oct 28 12:46:24 volumio volumio[3025]: info: I2S DAC not set, start Auto-detection Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "volumiodiscovery"... Oct 28 12:46:24 volumio volumio[3025]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:24 volumio node[3025]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:24 volumio node[3025]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:24 volumio node[3025]: *** WARNING *** For more information see Oct 28 12:46:24 volumio node[3025]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:24 volumio node[3025]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:24 volumio node[3025]: *** WARNING *** For more information see Oct 28 12:46:24 volumio volumio[3025]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:24 volumio volumio[3025]: *** WARNING *** For more information see Oct 28 12:46:24 volumio volumio[3025]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:24 volumio volumio[3025]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:24 volumio volumio[3025]: *** WARNING *** For more information see Oct 28 12:46:24 volumio volumio[3025]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 12:46:24 volumio volumio[3025]: Discovery: StartAdv! undefined Oct 28 12:46:24 volumio volumio[3025]: Discovery: Started advertising... undefined - undefined Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:24 volumio volumio[3025]: info: Plugin now_playing is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Plugin bandcamp is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Plugin jellyfin is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "outputs"... Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "albumart"... Oct 28 12:46:24 volumio volumio[3025]: info: Plugin example_plugin is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "inputs"... Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "updater_comm"... Oct 28 12:46:24 volumio volumio[3025]: info: Plugin mpdemulation is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "rest_api"... Oct 28 12:46:24 volumio volumio[3025]: info: Loading plugin "websocket"... Oct 28 12:46:24 volumio volumio[3025]: info: Plugin lastfm is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Plugin personal_radio is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Plugin radio_paradise is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Plugin spop is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: Plugin swarmstation is not enabled Oct 28 12:46:24 volumio volumio[3025]: info: ___________ START PLUGINS ___________ Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:24 volumio volumio[3025]: info: [1730119584560] CoreMusicLibrary::Adding element Media Servers Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:24 volumio volumio[3025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:46:24 volumio volumio[3025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:24 volumio volumio[3025]: info: [1730119584619] CoreMusicLibrary::Adding element Last_100 Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:24 volumio volumio[3025]: info: [1730119584621] CoreMusicLibrary::Adding element Webradio Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 12:46:24 volumio volumio[3025]: info: ------------------------------------------- Oct 28 12:46:24 volumio volumio[3025]: info: ----- MyVolumio plugins startup ---- Oct 28 12:46:24 volumio volumio[3025]: info: ------------------------------------------- Oct 28 12:46:24 volumio volumio[3025]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 12:46:24 volumio volumio[3025]: info: Loading i18n strings for locale fr Oct 28 12:46:24 volumio volumio[3025]: Updating browse sources language Oct 28 12:46:24 volumio volumio[3025]: Cannot find translation for sourceMedia Servers Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::initPlayerControls Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: BOOT COMPLETED Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:46:24 volumio volumio[3025]: Express server listening on port 3000 Oct 28 12:46:24 volumio volumio[3025]: info: Setting Device type: x86 Oct 28 12:46:24 volumio volumio[3025]: Volumio Calling Home Oct 28 12:46:24 volumio volumio[3025]: Forking 1 albumart workers Oct 28 12:46:24 volumio volumio[3025]: info: CoreStateMachine::resetVolumioState Oct 28 12:46:24 volumio volumio[3025]: info: CoreStateMachine::getcurrentVolume Oct 28 12:46:24 volumio volumio[3025]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 12:46:24 volumio volumio[3025]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 28 12:46:24 volumio volumio[3025]: info: MPD running with PID643 ,establishing connection Oct 28 12:46:24 volumio sudo[3090]: pam_unix(sudo:auth): conversation failed Oct 28 12:46:24 volumio sudo[3090]: pam_unix(sudo:auth): auth could not identify password for [volumio] Oct 28 12:46:25 volumio volumio[3025]: info: VolumeController:: Volume=0 Mute =false Oct 28 12:46:25 volumio volumio[3025]: info: CoreStateMachine::pushState Oct 28 12:46:25 volumio volumio[3025]: info: CoreStateMachine::getState Oct 28 12:46:25 volumio volumio[3025]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:25 volumio volumio[3025]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:25 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:25 volumio volumio[3025]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Oct 28 12:46:25 volumio volumio[3025]: sudo: no tty present and no askpass program specified Oct 28 12:46:25 volumio volumio[3025]: info: Reloading queue from file Oct 28 12:46:25 volumio volumio[3025]: info: Oct 28 12:46:25 volumio volumio[3025]: info: CoreStateMachine::setRepeat true single undefined Oct 28 12:46:25 volumio volumio[3025]: info: CoreStateMachine::pushState Oct 28 12:46:25 volumio volumio[3025]: info: CoreStateMachine::getState Oct 28 12:46:25 volumio volumio[3025]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:25 volumio volumio[3025]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:25 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:25 volumio volumio[3025]: info: CoreStateMachine::setRandom true Oct 28 12:46:25 volumio volumio[3025]: info: CoreStateMachine::pushState Oct 28 12:46:25 volumio volumio[3025]: info: CoreStateMachine::getState Oct 28 12:46:25 volumio volumio[3025]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:25 volumio volumio[3025]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:25 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:25 volumio sudo[3093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:46:25 volumio sudo[3093]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:25 volumio sudo[3094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:46:25 volumio sudo[3094]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:25 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:46:25 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:46:25 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:46:25 volumio sudo[3093]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:25 volumio shairport-sync[3097]: Successful Startup Oct 28 12:46:25 volumio sudo[3094]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:25 volumio volumio[3025]: info: Shairport-Sync Started Oct 28 12:46:25 volumio volumio[3025]: Error adding Membership: Error: addMembership EINVAL Oct 28 12:46:25 volumio volumio[3025]: info: Shairport-Sync Started Oct 28 12:46:25 volumio volumio[3025]: Starting albumart workers Oct 28 12:46:25 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 12:46:25 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 12:46:26 volumio volumio-remote-updater[621]: [2024-10-28 12:46:26] [connect] Successful connection Oct 28 12:46:26 volumio volumio-remote-updater[621]: [2024-10-28 12:46:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730119586 101 Oct 28 12:46:26 volumio volumio[3025]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 12:46:26 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:26 volumio volumio[3025]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:26 volumio volumio[3025]: info: CoreStateMachine::getState Oct 28 12:46:26 volumio volumio[3025]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:26 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 12:46:26 volumio volumio[3025]: info: Listing playlists Oct 28 12:46:27 volumio volumio[3025]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:27 volumio volumio[3025]: info: CoreStateMachine::getState Oct 28 12:46:27 volumio volumio[3025]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:28 volumio volumio[3025]: info: Oct 28 12:46:28 volumio volumio[3025]: ---------------------------- Client requests Volume NaN Oct 28 12:46:28 volumio volumio[3025]: info: VolumeController::SetAlsaVolumeNaN Oct 28 12:46:28 volumio volumio[3025]: info: CoreStateMachine::pushState Oct 28 12:46:28 volumio volumio[3025]: info: CoreStateMachine::getState Oct 28 12:46:28 volumio volumio[3025]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:28 volumio volumio[3025]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:28 volumio volumio[3025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:28 volumio volumio[3025]: info: Setting volume on startup at undefined Oct 28 12:46:29 volumio volumio[3025]: Discovery: ecc TypeError: argument 8 must be an integer (port) Oct 28 12:46:29 volumio volumio[3025]: Discovery: StartAdv! false Oct 28 12:46:29 volumio volumio[3025]: Discovery: Started advertising... undefined - false Oct 28 12:46:33 volumio volumio[3025]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:33 volumio volumio[3025]: TypeError: Cannot read property 'charAt' of undefined Oct 28 12:46:33 volumio volumio[3025]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Oct 28 12:46:33 volumio volumio[3025]: at ontimeout (timers.js:482:11) Oct 28 12:46:33 volumio volumio[3025]: at tryOnTimeout (timers.js:317:5) Oct 28 12:46:33 volumio volumio[3025]: at Timer.listOnTimeout (timers.js:277:5) Oct 28 12:46:33 volumio volumio[3025]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:34 volumio sudo[3120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 12:45 Oct 28 12:46:34 volumio sudo[3120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:34 volumio sudo[3120]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:34 volumio volumio-remote-updater[621]: [2024-10-28 12:46:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 28 12:46:34 volumio volumio-remote-updater[621]: [2024-10-28 12:46:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 28 12:46:34 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Oct 28 12:46:34 volumio systemd[1]: Unit volumio.service entered failed state. Oct 28 12:46:34 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:34 volumio systemd[1]: Started dynamicswap service. Oct 28 12:46:34 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Oct 28 12:46:34 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:34 volumio systemd[1]: Started dynamicswap service. Oct 28 12:46:34 volumio systemd[1]: Stopping Volumio Backend Module... Oct 28 12:46:34 volumio systemd[1]: Starting Volumio Backend Module... Oct 28 12:46:34 volumio systemd[1]: Started Volumio Backend Module. Oct 28 12:46:35 volumio volumio[3138]: info: ------------------------------------------- Oct 28 12:46:35 volumio volumio[3138]: info: ----- Volumio2 ---- Oct 28 12:46:35 volumio volumio[3138]: info: ------------------------------------------- Oct 28 12:46:35 volumio volumio[3138]: info: ----- System startup ---- Oct 28 12:46:35 volumio volumio[3138]: info: ------------------------------------------- Oct 28 12:46:35 volumio volumio[3138]: info: MYVOLUMIO Environment detected Oct 28 12:46:35 volumio volumio[3138]: info: Plugin folders cleanup Oct 28 12:46:35 volumio volumio[3138]: info: Scanning into folder /volumio/app/plugins/ Oct 28 12:46:35 volumio volumio[3138]: info: Scanning category audio_interface Oct 28 12:46:35 volumio volumio[3138]: info: Scanning category miscellanea Oct 28 12:46:35 volumio volumio[3138]: info: Scanning category music_service Oct 28 12:46:35 volumio volumio[3138]: info: Scanning category plugins.json Oct 28 12:46:35 volumio volumio[3138]: info: Scanning category system_controller Oct 28 12:46:35 volumio volumio[3138]: info: Scanning category user_interface Oct 28 12:46:35 volumio volumio[3138]: info: Scanning into folder /data/plugins/ Oct 28 12:46:35 volumio volumio[3138]: info: Scanning category miscellanea Oct 28 12:46:35 volumio volumio[3138]: info: Scanning category music_service Oct 28 12:46:35 volumio volumio[3138]: info: Plugin folders cleanup completed Oct 28 12:46:35 volumio volumio[3138]: info: ------------------------------------------- Oct 28 12:46:35 volumio volumio[3138]: info: ----- Core plugins startup ---- Oct 28 12:46:35 volumio volumio[3138]: info: ------------------------------------------- Oct 28 12:46:35 volumio volumio[3138]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 12:46:35 volumio volumio[3138]: info: Adding plugin upnp to MyMusic Plugins Oct 28 12:46:35 volumio volumio[3138]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 12:46:35 volumio volumio[3138]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 12:46:35 volumio volumio[3138]: info: Loading plugins from folder /data/plugins/ Oct 28 12:46:35 volumio volumio[3138]: info: Loading plugin "system"... Oct 28 12:46:35 volumio volumio[3138]: info: Loading plugin "appearance"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "network"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "services"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "alsa_controller"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "wizard"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "volumio_command_line_client"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "upnp"... Oct 28 12:46:36 volumio volumio[3138]: info: [1730119596483] Starting Upmpd Daemon Oct 28 12:46:36 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "my_music"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "mpd"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "upnp_browser"... Oct 28 12:46:36 volumio volumio[3138]: info: Loading plugin "networkfs"... Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "alarm-clock"... Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "airplay_emulation"... Oct 28 12:46:37 volumio volumio[3138]: info: Starting Shairport Sync Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "last_100"... Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "webradio"... Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "i2s_dacs"... Oct 28 12:46:37 volumio volumio[3138]: info: I2S DAC not set, start Auto-detection Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "volumiodiscovery"... Oct 28 12:46:37 volumio volumio[3138]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:37 volumio node[3138]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:37 volumio node[3138]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:37 volumio node[3138]: *** WARNING *** For more information see Oct 28 12:46:37 volumio node[3138]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:37 volumio node[3138]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:37 volumio node[3138]: *** WARNING *** For more information see Oct 28 12:46:37 volumio volumio[3138]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:37 volumio volumio[3138]: *** WARNING *** For more information see Oct 28 12:46:37 volumio volumio[3138]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:37 volumio volumio[3138]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:37 volumio volumio[3138]: *** WARNING *** For more information see Oct 28 12:46:37 volumio volumio[3138]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 12:46:37 volumio volumio[3138]: Discovery: StartAdv! undefined Oct 28 12:46:37 volumio volumio[3138]: Discovery: Started advertising... undefined - undefined Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:37 volumio volumio[3138]: info: Plugin now_playing is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Plugin bandcamp is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Plugin jellyfin is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "outputs"... Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "albumart"... Oct 28 12:46:37 volumio volumio[3138]: info: Plugin example_plugin is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "inputs"... Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "updater_comm"... Oct 28 12:46:37 volumio volumio[3138]: info: Plugin mpdemulation is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "rest_api"... Oct 28 12:46:37 volumio volumio[3138]: info: Loading plugin "websocket"... Oct 28 12:46:37 volumio volumio[3138]: info: Plugin lastfm is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Plugin personal_radio is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Plugin radio_paradise is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Plugin spop is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: Plugin swarmstation is not enabled Oct 28 12:46:37 volumio volumio[3138]: info: ___________ START PLUGINS ___________ Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:37 volumio volumio[3138]: info: [1730119597441] CoreMusicLibrary::Adding element Media Servers Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:37 volumio volumio[3138]: Forking 1 albumart workers Oct 28 12:46:37 volumio volumio[3138]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:46:37 volumio volumio[3138]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:37 volumio volumio[3138]: info: [1730119597515] CoreMusicLibrary::Adding element Last_100 Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:37 volumio volumio[3138]: info: [1730119597519] CoreMusicLibrary::Adding element Webradio Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 12:46:37 volumio volumio[3138]: info: ------------------------------------------- Oct 28 12:46:37 volumio volumio[3138]: info: ----- MyVolumio plugins startup ---- Oct 28 12:46:37 volumio volumio[3138]: info: ------------------------------------------- Oct 28 12:46:37 volumio volumio[3138]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 12:46:37 volumio volumio[3138]: info: Loading i18n strings for locale fr Oct 28 12:46:37 volumio volumio[3138]: Updating browse sources language Oct 28 12:46:37 volumio volumio[3138]: Cannot find translation for sourceMedia Servers Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::initPlayerControls Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: BOOT COMPLETED Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:46:37 volumio volumio[3138]: Express server listening on port 3000 Oct 28 12:46:37 volumio volumio[3138]: info: Setting Device type: x86 Oct 28 12:46:37 volumio volumio[3138]: Volumio Calling Home Oct 28 12:46:37 volumio volumio[3138]: info: CoreStateMachine::resetVolumioState Oct 28 12:46:37 volumio volumio[3138]: info: CoreStateMachine::getcurrentVolume Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 12:46:37 volumio volumio[3138]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 28 12:46:37 volumio volumio[3138]: info: MPD running with PID643 ,establishing connection Oct 28 12:46:37 volumio sudo[3203]: pam_unix(sudo:auth): conversation failed Oct 28 12:46:37 volumio sudo[3203]: pam_unix(sudo:auth): auth could not identify password for [volumio] Oct 28 12:46:37 volumio volumio[3138]: info: VolumeController:: Volume=0 Mute =false Oct 28 12:46:37 volumio volumio[3138]: info: CoreStateMachine::pushState Oct 28 12:46:37 volumio volumio[3138]: info: CoreStateMachine::getState Oct 28 12:46:37 volumio volumio[3138]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:37 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:38 volumio volumio[3138]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Oct 28 12:46:38 volumio volumio[3138]: sudo: no tty present and no askpass program specified Oct 28 12:46:38 volumio volumio[3138]: Starting albumart workers Oct 28 12:46:38 volumio volumio[3138]: info: Reloading queue from file Oct 28 12:46:38 volumio volumio[3138]: info: Oct 28 12:46:38 volumio volumio[3138]: info: CoreStateMachine::setRepeat true single undefined Oct 28 12:46:38 volumio volumio[3138]: info: CoreStateMachine::pushState Oct 28 12:46:38 volumio volumio[3138]: info: CoreStateMachine::getState Oct 28 12:46:38 volumio volumio[3138]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:38 volumio volumio[3138]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:38 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:38 volumio volumio[3138]: info: CoreStateMachine::setRandom true Oct 28 12:46:38 volumio volumio[3138]: info: CoreStateMachine::pushState Oct 28 12:46:38 volumio volumio[3138]: info: CoreStateMachine::getState Oct 28 12:46:38 volumio volumio[3138]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:38 volumio volumio[3138]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:38 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:38 volumio sudo[3206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:46:38 volumio sudo[3206]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:38 volumio sudo[3207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:46:38 volumio sudo[3207]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:38 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:46:38 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:46:38 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:46:38 volumio shairport-sync[3211]: Successful Startup Oct 28 12:46:38 volumio sudo[3207]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:38 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:46:38 volumio volumio[3138]: info: Shairport-Sync Started Oct 28 12:46:38 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:46:38 volumio sudo[3206]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:38 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:46:38 volumio volumio[3138]: Error adding Membership: Error: addMembership EINVAL Oct 28 12:46:38 volumio shairport-sync[3215]: Successful Startup Oct 28 12:46:38 volumio volumio[3138]: info: Shairport-Sync Started Oct 28 12:46:38 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 12:46:38 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 12:46:39 volumio volumio-remote-updater[621]: [2024-10-28 12:46:39] [connect] Successful connection Oct 28 12:46:39 volumio volumio-remote-updater[621]: [2024-10-28 12:46:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730119599 101 Oct 28 12:46:39 volumio volumio[3138]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 12:46:39 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:39 volumio volumio[3138]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:39 volumio volumio[3138]: info: CoreStateMachine::getState Oct 28 12:46:39 volumio volumio[3138]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:39 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 12:46:39 volumio volumio[3138]: info: Listing playlists Oct 28 12:46:40 volumio volumio[3138]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:40 volumio volumio[3138]: info: CoreStateMachine::getState Oct 28 12:46:40 volumio volumio[3138]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:41 volumio volumio[3138]: info: Oct 28 12:46:41 volumio volumio[3138]: ---------------------------- Client requests Volume NaN Oct 28 12:46:41 volumio volumio[3138]: info: VolumeController::SetAlsaVolumeNaN Oct 28 12:46:41 volumio volumio[3138]: info: CoreStateMachine::pushState Oct 28 12:46:41 volumio volumio[3138]: info: CoreStateMachine::getState Oct 28 12:46:41 volumio volumio[3138]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:41 volumio volumio[3138]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:41 volumio volumio[3138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:41 volumio volumio[3138]: info: Setting volume on startup at undefined Oct 28 12:46:42 volumio volumio[3138]: Discovery: ecc TypeError: argument 8 must be an integer (port) Oct 28 12:46:42 volumio volumio[3138]: Discovery: StartAdv! false Oct 28 12:46:42 volumio volumio[3138]: Discovery: Started advertising... undefined - false Oct 28 12:46:46 volumio volumio[3138]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:46 volumio volumio[3138]: TypeError: Cannot read property 'charAt' of undefined Oct 28 12:46:46 volumio volumio[3138]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Oct 28 12:46:46 volumio volumio[3138]: at ontimeout (timers.js:482:11) Oct 28 12:46:46 volumio volumio[3138]: at tryOnTimeout (timers.js:317:5) Oct 28 12:46:46 volumio volumio[3138]: at Timer.listOnTimeout (timers.js:277:5) Oct 28 12:46:46 volumio volumio[3138]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:46 volumio sudo[3236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 12:45 Oct 28 12:46:46 volumio sudo[3236]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:46 volumio sudo[3236]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:47 volumio volumio-remote-updater[621]: [2024-10-28 12:46:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 28 12:46:47 volumio volumio-remote-updater[621]: [2024-10-28 12:46:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 28 12:46:47 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Oct 28 12:46:47 volumio systemd[1]: Unit volumio.service entered failed state. Oct 28 12:46:47 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:47 volumio systemd[1]: Started dynamicswap service. Oct 28 12:46:47 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Oct 28 12:46:47 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:47 volumio systemd[1]: Started dynamicswap service. Oct 28 12:46:47 volumio systemd[1]: Stopping Volumio Backend Module... Oct 28 12:46:47 volumio systemd[1]: Starting Volumio Backend Module... Oct 28 12:46:47 volumio systemd[1]: Started Volumio Backend Module. Oct 28 12:46:48 volumio volumio[3254]: info: ------------------------------------------- Oct 28 12:46:48 volumio volumio[3254]: info: ----- Volumio2 ---- Oct 28 12:46:48 volumio volumio[3254]: info: ------------------------------------------- Oct 28 12:46:48 volumio volumio[3254]: info: ----- System startup ---- Oct 28 12:46:48 volumio volumio[3254]: info: ------------------------------------------- Oct 28 12:46:48 volumio volumio[3254]: info: MYVOLUMIO Environment detected Oct 28 12:46:48 volumio volumio[3254]: info: Plugin folders cleanup Oct 28 12:46:48 volumio volumio[3254]: info: Scanning into folder /volumio/app/plugins/ Oct 28 12:46:48 volumio volumio[3254]: info: Scanning category audio_interface Oct 28 12:46:48 volumio volumio[3254]: info: Scanning category miscellanea Oct 28 12:46:48 volumio volumio[3254]: info: Scanning category music_service Oct 28 12:46:48 volumio volumio[3254]: info: Scanning category plugins.json Oct 28 12:46:48 volumio volumio[3254]: info: Scanning category system_controller Oct 28 12:46:48 volumio volumio[3254]: info: Scanning category user_interface Oct 28 12:46:48 volumio volumio[3254]: info: Scanning into folder /data/plugins/ Oct 28 12:46:48 volumio volumio[3254]: info: Scanning category miscellanea Oct 28 12:46:48 volumio volumio[3254]: info: Scanning category music_service Oct 28 12:46:48 volumio volumio[3254]: info: Plugin folders cleanup completed Oct 28 12:46:48 volumio volumio[3254]: info: ------------------------------------------- Oct 28 12:46:48 volumio volumio[3254]: info: ----- Core plugins startup ---- Oct 28 12:46:48 volumio volumio[3254]: info: ------------------------------------------- Oct 28 12:46:48 volumio volumio[3254]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 12:46:48 volumio volumio[3254]: info: Adding plugin upnp to MyMusic Plugins Oct 28 12:46:48 volumio volumio[3254]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 12:46:48 volumio volumio[3254]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 12:46:48 volumio volumio[3254]: info: Loading plugins from folder /data/plugins/ Oct 28 12:46:48 volumio volumio[3254]: info: Loading plugin "system"... Oct 28 12:46:48 volumio volumio[3254]: info: Loading plugin "appearance"... Oct 28 12:46:48 volumio volumio[3254]: info: Loading plugin "network"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "services"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "alsa_controller"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "wizard"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "volumio_command_line_client"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "upnp"... Oct 28 12:46:49 volumio volumio[3254]: info: [1730119609218] Starting Upmpd Daemon Oct 28 12:46:49 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "my_music"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "mpd"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "upnp_browser"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "networkfs"... Oct 28 12:46:49 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "alarm-clock"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "airplay_emulation"... Oct 28 12:46:49 volumio volumio[3254]: info: Starting Shairport Sync Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "last_100"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "webradio"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "i2s_dacs"... Oct 28 12:46:49 volumio volumio[3254]: info: I2S DAC not set, start Auto-detection Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "volumiodiscovery"... Oct 28 12:46:49 volumio volumio[3254]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:49 volumio node[3254]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:49 volumio volumio[3254]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:49 volumio node[3254]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:49 volumio volumio[3254]: *** WARNING *** For more information see Oct 28 12:46:49 volumio node[3254]: *** WARNING *** For more information see Oct 28 12:46:49 volumio volumio[3254]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:49 volumio node[3254]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:46:49 volumio volumio[3254]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:49 volumio node[3254]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:46:49 volumio node[3254]: *** WARNING *** For more information see Oct 28 12:46:49 volumio volumio[3254]: *** WARNING *** For more information see Oct 28 12:46:49 volumio volumio[3254]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 12:46:49 volumio volumio[3254]: Discovery: StartAdv! undefined Oct 28 12:46:49 volumio volumio[3254]: Discovery: Started advertising... undefined - undefined Oct 28 12:46:49 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:46:49 volumio volumio[3254]: info: Plugin now_playing is not enabled Oct 28 12:46:49 volumio volumio[3254]: info: Plugin bandcamp is not enabled Oct 28 12:46:49 volumio volumio[3254]: info: Plugin jellyfin is not enabled Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "outputs"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "albumart"... Oct 28 12:46:49 volumio volumio[3254]: info: Plugin example_plugin is not enabled Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "inputs"... Oct 28 12:46:49 volumio volumio[3254]: info: Loading plugin "updater_comm"... Oct 28 12:46:50 volumio volumio[3254]: info: Plugin mpdemulation is not enabled Oct 28 12:46:50 volumio volumio[3254]: info: Loading plugin "rest_api"... Oct 28 12:46:50 volumio volumio[3254]: info: Loading plugin "websocket"... Oct 28 12:46:50 volumio volumio[3254]: info: Plugin lastfm is not enabled Oct 28 12:46:50 volumio volumio[3254]: info: Plugin personal_radio is not enabled Oct 28 12:46:50 volumio volumio[3254]: info: Plugin radio_paradise is not enabled Oct 28 12:46:50 volumio volumio[3254]: info: Plugin spop is not enabled Oct 28 12:46:50 volumio volumio[3254]: info: Plugin swarmstation is not enabled Oct 28 12:46:50 volumio volumio[3254]: info: ___________ START PLUGINS ___________ Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:50 volumio volumio[3254]: info: [1730119610134] CoreMusicLibrary::Adding element Media Servers Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:50 volumio volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:46:50 volumio volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:50 volumio volumio[3254]: info: [1730119610194] CoreMusicLibrary::Adding element Last_100 Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:46:50 volumio volumio[3254]: info: [1730119610198] CoreMusicLibrary::Adding element Webradio Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 12:46:50 volumio volumio[3254]: info: ------------------------------------------- Oct 28 12:46:50 volumio volumio[3254]: info: ----- MyVolumio plugins startup ---- Oct 28 12:46:50 volumio volumio[3254]: info: ------------------------------------------- Oct 28 12:46:50 volumio volumio[3254]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 12:46:50 volumio volumio[3254]: info: Loading i18n strings for locale fr Oct 28 12:46:50 volumio volumio[3254]: Updating browse sources language Oct 28 12:46:50 volumio volumio[3254]: Cannot find translation for sourceMedia Servers Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:46:50 volumio volumio[3254]: Forking 1 albumart workers Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::initPlayerControls Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: BOOT COMPLETED Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:46:50 volumio volumio[3254]: Express server listening on port 3000 Oct 28 12:46:50 volumio volumio[3254]: info: Setting Device type: x86 Oct 28 12:46:50 volumio volumio[3254]: Volumio Calling Home Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::resetVolumioState Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::getcurrentVolume Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 12:46:50 volumio volumio[3254]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 28 12:46:50 volumio volumio[3254]: info: MPD running with PID643 ,establishing connection Oct 28 12:46:50 volumio sudo[3319]: pam_unix(sudo:auth): conversation failed Oct 28 12:46:50 volumio sudo[3319]: pam_unix(sudo:auth): auth could not identify password for [volumio] Oct 28 12:46:50 volumio volumio[3254]: info: VolumeController:: Volume=0 Mute =false Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::pushState Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::getState Oct 28 12:46:50 volumio volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:50 volumio volumio[3254]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Oct 28 12:46:50 volumio volumio[3254]: sudo: no tty present and no askpass program specified Oct 28 12:46:50 volumio volumio[3254]: info: Reloading queue from file Oct 28 12:46:50 volumio volumio[3254]: info: Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::setRepeat true single undefined Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::pushState Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::getState Oct 28 12:46:50 volumio volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::setRandom true Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::pushState Oct 28 12:46:50 volumio volumio[3254]: info: CoreStateMachine::getState Oct 28 12:46:50 volumio volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:50 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:50 volumio sudo[3322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:46:50 volumio sudo[3322]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:50 volumio sudo[3323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:46:50 volumio sudo[3323]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:50 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:46:50 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:46:50 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:46:50 volumio shairport-sync[3327]: Successful Startup Oct 28 12:46:50 volumio sudo[3322]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:50 volumio volumio[3254]: info: Shairport-Sync Started Oct 28 12:46:50 volumio volumio[3254]: Error adding Membership: Error: addMembership EINVAL Oct 28 12:46:50 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:46:50 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:46:50 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:46:50 volumio shairport-sync[3331]: Successful Startup Oct 28 12:46:50 volumio sudo[3323]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:50 volumio volumio[3254]: info: Shairport-Sync Started Oct 28 12:46:50 volumio volumio[3254]: Starting albumart workers Oct 28 12:46:52 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 12:46:52 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 12:46:52 volumio volumio-remote-updater[621]: [2024-10-28 12:46:52] [connect] Successful connection Oct 28 12:46:52 volumio volumio-remote-updater[621]: [2024-10-28 12:46:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730119612 101 Oct 28 12:46:53 volumio volumio[3254]: info: CoreCommandRouter::volumioGetState Oct 28 12:46:53 volumio volumio[3254]: info: CoreStateMachine::getState Oct 28 12:46:53 volumio volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:54 volumio volumio[3254]: info: Oct 28 12:46:54 volumio volumio[3254]: ---------------------------- Client requests Volume NaN Oct 28 12:46:54 volumio volumio[3254]: info: VolumeController::SetAlsaVolumeNaN Oct 28 12:46:54 volumio volumio[3254]: info: CoreStateMachine::pushState Oct 28 12:46:54 volumio volumio[3254]: info: CoreStateMachine::getState Oct 28 12:46:54 volumio volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 28 12:46:54 volumio volumio[3254]: info: CoreCommandRouter::volumioPushState Oct 28 12:46:54 volumio volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:46:54 volumio volumio[3254]: info: Setting volume on startup at undefined Oct 28 12:46:54 volumio kernel: r8169 0000:01:00.0 eth0: link up Oct 28 12:46:54 volumio volumio[3254]: Discovery: ecc TypeError: argument 8 must be an integer (port) Oct 28 12:46:54 volumio volumio[3254]: Discovery: StartAdv! false Oct 28 12:46:54 volumio volumio[3254]: Discovery: Started advertising... undefined - false Oct 28 12:46:55 volumio ifplugd(eth0)[689]: Link beat detected. Oct 28 12:46:55 volumio ifplugd(eth0)[689]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 28 12:46:55 volumio ifplugd(eth0)[689]: client: command failed: No such device (-19) Oct 28 12:46:56 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: version 6.0.5 starting Oct 28 12:46:56 volumio dhcpcd[3366]: version 6.0.5 starting Oct 28 12:46:56 volumio dhcpcd[3366]: eth0: soliciting an IPv6 router Oct 28 12:46:56 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: eth0: soliciting an IPv6 router Oct 28 12:46:56 volumio dhcpcd[3366]: eth0: soliciting a DHCP lease Oct 28 12:46:56 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: eth0: soliciting a DHCP lease Oct 28 12:46:59 volumio volumio[3254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:59 volumio volumio[3254]: TypeError: Cannot read property 'charAt' of undefined Oct 28 12:46:59 volumio volumio[3254]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Oct 28 12:46:59 volumio volumio[3254]: at ontimeout (timers.js:482:11) Oct 28 12:46:59 volumio volumio[3254]: at tryOnTimeout (timers.js:317:5) Oct 28 12:46:59 volumio volumio[3254]: at Timer.listOnTimeout (timers.js:277:5) Oct 28 12:46:59 volumio volumio[3254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:46:59 volumio sudo[3393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 12:45 Oct 28 12:46:59 volumio sudo[3393]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:46:59 volumio sudo[3393]: pam_unix(sudo:session): session closed for user root Oct 28 12:46:59 volumio volumio-remote-updater[621]: [2024-10-28 12:46:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 28 12:46:59 volumio volumio-remote-updater[621]: [2024-10-28 12:46:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 28 12:46:59 volumio dhcpcd[3366]: eth0: offered 192.168.1.192 from 192.168.1.254 Oct 28 12:46:59 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: eth0: offered 192.168.1.192 from 192.168.1.254 Oct 28 12:46:59 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Oct 28 12:46:59 volumio systemd[1]: Unit volumio.service entered failed state. Oct 28 12:46:59 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:46:59 volumio systemd[1]: Started dynamicswap service. Oct 28 12:47:00 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Oct 28 12:47:00 volumio systemd[1]: Starting dynamicswap service... Oct 28 12:47:00 volumio systemd[1]: Started dynamicswap service. Oct 28 12:47:00 volumio systemd[1]: Stopping Volumio Backend Module... Oct 28 12:47:00 volumio systemd[1]: Starting Volumio Backend Module... Oct 28 12:47:00 volumio systemd[1]: Started Volumio Backend Module. Oct 28 12:47:01 volumio volumio[3411]: info: ------------------------------------------- Oct 28 12:47:01 volumio volumio[3411]: info: ----- Volumio2 ---- Oct 28 12:47:01 volumio volumio[3411]: info: ------------------------------------------- Oct 28 12:47:01 volumio volumio[3411]: info: ----- System startup ---- Oct 28 12:47:01 volumio volumio[3411]: info: ------------------------------------------- Oct 28 12:47:01 volumio volumio[3411]: info: MYVOLUMIO Environment detected Oct 28 12:47:01 volumio volumio[3411]: info: Plugin folders cleanup Oct 28 12:47:01 volumio volumio[3411]: info: Scanning into folder /volumio/app/plugins/ Oct 28 12:47:01 volumio volumio[3411]: info: Scanning category audio_interface Oct 28 12:47:01 volumio volumio[3411]: info: Scanning category miscellanea Oct 28 12:47:01 volumio volumio[3411]: info: Scanning category music_service Oct 28 12:47:01 volumio volumio[3411]: info: Scanning category plugins.json Oct 28 12:47:01 volumio volumio[3411]: info: Scanning category system_controller Oct 28 12:47:01 volumio volumio[3411]: info: Scanning category user_interface Oct 28 12:47:01 volumio volumio[3411]: info: Scanning into folder /data/plugins/ Oct 28 12:47:01 volumio volumio[3411]: info: Scanning category miscellanea Oct 28 12:47:01 volumio volumio[3411]: info: Scanning category music_service Oct 28 12:47:01 volumio volumio[3411]: info: Plugin folders cleanup completed Oct 28 12:47:01 volumio volumio[3411]: info: ------------------------------------------- Oct 28 12:47:01 volumio volumio[3411]: info: ----- Core plugins startup ---- Oct 28 12:47:01 volumio volumio[3411]: info: ------------------------------------------- Oct 28 12:47:01 volumio volumio[3411]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 12:47:01 volumio volumio[3411]: info: Adding plugin upnp to MyMusic Plugins Oct 28 12:47:01 volumio volumio[3411]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 12:47:01 volumio volumio[3411]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 12:47:01 volumio volumio[3411]: info: Loading plugins from folder /data/plugins/ Oct 28 12:47:01 volumio volumio[3411]: info: Loading plugin "system"... Oct 28 12:47:01 volumio volumio[3411]: info: Loading plugin "appearance"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "network"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "services"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "alsa_controller"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "wizard"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "volumio_command_line_client"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "upnp"... Oct 28 12:47:02 volumio volumio[3411]: info: [1730119622244] Starting Upmpd Daemon Oct 28 12:47:02 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "my_music"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "mpd"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "upnp_browser"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "networkfs"... Oct 28 12:47:02 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "alarm-clock"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "airplay_emulation"... Oct 28 12:47:02 volumio volumio[3411]: info: Starting Shairport Sync Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "last_100"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "webradio"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "i2s_dacs"... Oct 28 12:47:02 volumio volumio[3411]: info: I2S DAC not set, start Auto-detection Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "volumiodiscovery"... Oct 28 12:47:02 volumio volumio[3411]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:47:02 volumio node[3411]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 12:47:02 volumio volumio[3411]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:47:02 volumio node[3411]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:47:02 volumio volumio[3411]: *** WARNING *** For more information see Oct 28 12:47:02 volumio node[3411]: *** WARNING *** For more information see Oct 28 12:47:02 volumio volumio[3411]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:47:02 volumio node[3411]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 12:47:02 volumio volumio[3411]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:47:02 volumio node[3411]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 12:47:02 volumio volumio[3411]: *** WARNING *** For more information see Oct 28 12:47:02 volumio node[3411]: *** WARNING *** For more information see Oct 28 12:47:02 volumio volumio[3411]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 12:47:02 volumio volumio[3411]: Discovery: StartAdv! undefined Oct 28 12:47:02 volumio volumio[3411]: Discovery: Started advertising... undefined - undefined Oct 28 12:47:02 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 12:47:02 volumio volumio[3411]: info: Plugin now_playing is not enabled Oct 28 12:47:02 volumio volumio[3411]: info: Plugin bandcamp is not enabled Oct 28 12:47:02 volumio volumio[3411]: info: Plugin jellyfin is not enabled Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "outputs"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "albumart"... Oct 28 12:47:02 volumio volumio[3411]: info: Plugin example_plugin is not enabled Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "inputs"... Oct 28 12:47:02 volumio volumio[3411]: info: Loading plugin "updater_comm"... Oct 28 12:47:03 volumio volumio[3411]: info: Plugin mpdemulation is not enabled Oct 28 12:47:03 volumio volumio[3411]: info: Loading plugin "rest_api"... Oct 28 12:47:03 volumio volumio[3411]: info: Loading plugin "websocket"... Oct 28 12:47:03 volumio volumio[3411]: info: Plugin lastfm is not enabled Oct 28 12:47:03 volumio volumio[3411]: info: Plugin personal_radio is not enabled Oct 28 12:47:03 volumio volumio[3411]: info: Plugin radio_paradise is not enabled Oct 28 12:47:03 volumio volumio[3411]: info: Plugin spop is not enabled Oct 28 12:47:03 volumio volumio[3411]: info: Plugin swarmstation is not enabled Oct 28 12:47:03 volumio volumio[3411]: info: ___________ START PLUGINS ___________ Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:47:03 volumio volumio[3411]: info: [1730119623129] CoreMusicLibrary::Adding element Media Servers Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:47:03 volumio volumio[3411]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:47:03 volumio volumio[3411]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:47:03 volumio volumio[3411]: info: [1730119623190] CoreMusicLibrary::Adding element Last_100 Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 12:47:03 volumio volumio[3411]: info: [1730119623193] CoreMusicLibrary::Adding element Webradio Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 12:47:03 volumio volumio[3411]: info: ------------------------------------------- Oct 28 12:47:03 volumio volumio[3411]: info: ----- MyVolumio plugins startup ---- Oct 28 12:47:03 volumio volumio[3411]: info: ------------------------------------------- Oct 28 12:47:03 volumio volumio[3411]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 12:47:03 volumio volumio[3411]: info: Loading i18n strings for locale fr Oct 28 12:47:03 volumio volumio[3411]: Updating browse sources language Oct 28 12:47:03 volumio volumio[3411]: Cannot find translation for sourceMedia Servers Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::initPlayerControls Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 28 12:47:03 volumio volumio[3411]: Forking 1 albumart workers Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: BOOT COMPLETED Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::Close All Modals sent Oct 28 12:47:03 volumio volumio[3411]: Express server listening on port 3000 Oct 28 12:47:03 volumio volumio[3411]: info: Setting Device type: x86 Oct 28 12:47:03 volumio volumio[3411]: Volumio Calling Home Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::resetVolumioState Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::getcurrentVolume Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 12:47:03 volumio volumio[3411]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 28 12:47:03 volumio volumio[3411]: info: MPD running with PID643 ,establishing connection Oct 28 12:47:03 volumio sudo[3476]: pam_unix(sudo:auth): conversation failed Oct 28 12:47:03 volumio sudo[3476]: pam_unix(sudo:auth): auth could not identify password for [volumio] Oct 28 12:47:03 volumio volumio[3411]: info: VolumeController:: Volume=0 Mute =false Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::pushState Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::getState Oct 28 12:47:03 volumio volumio[3411]: info: CorePlayQueue::getTrack 0 Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::volumioPushState Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:47:03 volumio volumio[3411]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Oct 28 12:47:03 volumio volumio[3411]: sudo: no tty present and no askpass program specified Oct 28 12:47:03 volumio volumio[3411]: Starting albumart workers Oct 28 12:47:03 volumio volumio[3411]: info: Reloading queue from file Oct 28 12:47:03 volumio volumio[3411]: info: Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::setRepeat true single undefined Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::pushState Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::getState Oct 28 12:47:03 volumio volumio[3411]: info: CorePlayQueue::getTrack 0 Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::volumioPushState Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::setRandom true Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::pushState Oct 28 12:47:03 volumio volumio[3411]: info: CoreStateMachine::getState Oct 28 12:47:03 volumio volumio[3411]: info: CorePlayQueue::getTrack 0 Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::volumioPushState Oct 28 12:47:03 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:47:03 volumio sudo[3479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:47:03 volumio sudo[3479]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:47:03 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:47:03 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:47:03 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:47:03 volumio shairport-sync[3482]: Successful Startup Oct 28 12:47:03 volumio sudo[3479]: pam_unix(sudo:session): session closed for user root Oct 28 12:47:03 volumio sudo[3484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 28 12:47:03 volumio sudo[3484]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:47:03 volumio volumio[3411]: info: Shairport-Sync Started Oct 28 12:47:03 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Oct 28 12:47:03 volumio volumio[3411]: Error adding Membership: Error: addMembership EINVAL Oct 28 12:47:03 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Oct 28 12:47:03 volumio systemd[1]: Started ShairportSync AirTunes receiver. Oct 28 12:47:03 volumio sudo[3484]: pam_unix(sudo:session): session closed for user root Oct 28 12:47:03 volumio shairport-sync[3488]: Successful Startup Oct 28 12:47:03 volumio volumio[3411]: info: Shairport-Sync Started Oct 28 12:47:04 volumio dhcpcd[3366]: eth0: leased 192.168.1.192 for 86400 seconds Oct 28 12:47:04 volumio dhcpcd[3366]: eth0: adding host route to 192.168.1.192 via 127.0.0.1 Oct 28 12:47:04 volumio dhcpcd[3366]: eth0: adding route to 192.168.1.0/24 Oct 28 12:47:04 volumio dhcpcd[3366]: eth0: adding default route via 192.168.1.254 Oct 28 12:47:04 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: eth0: leased 192.168.1.192 for 86400 seconds Oct 28 12:47:04 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: eth0: adding host route to 192.168.1.192 via 127.0.0.1 Oct 28 12:47:04 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: eth0: adding route to 192.168.1.0/24 Oct 28 12:47:04 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: eth0: adding default route via 192.168.1.254 Oct 28 12:47:04 volumio avahi-daemon[642]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.192. Oct 28 12:47:04 volumio avahi-daemon[642]: New relevant interface eth0.IPv4 for mDNS. Oct 28 12:47:04 volumio avahi-daemon[642]: Registering new address record for 192.168.1.192 on eth0.IPv4. Oct 28 12:47:04 volumio dhcpcd[3366]: forked to background, child pid 3515 Oct 28 12:47:04 volumio ifplugd(eth0)[689]: client: dhcpcd[3366]: forked to background, child pid 3515 Oct 28 12:47:04 volumio volumio-remote-updater[621]: [2024-10-28 12:47:04] [connect] Successful connection Oct 28 12:47:04 volumio ifplugd(eth0)[689]: Program executed successfully. Oct 28 12:47:04 volumio nmbd[832]: [2024/10/28 12:47:04.961508, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 28 12:47:04 volumio nmbd[832]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 28 12:47:05 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 12:47:05 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 12:47:05 volumio volumio-remote-updater[621]: [2024-10-28 12:47:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730119624 101 Oct 28 12:47:05 volumio ntpd[701]: Listen normally on 3 eth0 192.168.1.192 UDP 123 Oct 28 12:47:05 volumio ntpd[701]: peers refreshed Oct 28 12:47:06 volumio volumio[3411]: info: CoreCommandRouter::volumioGetState Oct 28 12:47:06 volumio volumio[3411]: info: CoreStateMachine::getState Oct 28 12:47:06 volumio volumio[3411]: info: CorePlayQueue::getTrack 0 Oct 28 12:47:06 volumio volumio[3411]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 12:47:06 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 12:47:06 volumio volumio[3411]: info: CoreCommandRouter::volumioGetState Oct 28 12:47:06 volumio volumio[3411]: info: CoreStateMachine::getState Oct 28 12:47:06 volumio volumio[3411]: info: CorePlayQueue::getTrack 0 Oct 28 12:47:06 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 12:47:06 volumio volumio[3411]: info: Listing playlists Oct 28 12:47:07 volumio volumio[3411]: info: Oct 28 12:47:07 volumio volumio[3411]: ---------------------------- Client requests Volume NaN Oct 28 12:47:07 volumio volumio[3411]: info: VolumeController::SetAlsaVolumeNaN Oct 28 12:47:07 volumio volumio[3411]: info: CoreStateMachine::pushState Oct 28 12:47:07 volumio volumio[3411]: info: CoreStateMachine::getState Oct 28 12:47:07 volumio volumio[3411]: info: CorePlayQueue::getTrack 0 Oct 28 12:47:07 volumio volumio[3411]: info: CoreCommandRouter::volumioPushState Oct 28 12:47:07 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 12:47:07 volumio volumio[3411]: info: Setting volume on startup at undefined Oct 28 12:47:07 volumio ntpd_intres[795]: DNS 0.debian.pool.ntp.org -> 129.250.35.250 Oct 28 12:47:07 volumio volumio[3411]: Discovery: ecc TypeError: argument 8 must be an integer (port) Oct 28 12:47:07 volumio volumio[3411]: Discovery: StartAdv! false Oct 28 12:47:07 volumio volumio[3411]: Discovery: Started advertising... undefined - false Oct 28 12:47:08 volumio ntpd_intres[795]: DNS 1.debian.pool.ntp.org -> 51.255.141.76 Oct 28 12:47:08 volumio ntpd_intres[795]: DNS 2.debian.pool.ntp.org -> 51.255.95.80 Oct 28 12:47:08 volumio ntpd_intres[795]: DNS 3.debian.pool.ntp.org -> 51.68.44.27 Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 28 12:47:11 volumio volumio[3411]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 28 12:47:12 volumio volumio[3411]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 28 12:47:13 volumio volumio[3411]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 28 12:47:13 volumio volumio[3411]: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 28 12:47:13 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 12:47:13 volumio volumio[3411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 12:47:13 volumio volumio[3411]: info: Starting MyVolumio Remote Streaming Endpoints Oct 28 12:47:13 volumio volumio[3411]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 28 12:47:13 volumio volumio[3411]: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 28 12:47:13 volumio volumio[3411]: info: Streaming services startup Oct 28 12:47:13 volumio volumio[3411]: info: Starting Streaming Daemon Oct 28 12:47:13 volumio volumio[3411]: info: MyVolumio not started Oct 28 12:47:13 volumio volumio[3411]: info: Initializing device activation check Oct 28 12:47:13 volumio sudo[3575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 28 12:47:13 volumio sudo[3575]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 12:47:13 volumio volumio[3411]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 28 12:47:13 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 28 12:47:13 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 28 12:47:13 volumio volumio[3411]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:47:13 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 28 12:47:13 volumio sudo[3575]: pam_unix(sudo:session): session closed for user root Oct 28 12:47:13 volumio volumio[3411]: TypeError: Cannot read property 'charAt' of undefined Oct 28 12:47:13 volumio volumio[3411]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Oct 28 12:47:13 volumio volumio[3411]: at ontimeout (timers.js:482:11) Oct 28 12:47:13 volumio volumio[3411]: at tryOnTimeout (timers.js:317:5) Oct 28 12:47:13 volumio volumio[3411]: at Timer.listOnTimeout (timers.js:277:5) Oct 28 12:47:13 volumio volumio[3411]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 12:47:13 volumio volumio-streaming-daemon[3578]: ############################ Oct 28 12:47:13 volumio volumio-streaming-daemon[3578]: # Volumio Streaming Daemon # Oct 28 12:47:13 volumio volumio-streaming-daemon[3578]: # Running on port 7777 # Oct 28 12:47:13 volumio volumio-streaming-daemon[3578]: ############################ Oct 28 12:47:14 volumio sudo[3591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 12:46 Oct 28 12:47:14 volumio sudo[3591]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="6c3e4fa86d5413587e5bfd2f824b888a0ddfaa8b" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="b78ecf1a48254ce8eadcc4803b4fff49030ca2e5" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 18 16:15:21 CEST 2019" VOLUMIO_VERSION="2.657" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="0a3312c3755379fbcf6e5381e11d9dc8"