-- Logs begin at Thu 2024-11-21 06:18:44 UTC, end at Mon 2024-11-25 07:18:56 UTC. -- Nov 25 07:17:20 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 25 07:17:20 player volumio[17640]: info: CURURI: albums:// Nov 25 07:17:20 player volumio[17640]: listAlbums - loading Albums from cache Nov 25 07:17:23 player volumio[17640]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Mylene%20Farmer/Live%20A%20Bercy/f4afeb4a-6522-462a-b719-2c161ddf42d8.jpg' Nov 25 07:17:24 player volumio[17640]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dire%20Straits/Dire%20Straits/520c4aab-d210-4a71-8f08-6dbf4c5bb3fc.png' Nov 25 07:17:24 player volumio[17640]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dire%20Straits/Communiqu%C3%A9/89950f2f-4f2d-41a4-81fc-078910c4be89.png' Nov 25 07:17:24 player volumio[17640]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dire%20Straits/Making%20Movies/bc9f840c-4a30-437a-89f1-ebcd903144c1.jpg' Nov 25 07:17:31 player volumio[17640]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Thomas%20Dutronc/Live%20Is%20Love/df3fc29b-da38-4dfc-97e5-59190b41fcd5.jpg' Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 07:17:34 player volumio[17640]: info: Retrieving Cloud Streaming UI Nov 25 07:17:34 player volumio[17640]: info: Getting Tidal Cloud Configuration Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 07:17:34 player volumio[17640]: info: Getting Qobuz Cloud Configuration Nov 25 07:17:34 player volumio[17640]: info: Asking plugin for UI Config Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 07:17:34 player volumio[17640]: info: Getting Spotify Cloud Configuration Nov 25 07:17:34 player volumio[17640]: info: Asking plugin for UI Config Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 07:17:34 player volumio[17640]: info: Saving Spotify Acccount Nov 25 07:17:34 player volumio[17640]: info: Got Tidal Cloud Configuration Nov 25 07:17:34 player volumio[17640]: info: Got it Nov 25 07:17:34 player volumio[17640]: info: Got it Nov 25 07:17:34 player volumio[17640]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 07:17:34 player volumio[17640]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 07:17:34 player volumio[17640]: info: CoreCommandRouter::volumioGetBrowseSources Nov 25 07:17:35 player volumio[17640]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Aerosmith/A%20Little%20South%20Of%20Sanity%20-%20CD%202/146c9c6b-64ed-4491-bb9e-e6e36f138915.jpg' Nov 25 07:17:35 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:17:35 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 25 07:17:37 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 25 07:17:37 player volumio[17640]: verbose: ControllerMpd::sendMpdCommand update Nov 25 07:17:37 player volumio[17640]: verbose: ControllerMpd::sendMpdCommand status Nov 25 07:17:37 player volumio[17640]: info: Nov 25 07:17:37 player volumio[17640]: ---------------------------- MPD announces state update: update Nov 25 07:17:37 player volumio[17640]: info: sendMpdCommand update took 5 milliseconds Nov 25 07:17:37 player volumio[17640]: info: ControllerMpd::getState Nov 25 07:17:37 player volumio[17640]: verbose: ControllerMpd::sendMpdCommand status Nov 25 07:17:37 player volumio[17640]: info: sendMpdCommand status took 5 milliseconds Nov 25 07:17:37 player volumio[17640]: info: sendMpdCommand status took 4 milliseconds Nov 25 07:17:37 player volumio[17640]: verbose: ControllerMpd::parseState Nov 25 07:17:37 player volumio[17640]: info: Command Router : Notfying DB Updatetrue Nov 25 07:17:37 player volumio[17640]: verbose: ControllerMpd::parseState Nov 25 07:17:37 player volumio[17640]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 25 07:17:37 player volumio[17640]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 25 07:17:37 player volumio[17640]: verbose: ControllerMpd::parseTrackInfo Nov 25 07:17:37 player volumio[17640]: info: ControllerMpd::pushState Nov 25 07:17:37 player volumio[17640]: info: CoreCommandRouter::servicePushState Nov 25 07:17:37 player volumio[17640]: info: CorePlayQueue::getTrack 10 Nov 25 07:17:37 player volumio[17640]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":145867,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Radio Jesus (feat. Paul Smith & Ross Millard)","artist":"Du Blonde","album":"Sniff More Gritty","uri":"NAS/71/Du Blonde/Albums/2024 - Sniff More Gritty/11 - Radio Jesus (feat. Paul Smith & Ross Millard).mp3","trackType":"mp3"} Nov 25 07:17:37 player volumio[17640]: verbose: CURRENT POSITION 10 Nov 25 07:17:37 player volumio[17640]: info: CoreStateMachine::syncState stateService pause Nov 25 07:17:37 player volumio[17640]: info: CoreStateMachine::syncState currentStatus pause Nov 25 07:17:37 player volumio[17640]: info: CoreStateMachine::pushState Nov 25 07:17:37 player volumio[17640]: info: CorePlayQueue::getTrack 10 Nov 25 07:17:37 player volumio[17640]: info: CoreCommandRouter::volumioPushState Nov 25 07:17:37 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 07:17:37 player volumio[17640]: info: CoreStateMachine::stPlaybackTimer Nov 25 07:17:37 player volumio[17640]: info: ------------------------------ 173ms Nov 25 07:17:39 player volumio[17640]: info: CoreCommandRouter::Close All Modals sent Nov 25 07:17:39 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:17:43 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:17:47 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:17:51 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:17:55 player volumio[17640]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:17:59 player volumio[17640]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 07:17:59 player volumio[17640]: { Error: connect ETIMEDOUT 151.101.2.79:80 Nov 25 07:17:59 player volumio[17640]: at Object._errnoException (util.js:1022:11) Nov 25 07:17:59 player volumio[17640]: at _exceptionWithHostPort (util.js:1044:20) Nov 25 07:17:59 player volumio[17640]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 25 07:17:59 player volumio[17640]: code: 'ETIMEDOUT', Nov 25 07:17:59 player volumio[17640]: errno: 'ETIMEDOUT', Nov 25 07:17:59 player volumio[17640]: syscall: 'connect', Nov 25 07:17:59 player volumio[17640]: address: '151.101.2.79', Nov 25 07:17:59 player volumio[17640]: port: 80 } Nov 25 07:17:59 player volumio[17640]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 07:18:00 player sudo[11531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 07:17 Nov 25 07:18:00 player sudo[11531]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:00 player sudo[11531]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:01 player volumio-remote-updater[607]: [2024-11-25 07:18:01] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Nov 25 07:18:01 player volumio-remote-updater[607]: [2024-11-25 07:18:01] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Nov 25 07:18:01 player volumio-remote-updater[607]: [2024-11-25 07:18:01] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Nov 25 07:18:01 player systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 25 07:18:01 player systemd[1]: Unit volumio.service entered failed state. Nov 25 07:18:01 player systemd[1]: Starting dynamicswap service... Nov 25 07:18:01 player systemd[1]: Started dynamicswap service. Nov 25 07:18:01 player systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 25 07:18:01 player systemd[1]: Starting dynamicswap service... Nov 25 07:18:01 player systemd[1]: Started dynamicswap service. Nov 25 07:18:01 player systemd[1]: Stopping Volumio Backend Module... Nov 25 07:18:01 player systemd[1]: Starting Volumio Backend Module... Nov 25 07:18:01 player systemd[1]: Started Volumio Backend Module. Nov 25 07:18:04 player volumio[11573]: info: ------------------------------------------- Nov 25 07:18:04 player volumio[11573]: info: ----- Volumio2 ---- Nov 25 07:18:04 player volumio[11573]: info: ------------------------------------------- Nov 25 07:18:04 player volumio[11573]: info: ----- System startup ---- Nov 25 07:18:04 player volumio[11573]: info: ------------------------------------------- Nov 25 07:18:05 player volumio[11573]: info: MYVOLUMIO Environment detected Nov 25 07:18:05 player volumio[11573]: info: Plugin folders cleanup Nov 25 07:18:05 player volumio[11573]: info: Scanning into folder /volumio/app/plugins/ Nov 25 07:18:05 player volumio[11573]: info: Scanning category audio_interface Nov 25 07:18:05 player volumio[11573]: info: Scanning category miscellanea Nov 25 07:18:05 player volumio[11573]: info: Scanning category music_service Nov 25 07:18:05 player volumio[11573]: info: Scanning category plugins.json Nov 25 07:18:05 player volumio[11573]: info: Scanning category system_controller Nov 25 07:18:05 player volumio[11573]: info: Scanning category user_interface Nov 25 07:18:05 player volumio[11573]: info: Scanning into folder /data/plugins/ Nov 25 07:18:05 player volumio[11573]: info: Scanning category music_service Nov 25 07:18:05 player volumio[11573]: info: Plugin folders cleanup completed Nov 25 07:18:05 player volumio[11573]: info: ------------------------------------------- Nov 25 07:18:05 player volumio[11573]: info: ----- Core plugins startup ---- Nov 25 07:18:05 player volumio[11573]: info: ------------------------------------------- Nov 25 07:18:05 player volumio[11573]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 07:18:05 player volumio[11573]: info: Adding plugin upnp to MyMusic Plugins Nov 25 07:18:05 player volumio[11573]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 07:18:05 player volumio[11573]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 07:18:05 player volumio[11573]: info: Loading plugins from folder /data/plugins/ Nov 25 07:18:05 player volumio[11573]: info: Loading plugin "system"... Nov 25 07:18:05 player volumio[11573]: info: Loading plugin "appearance"... Nov 25 07:18:06 player volumio-remote-updater[607]: [2024-11-25 07:18:06] [connect] Successful connection Nov 25 07:18:08 player volumio[11573]: info: Loading plugin "network"... Nov 25 07:18:08 player volumio[11573]: info: Refreshing Cached IP Addresses Nov 25 07:18:08 player sudo[11598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 07:18:08 player volumio[11573]: info: Loading plugin "services"... Nov 25 07:18:08 player volumio[11573]: info: Loading plugin "alsa_controller"... Nov 25 07:18:08 player sudo[11598]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:08 player sudo[11601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 07:18:08 player sudo[11601]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:08 player sudo[11598]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:08 player sudo[11601]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:08 player volumio[11573]: info: Loading plugin "wizard"... Nov 25 07:18:08 player volumio[11573]: info: Loading plugin "volumio_command_line_client"... Nov 25 07:18:08 player volumio[11573]: info: Loading plugin "upnp"... Nov 25 07:18:08 player volumio[11573]: info: [1732519088644] Starting Upmpd Daemon Nov 25 07:18:08 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 07:18:08 player volumio[11573]: info: Loading plugin "my_music"... Nov 25 07:18:08 player volumio[11573]: info: Loading plugin "mpd"... Nov 25 07:18:09 player volumio[11573]: info: Loading plugin "upnp_browser"... Nov 25 07:18:09 player volumio[11573]: info: Loading plugin "networkfs"... Nov 25 07:18:09 player volumio[11573]: info: Cannot mount NAS 71 at system boot, trial number 1 ,retrying in 5 seconds Nov 25 07:18:09 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 07:18:09 player volumio[11573]: info: Loading plugin "alarm-clock"... Nov 25 07:18:09 player volumio[11573]: info: Loading plugin "airplay_emulation"... Nov 25 07:18:09 player volumio[11573]: info: Starting Shairport Sync Nov 25 07:18:09 player volumio[11573]: info: Loading plugin "last_100"... Nov 25 07:18:09 player volumio[11573]: info: Loading plugin "webradio"... Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "i2s_dacs"... Nov 25 07:18:10 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "volumiodiscovery"... Nov 25 07:18:10 player volumio[11573]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 07:18:10 player node[11573]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 07:18:10 player node[11573]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 07:18:10 player node[11573]: *** WARNING *** For more information see Nov 25 07:18:10 player node[11573]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 07:18:10 player node[11573]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 07:18:10 player node[11573]: *** WARNING *** For more information see Nov 25 07:18:10 player volumio[11573]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 07:18:10 player volumio[11573]: *** WARNING *** For more information see Nov 25 07:18:10 player volumio[11573]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 07:18:10 player volumio[11573]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 07:18:10 player volumio[11573]: *** WARNING *** For more information see Nov 25 07:18:10 player volumio[11573]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 07:18:10 player volumio[11573]: Discovery: StartAdv! undefined Nov 25 07:18:10 player volumio[11573]: Discovery: Started advertising... player - undefined Nov 25 07:18:10 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "outputs"... Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "albumart"... Nov 25 07:18:10 player volumio[11573]: info: Plugin example_plugin is not enabled Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "inputs"... Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "updater_comm"... Nov 25 07:18:10 player volumio[11573]: info: Plugin mpdemulation is not enabled Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "rest_api"... Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "websocket"... Nov 25 07:18:10 player volumio[11573]: info: Loading plugin "yandex_music"... Nov 25 07:18:10 player volumio[11573]: Forking 3 albumart workers Nov 25 07:18:11 player volumio[11573]: Starting albumart workers Nov 25 07:18:11 player volumio[11573]: Starting albumart workers Nov 25 07:18:11 player volumio[11573]: Starting albumart workers Nov 25 07:18:12 player volumio[11573]: info: Applying required configuration parameters for plugin yandex_music Nov 25 07:18:12 player volumio[11573]: info: ___________ START PLUGINS ___________ Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 07:18:12 player volumio[11573]: info: [1732519092596] CoreMusicLibrary::Adding element Media Servers Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 07:18:12 player volumio[11573]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 07:18:12 player volumio[11573]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 07:18:12 player volumio[11573]: info: [1732519092725] CoreMusicLibrary::Adding element Last_100 Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 07:18:12 player volumio[11573]: info: [1732519092730] CoreMusicLibrary::Adding element Webradio Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 07:18:12 player volumio[11573]: info: [1732519092747] CoreMusicLibrary::Adding element Яндекс Музыка Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 07:18:12 player volumio[11573]: info: ------------------------------------------- Nov 25 07:18:12 player volumio[11573]: info: ----- MyVolumio plugins startup ---- Nov 25 07:18:12 player volumio[11573]: info: ------------------------------------------- Nov 25 07:18:12 player volumio[11573]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 25 07:18:12 player volumio[11573]: info: Loading i18n strings for locale ru Nov 25 07:18:12 player volumio[11573]: Updating browse sources language Nov 25 07:18:12 player volumio[11573]: Cannot find translation for sourceMedia Servers Nov 25 07:18:12 player volumio[11573]: Cannot find translation for sourceЯндекс Музыка Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::initPlayerControls Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 07:18:12 player volumio[11573]: info: BOOT COMPLETED Nov 25 07:18:12 player volumio[11573]: [Metrics] CommandRouter: 8s 679.34ms Nov 25 07:18:12 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 07:18:12 player kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Nov 25 07:18:18 player volumio[11573]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 25 07:18:18 player volumio[11573]: info: CoreCommandRouter::Close All Modals sent Nov 25 07:18:18 player volumio[11573]: info: CoreCommandRouter::Close All Modals sent Nov 25 07:18:18 player volumio[11573]: Express server listening on port 3000 Nov 25 07:18:18 player volumio[11573]: [Metrics] WebUI: 14s 711.85ms Nov 25 07:18:18 player volumio[11573]: Volumio Calling Home Nov 25 07:18:18 player volumio[11573]: info: CoreStateMachine::resetVolumioState Nov 25 07:18:18 player volumio[11573]: info: CoreStateMachine::getcurrentVolume Nov 25 07:18:18 player volumio[11573]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 07:18:18 player volumio[11573]: info: Cannot mount NAS 71 at system boot, trial number 2 ,retrying in 5 seconds Nov 25 07:18:18 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 07:18:18 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 07:18:18 player volumio[11573]: info: Setting Device type: Raspberry PI Nov 25 07:18:18 player volumio[11573]: info: MPD running with PID677 Nov 25 07:18:18 player volumio[11573]: ,establishing connection Nov 25 07:18:18 player sudo[11692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 07:18:18 player sudo[11692]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:18 player sudo[11694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 07:18:18 player sudo[11694]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:18 player sudo[11692]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:18 player sudo[11694]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:18 player volumio[11573]: info: VolumeController:: Volume=35 Mute =false Nov 25 07:18:18 player volumio[11573]: info: CoreStateMachine::pushState Nov 25 07:18:18 player volumio[11573]: info: CorePlayQueue::getTrack 0 Nov 25 07:18:18 player volumio[11573]: info: CoreCommandRouter::volumioPushState Nov 25 07:18:18 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 07:18:18 player volumio[11573]: info: CoreStateMachine::updateTrackBlock Nov 25 07:18:18 player volumio[11573]: info: CorePlayQueue::getTrackBlock Nov 25 07:18:18 player volumio[11573]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 07:18:19 player volumio-remote-updater[607]: [2024-11-25 07:18:19] [connect] Successful connection Nov 25 07:18:19 player volumio[11573]: info: Volumio called home Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 25 07:18:19 player volumio[11573]: info: Reloading queue from file Nov 25 07:18:19 player volumio[11573]: error: Failed LSINFO: null Nov 25 07:18:19 player volumio[11573]: info: CoreStateMachine::setRepeat false single undefined Nov 25 07:18:19 player volumio[11573]: info: CoreStateMachine::pushState Nov 25 07:18:19 player volumio[11573]: info: CorePlayQueue::getTrack 0 Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::volumioPushState Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 07:18:19 player volumio[11573]: info: CoreStateMachine::setRandom false Nov 25 07:18:19 player volumio[11573]: info: CoreStateMachine::pushState Nov 25 07:18:19 player volumio[11573]: info: CorePlayQueue::getTrack 0 Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::volumioPushState Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 07:18:19 player volumio-remote-updater[607]: [2024-11-25 07:18:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1732519099 101 Nov 25 07:18:19 player volumio[11573]: info: VolumeController:: Volume=35 Mute =false Nov 25 07:18:19 player volumio[11573]: info: CoreStateMachine::pushState Nov 25 07:18:19 player volumio[11573]: info: CorePlayQueue::getTrack 0 Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::volumioPushState Nov 25 07:18:19 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 07:18:19 player volumio[11573]: info: Starting Shairport Sync Nov 25 07:18:19 player volumio[11573]: info: Starting Shairport Sync Nov 25 07:18:19 player sudo[11713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 07:18:19 player sudo[11713]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:19 player sudo[11717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 07:18:19 player sudo[11717]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:19 player systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 07:18:19 player systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 25 07:18:19 player systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 07:18:19 player sudo[11713]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:19 player sudo[11717]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:19 player sudo[11743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 25 07:18:19 player sudo[11743]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:19 player systemd[1]: Started UPnP Renderer front-end to MPD. Nov 25 07:18:19 player sudo[11743]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:19 player volumio[11573]: info: Shairport-Sync Started Nov 25 07:18:19 player volumio[11573]: Error adding Membership: Error: addMembership EINVAL Nov 25 07:18:19 player volumio[11573]: info: Shairport-Sync Started Nov 25 07:18:20 player volumio[11573]: info: Upmpdcli Daemon Started Nov 25 07:18:20 player volumio[11573]: Discovery: adding 29b683d1-a6ca-45a8-91a7-c3ad222a1c10 Nov 25 07:18:20 player volumio[11573]: info: mDNS: Found device player Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::volumioGetState Nov 25 07:18:20 player volumio[11573]: info: CorePlayQueue::getTrack 0 Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::volumioGetState Nov 25 07:18:20 player volumio[11573]: info: CorePlayQueue::getTrack 0 Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::volumioGetState Nov 25 07:18:20 player volumio[11573]: info: CorePlayQueue::getTrack 0 Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 07:18:20 player volumio[11573]: info: Listing playlists Nov 25 07:18:20 player volumio[11573]: info: CoreCommandRouter::volumioGetQueue Nov 25 07:18:20 player volumio[11573]: info: CoreStateMachine::getQueue Nov 25 07:18:20 player volumio[11573]: info: CorePlayQueue::getQueue Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 25 07:18:22 player volumio[11573]: info: Adding plugin bluetooth to MyMusic Plugins Nov 25 07:18:22 player volumio[11573]: info: Adding plugin cd_controller to MyMusic Plugins Nov 25 07:18:22 player volumio[11573]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 25 07:18:22 player volumio[11573]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 25 07:18:22 player volumio[11573]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 25 07:18:23 player volumio[11573]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 25 07:18:23 player volumio[11573]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 25 07:18:23 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 07:18:23 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 07:18:23 player volumio[11573]: info: Starting MyVolumio Remote Streaming Endpoints Nov 25 07:18:23 player volumio[11573]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 25 07:18:23 player volumio[11573]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 25 07:18:23 player volumio[11573]: info: Streaming services startup Nov 25 07:18:23 player volumio[11573]: info: Starting Streaming Daemon Nov 25 07:18:23 player sudo[11753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 25 07:18:23 player sudo[11753]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 07:18:23 player systemd[1]: Stopping Volumio Streaming Daemon... Nov 25 07:18:23 player systemd[1]: Starting Volumio Streaming Daemon... Nov 25 07:18:23 player volumio[11573]: info: MyVolumio login type: Token Nov 25 07:18:23 player systemd[1]: Started Volumio Streaming Daemon. Nov 25 07:18:23 player sudo[11753]: pam_unix(sudo:session): session closed for user root Nov 25 07:18:23 player volumio[11573]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 25 07:18:23 player volumio-streaming-daemon[11763]: ############################ Nov 25 07:18:23 player volumio-streaming-daemon[11763]: # Volumio Streaming Daemon # Nov 25 07:18:23 player volumio-streaming-daemon[11763]: # Running on port 7777 # Nov 25 07:18:23 player volumio-streaming-daemon[11763]: ############################ Nov 25 07:18:23 player volumio-streaming-daemon[11763]: Environment: production Nov 25 07:18:23 player volumio[11573]: info: Cannot mount NAS 71 at system boot, trial number 3 ,retrying in 5 seconds Nov 25 07:18:26 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:27 player volumio-streaming-daemon[11763]: Environment: production Nov 25 07:18:28 player volumio[11573]: error: MyVolumio Custom Token format not valid, refreshing it Nov 25 07:18:29 player volumio[11573]: info: Fetching Streaming Services browse cache Nov 25 07:18:29 player volumio[11573]: info: Cannot mount NAS 71 at system boot, trial number 4 ,retrying in 5 seconds Nov 25 07:18:29 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:35 player volumio[11573]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 25 07:18:35 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:37 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:42 player volumio[11573]: info: MyVolumio login type: Token Nov 25 07:18:42 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:42 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 07:18:42 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 07:18:48 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:55 player volumio[11573]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 07:18:55 player volumio[11573]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 07:18:55 player volumio[11573]: { Error: connect ETIMEDOUT 151.101.2.79:443 Nov 25 07:18:55 player volumio[11573]: at Object._errnoException (util.js:1022:11) Nov 25 07:18:55 player volumio[11573]: at _exceptionWithHostPort (util.js:1044:20) Nov 25 07:18:55 player volumio[11573]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 25 07:18:55 player volumio[11573]: code: 'ETIMEDOUT', Nov 25 07:18:55 player volumio[11573]: errno: 'ETIMEDOUT', Nov 25 07:18:55 player volumio[11573]: syscall: 'connect', Nov 25 07:18:55 player volumio[11573]: address: '151.101.2.79', Nov 25 07:18:55 player volumio[11573]: port: 443 } Nov 25 07:18:55 player volumio[11573]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 07:18:56 player sudo[11797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 07:17 Nov 25 07:18:56 player sudo[11797]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"