-- Logs begin at Fri 2021-08-27 10:27:50 UTC, end at Fri 2021-08-27 17:51:47 UTC. -- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: ------------------------------------------- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: ----- Volumio2 ---- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: ------------------------------------------- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: ----- System startup ---- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: ------------------------------------------- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Plugin folders cleanup Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning into folder /volumio/app/plugins/ Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning category audio_interface Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning category miscellanea Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning category music_service Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning category plugins.json Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning category system_controller Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning category user_interface Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning into folder /data/plugins/ Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning category music_service Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Scanning category system_controller Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Plugin folders cleanup completed Aug 27 17:50:00 volumio2-vk volumio[2132]: info: ------------------------------------------- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: ----- Core plugins startup ---- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: ------------------------------------------- Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Adding plugin upnp to MyMusic Plugins Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Loading plugins from folder /data/plugins/ Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Loading plugin "system"... Aug 27 17:50:00 volumio2-vk volumio[2132]: info: Loading plugin "appearance"... Aug 27 17:50:01 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:50:01] [connect] Successful connection Aug 27 17:50:03 volumio2-vk volumio[2132]: info: Loading plugin "network"... Aug 27 17:50:03 volumio2-vk volumio[2132]: info: Loading plugin "services"... Aug 27 17:50:03 volumio2-vk volumio[2132]: info: Loading plugin "alsa_controller"... Aug 27 17:50:04 volumio2-vk volumio[2132]: info: Loading plugin "wizard"... Aug 27 17:50:04 volumio2-vk volumio[2132]: info: Loading plugin "volumio_command_line_client"... Aug 27 17:50:04 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:04 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:04 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:04 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:04 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:04 volumio2-vk volumio[2132]: info: Loading plugin "upnp"... Aug 27 17:50:04 volumio2-vk volumio[2132]: info: [1630086604292] Starting Upmpd Daemon Aug 27 17:50:04 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:50:04 volumio2-vk volumio[2132]: info: Loading plugin "my_music"... Aug 27 17:50:04 volumio2-vk volumio[2132]: info: Loading plugin "mpd"... Aug 27 17:50:05 volumio2-vk volumio[2132]: info: Loading plugin "upnp_browser"... Aug 27 17:50:07 volumio2-vk volumio[2132]: info: Loading plugin "networkfs"... Aug 27 17:50:07 volumio2-vk volumio[2132]: info: Cannot mount NAS RiksStation2 at system boot, trial number 1 ,retrying in 5 seconds Aug 27 17:50:07 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:50:07 volumio2-vk volumio[2132]: info: Loading plugin "alarm-clock"... Aug 27 17:50:08 volumio2-vk volumio[2132]: info: Loading plugin "airplay_emulation"... Aug 27 17:50:08 volumio2-vk volumio[2132]: info: Starting Shairport Sync Aug 27 17:50:08 volumio2-vk volumio[2132]: info: Loading plugin "last_100"... Aug 27 17:50:08 volumio2-vk volumio[2132]: info: Loading plugin "webradio"... Aug 27 17:50:08 volumio2-vk volumio[2132]: info: Loading plugin "i2s_dacs"... Aug 27 17:50:08 volumio2-vk volumio[2132]: info: I2S DAC not set, start Auto-detection Aug 27 17:50:08 volumio2-vk volumio[2132]: info: Loading plugin "volumiodiscovery"... Aug 27 17:50:08 volumio2-vk volumio[2132]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 17:50:08 volumio2-vk node[2132]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 17:50:08 volumio2-vk node[2132]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:50:08 volumio2-vk node[2132]: *** WARNING *** For more information see Aug 27 17:50:08 volumio2-vk node[2132]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 17:50:08 volumio2-vk node[2132]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:50:08 volumio2-vk node[2132]: *** WARNING *** For more information see Aug 27 17:50:08 volumio2-vk volumio[2132]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:50:08 volumio2-vk volumio[2132]: *** WARNING *** For more information see Aug 27 17:50:08 volumio2-vk volumio[2132]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 17:50:08 volumio2-vk volumio[2132]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:50:08 volumio2-vk volumio[2132]: *** WARNING *** For more information see Aug 27 17:50:08 volumio2-vk volumio[2132]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 17:50:08 volumio2-vk volumio[2132]: Discovery: StartAdv! undefined Aug 27 17:50:08 volumio2-vk volumio[2132]: Discovery: Started advertising... Volumio2-vk - undefined Aug 27 17:50:08 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:50:08 volumio2-vk volumio[2132]: info: Loading plugin "youtube"... Aug 27 17:50:12 volumio2-vk volumio[2132]: info: Loading plugin "albumart"... Aug 27 17:50:12 volumio2-vk volumio[2132]: info: Plugin example_plugin is not enabled Aug 27 17:50:12 volumio2-vk volumio[2132]: info: Loading plugin "updater_comm"... Aug 27 17:50:12 volumio2-vk volumio[2132]: info: Plugin mpdemulation is not enabled Aug 27 17:50:12 volumio2-vk volumio[2132]: info: Loading plugin "rest_api"... Aug 27 17:50:12 volumio2-vk volumio[2132]: info: Loading plugin "websocket"... Aug 27 17:50:12 volumio2-vk volumio[2132]: info: Loading plugin "backup_restore"... Aug 27 17:50:13 volumio2-vk volumio[2132]: Forking 3 albumart workers Aug 27 17:50:13 volumio2-vk volumio[2132]: info: ___________ START PLUGINS ___________ Aug 27 17:50:13 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:50:13 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:50:13 volumio2-vk volumio[2132]: info: [1630086613269] CoreMusicLibrary::Adding element Media Servers Aug 27 17:50:13 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:13 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:13 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:50:13 volumio2-vk volumio[2132]: info: [1630086613378] CoreMusicLibrary::Adding element Last_100 Aug 27 17:50:13 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:50:13 volumio2-vk volumio[2132]: info: [1630086613392] CoreMusicLibrary::Adding element Webradio Aug 27 17:50:13 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 17:50:13 volumio2-vk volumio[2132]: info: Youtube::onStart Adding to browse sources Aug 27 17:50:13 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:50:13 volumio2-vk volumio[2132]: info: [1630086613421] CoreMusicLibrary::Adding element Youtube Aug 27 17:50:13 volumio2-vk volumio[2132]: info: ------------------------------------------- Aug 27 17:50:13 volumio2-vk volumio[2132]: info: ----- MyVolumio plugins startup ---- Aug 27 17:50:13 volumio2-vk volumio[2132]: info: ------------------------------------------- Aug 27 17:50:13 volumio2-vk volumio[2132]: info: Loading plugins from folder /myvolumio/plugins Aug 27 17:50:13 volumio2-vk volumio[2132]: info: Loading plugins from folder /data/myvolumio/plugins Aug 27 17:50:13 volumio2-vk volumio[2132]: info: Loading plugin "my_volumio"... Aug 27 17:50:13 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:50:13] [connect] Successful connection Aug 27 17:50:14 volumio2-vk volumio[2132]: info: Loading plugin "streaming_services"... Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: Streaming services startup Aug 27 17:50:15 volumio2-vk volumio[2132]: Starting albumart workers Aug 27 17:50:15 volumio2-vk volumio[2132]: Starting albumart workers Aug 27 17:50:15 volumio2-vk sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 17:50:15 volumio2-vk volumio[2132]: info: Loading i18n strings for locale en Aug 27 17:50:15 volumio2-vk volumio[2132]: Updating browse sources language Aug 27 17:50:15 volumio2-vk sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:15 volumio2-vk volumio[2132]: Cannot find translation for sourceMedia Servers Aug 27 17:50:15 volumio2-vk volumio[2132]: Cannot find translation for sourceYoutube Aug 27 17:50:15 volumio2-vk systemd[1]: Stopping Volumio Streaming Daemon... Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::initPlayerControls Aug 27 17:50:15 volumio2-vk systemd[1]: Starting Volumio Streaming Daemon... Aug 27 17:50:15 volumio2-vk systemd[1]: Started Volumio Streaming Daemon. Aug 27 17:50:15 volumio2-vk sudo[2188]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:15 volumio2-vk volumio[2132]: Starting albumart workers Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:15 volumio2-vk volumio-streaming-daemon[2194]: ############################ Aug 27 17:50:15 volumio2-vk volumio-streaming-daemon[2194]: # Volumio Streaming Daemon # Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:50:15 volumio2-vk volumio-streaming-daemon[2194]: # Running on port 7777 # Aug 27 17:50:15 volumio2-vk volumio-streaming-daemon[2194]: ############################ Aug 27 17:50:15 volumio2-vk volumio[2132]: info: BOOT COMPLETED Aug 27 17:50:15 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:50:15 volumio2-vk kernel: usb 1-1.4: current rate 4500480 is different from the runtime rate 44100 Aug 27 17:50:21 volumio2-vk volumio[2132]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 27 17:50:21 volumio2-vk volumio[2132]: info: CoreCommandRouter::Close All Modals sent Aug 27 17:50:21 volumio2-vk volumio[2132]: info: CoreCommandRouter::Close All Modals sent Aug 27 17:50:21 volumio2-vk volumio[2132]: Express server listening on port 3000 Aug 27 17:50:21 volumio2-vk volumio[2132]: Volumio Calling Home Aug 27 17:50:21 volumio2-vk volumio[2132]: info: MyVolumio not started Aug 27 17:50:21 volumio2-vk volumio[2132]: info: CoreStateMachine::resetVolumioState Aug 27 17:50:21 volumio2-vk volumio[2132]: info: CoreStateMachine::getcurrentVolume Aug 27 17:50:21 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 17:50:21 volumio2-vk volumio[2132]: info: CoreStateMachine::updateTrackBlock Aug 27 17:50:21 volumio2-vk volumio[2132]: info: CorePlayQueue::getTrackBlock Aug 27 17:50:21 volumio2-vk volumio[2132]: info: Cannot mount NAS RiksStation2 at system boot, trial number 2 ,retrying in 5 seconds Aug 27 17:50:21 volumio2-vk sudo[2222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:50:21 volumio2-vk sudo[2222]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:21 volumio2-vk sudo[2222]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:22 volumio2-vk volumio[2132]: info: CoreStateMachine::pushState Aug 27 17:50:22 volumio2-vk volumio[2132]: info: CoreStateMachine::getState Aug 27 17:50:22 volumio2-vk volumio[2132]: info: CorePlayQueue::getTrack 0 Aug 27 17:50:22 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioPushState Aug 27 17:50:22 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:50:22 volumio2-vk volumio[2132]: info: Setting Device type: Raspberry PI Aug 27 17:50:22 volumio2-vk volumio[2132]: info: Cannot initialize MPD Connection: MPD is not running Aug 27 17:50:22 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 17:50:22 volumio2-vk sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Aug 27 17:50:22 volumio2-vk sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:22 volumio2-vk sudo[2233]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:22 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 17:50:23 volumio2-vk volumio[2132]: info: Aug 27 17:50:23 volumio2-vk volumio[2132]: ---------------------------- Client requests Volume 30 Aug 27 17:50:23 volumio2-vk volumio[2132]: info: VolumeController::SetAlsaVolume30 Aug 27 17:50:23 volumio2-vk volumio[2132]: info: CoreStateMachine::pushState Aug 27 17:50:23 volumio2-vk volumio[2132]: info: CoreStateMachine::getState Aug 27 17:50:23 volumio2-vk volumio[2132]: info: CorePlayQueue::getTrack 0 Aug 27 17:50:23 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioPushState Aug 27 17:50:23 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:50:23 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:50:23 volumio2-vk volumio[2132]: info: Reloading queue from file Aug 27 17:50:24 volumio2-vk volumio[2132]: info: Setting volume on startup at 30 Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreStateMachine::setRepeat true single undefined Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreStateMachine::pushState Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreStateMachine::getState Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CorePlayQueue::getTrack 0 Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioPushState Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreStateMachine::setRandom false Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreStateMachine::pushState Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreStateMachine::getState Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CorePlayQueue::getTrack 0 Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioPushState Aug 27 17:50:24 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:50:24 volumio2-vk sudo[2247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 27 17:50:24 volumio2-vk sudo[2247]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:24 volumio2-vk sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 27 17:50:24 volumio2-vk volumio[2132]: Discovery: adding ae7c4e99-8680-4124-847e-35035ca6085c Aug 27 17:50:24 volumio2-vk volumio[2132]: info: mDNS: Found device Volumio2-ak Aug 27 17:50:24 volumio2-vk sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:24 volumio2-vk sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 17:50:24 volumio2-vk systemd[1]: Stopping ShairportSync AirTunes receiver... Aug 27 17:50:24 volumio2-vk sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:24 volumio2-vk systemd[1]: Starting ShairportSync AirTunes receiver... Aug 27 17:50:24 volumio2-vk systemd[1]: Started ShairportSync AirTunes receiver. Aug 27 17:50:24 volumio2-vk sudo[2249]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:24 volumio2-vk sudo[2247]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:24 volumio2-vk systemd[1]: Started UPnP Renderer front-end to MPD. Aug 27 17:50:24 volumio2-vk sudo[2256]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:24 volumio2-vk shairport-sync[2265]: Successful Startup Aug 27 17:50:24 volumio2-vk volumio[2132]: info: Upmpdcli Daemon Started Aug 27 17:50:24 volumio2-vk volumio[2132]: info: Shairport-Sync Started Aug 27 17:50:24 volumio2-vk volumio[2132]: Error adding Membership: Error: addMembership EINVAL Aug 27 17:50:24 volumio2-vk volumio[2132]: info: Shairport-Sync Started Aug 27 17:50:24 volumio2-vk volumio[2132]: Discovery: adding c4e8251b-c190-4f38-99be-64a9d9148f89 Aug 27 17:50:24 volumio2-vk volumio[2132]: info: mDNS: Found device Volumio2-vk Aug 27 17:50:25 volumio2-vk sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:50:25 volumio2-vk sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:25 volumio2-vk sudo[2280]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:25 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioGetState Aug 27 17:50:25 volumio2-vk volumio[2132]: info: CoreStateMachine::getState Aug 27 17:50:25 volumio2-vk volumio[2132]: info: CorePlayQueue::getTrack 0 Aug 27 17:50:25 volumio2-vk sudo[2291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:50:25 volumio2-vk sudo[2291]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:25 volumio2-vk sudo[2291]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:26 volumio2-vk volumio[2132]: info: Cannot mount NAS RiksStation2 at system boot, trial number 3 ,retrying in 5 seconds Aug 27 17:50:28 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:50:28] [connect] Successful connection Aug 27 17:50:28 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 17:50:28 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioGetState Aug 27 17:50:28 volumio2-vk volumio[2132]: info: CoreStateMachine::getState Aug 27 17:50:28 volumio2-vk volumio[2132]: info: CorePlayQueue::getTrack 0 Aug 27 17:50:28 volumio2-vk sudo[2302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:50:29 volumio2-vk sudo[2302]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:29 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 17:50:29 volumio2-vk volumio[2132]: info: Listing playlists Aug 27 17:50:29 volumio2-vk volumio[2132]: info: CoreCommandRouter::volumioGetQueue Aug 27 17:50:29 volumio2-vk volumio[2132]: info: CoreStateMachine::getQueue Aug 27 17:50:29 volumio2-vk volumio[2132]: info: CorePlayQueue::getQueue Aug 27 17:50:29 volumio2-vk sudo[2306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:50:29 volumio2-vk sudo[2302]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:29 volumio2-vk sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:29 volumio2-vk sudo[2306]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:29 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:50:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1630086628 101 Aug 27 17:50:31 volumio2-vk volumio[2132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:50:31 volumio2-vk volumio[2132]: info: CURURI: music-library Aug 27 17:50:31 volumio2-vk volumio[2132]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:50:31 volumio2-vk volumio[2132]: TypeError: Cannot read property 'then' of undefined Aug 27 17:50:31 volumio2-vk volumio[2132]: at ControllerMpd.lsInfo (/volumio/app/plugins/music_service/mpd/index.js:1205:16) Aug 27 17:50:31 volumio2-vk volumio[2132]: at ControllerMpd.handleBrowseUri (/volumio/app/plugins/music_service/mpd/index.js:2893:25) Aug 27 17:50:31 volumio2-vk volumio[2132]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1018:29) Aug 27 17:50:31 volumio2-vk volumio[2132]: at CoreMusicLibrary.executeBrowseSource (/volumio/app/musiclibrary.js:329:47) Aug 27 17:50:31 volumio2-vk volumio[2132]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:363:32) Aug 27 17:50:31 volumio2-vk volumio[2132]: at emitTwo (events.js:126:13) Aug 27 17:50:31 volumio2-vk volumio[2132]: at Socket.emit (events.js:214:7) Aug 27 17:50:31 volumio2-vk volumio[2132]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 27 17:50:31 volumio2-vk volumio[2132]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 27 17:50:31 volumio2-vk volumio[2132]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 27 17:50:31 volumio2-vk volumio[2132]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:50:33 volumio2-vk sudo[2331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-08-27 17:49 Aug 27 17:50:33 volumio2-vk sudo[2331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:33 volumio2-vk sudo[2331]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:33 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:50:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 27 17:50:33 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:50:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 27 17:50:33 volumio2-vk systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Aug 27 17:50:33 volumio2-vk systemd[1]: Unit volumio.service entered failed state. Aug 27 17:50:33 volumio2-vk systemd[1]: Starting dynamicswap service... Aug 27 17:50:33 volumio2-vk systemd[1]: Started dynamicswap service. Aug 27 17:50:34 volumio2-vk systemd[1]: volumio.service holdoff time over, scheduling restart. Aug 27 17:50:34 volumio2-vk systemd[1]: Starting dynamicswap service... Aug 27 17:50:34 volumio2-vk systemd[1]: Started dynamicswap service. Aug 27 17:50:34 volumio2-vk systemd[1]: Stopping Volumio Backend Module... Aug 27 17:50:34 volumio2-vk systemd[1]: Starting Volumio Backend Module... Aug 27 17:50:34 volumio2-vk systemd[1]: Started Volumio Backend Module. Aug 27 17:50:38 volumio2-vk volumio[2352]: info: ------------------------------------------- Aug 27 17:50:38 volumio2-vk volumio[2352]: info: ----- Volumio2 ---- Aug 27 17:50:38 volumio2-vk volumio[2352]: info: ------------------------------------------- Aug 27 17:50:38 volumio2-vk volumio[2352]: info: ----- System startup ---- Aug 27 17:50:38 volumio2-vk volumio[2352]: info: ------------------------------------------- Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Plugin folders cleanup Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning into folder /volumio/app/plugins/ Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning category audio_interface Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning category miscellanea Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning category music_service Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning category plugins.json Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning category system_controller Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning category user_interface Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning into folder /data/plugins/ Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning category music_service Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Scanning category system_controller Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Plugin folders cleanup completed Aug 27 17:50:38 volumio2-vk volumio[2352]: info: ------------------------------------------- Aug 27 17:50:38 volumio2-vk volumio[2352]: info: ----- Core plugins startup ---- Aug 27 17:50:38 volumio2-vk volumio[2352]: info: ------------------------------------------- Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Adding plugin upnp to MyMusic Plugins Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Loading plugins from folder /data/plugins/ Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Loading plugin "system"... Aug 27 17:50:38 volumio2-vk volumio[2352]: info: Loading plugin "appearance"... Aug 27 17:50:38 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:50:38] [connect] Successful connection Aug 27 17:50:41 volumio2-vk volumio[2352]: info: Loading plugin "network"... Aug 27 17:50:41 volumio2-vk volumio[2352]: info: Loading plugin "services"... Aug 27 17:50:41 volumio2-vk volumio[2352]: info: Loading plugin "alsa_controller"... Aug 27 17:50:41 volumio2-vk volumio[2352]: info: Loading plugin "wizard"... Aug 27 17:50:41 volumio2-vk volumio[2352]: info: Loading plugin "volumio_command_line_client"... Aug 27 17:50:41 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:41 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:41 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:41 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:41 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:42 volumio2-vk volumio[2352]: info: Loading plugin "upnp"... Aug 27 17:50:42 volumio2-vk volumio[2352]: info: [1630086642061] Starting Upmpd Daemon Aug 27 17:50:42 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:50:42 volumio2-vk volumio[2352]: info: Loading plugin "my_music"... Aug 27 17:50:42 volumio2-vk volumio[2352]: info: Loading plugin "mpd"... Aug 27 17:50:42 volumio2-vk volumio[2352]: info: Loading plugin "upnp_browser"... Aug 27 17:50:44 volumio2-vk volumio[2352]: info: Loading plugin "networkfs"... Aug 27 17:50:45 volumio2-vk volumio[2352]: info: Cannot mount NAS RiksStation2 at system boot, trial number 1 ,retrying in 5 seconds Aug 27 17:50:45 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:50:45 volumio2-vk volumio[2352]: info: Loading plugin "alarm-clock"... Aug 27 17:50:45 volumio2-vk volumio[2352]: info: Loading plugin "airplay_emulation"... Aug 27 17:50:45 volumio2-vk volumio[2352]: info: Starting Shairport Sync Aug 27 17:50:45 volumio2-vk volumio[2352]: info: Loading plugin "last_100"... Aug 27 17:50:45 volumio2-vk volumio[2352]: info: Loading plugin "webradio"... Aug 27 17:50:46 volumio2-vk volumio[2352]: info: Loading plugin "i2s_dacs"... Aug 27 17:50:46 volumio2-vk volumio[2352]: info: I2S DAC not set, start Auto-detection Aug 27 17:50:46 volumio2-vk volumio[2352]: info: Loading plugin "volumiodiscovery"... Aug 27 17:50:46 volumio2-vk volumio[2352]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 17:50:46 volumio2-vk node[2352]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 17:50:46 volumio2-vk node[2352]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:50:46 volumio2-vk node[2352]: *** WARNING *** For more information see Aug 27 17:50:46 volumio2-vk node[2352]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 17:50:46 volumio2-vk node[2352]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:50:46 volumio2-vk node[2352]: *** WARNING *** For more information see Aug 27 17:50:46 volumio2-vk volumio[2352]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:50:46 volumio2-vk volumio[2352]: *** WARNING *** For more information see Aug 27 17:50:46 volumio2-vk volumio[2352]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 17:50:46 volumio2-vk volumio[2352]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:50:46 volumio2-vk volumio[2352]: *** WARNING *** For more information see Aug 27 17:50:46 volumio2-vk volumio[2352]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 17:50:46 volumio2-vk volumio[2352]: Discovery: StartAdv! undefined Aug 27 17:50:46 volumio2-vk volumio[2352]: Discovery: Started advertising... Volumio2-vk - undefined Aug 27 17:50:46 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:50:46 volumio2-vk volumio[2352]: info: Loading plugin "youtube"... Aug 27 17:50:49 volumio2-vk volumio[2352]: info: Loading plugin "albumart"... Aug 27 17:50:49 volumio2-vk volumio[2352]: info: Plugin example_plugin is not enabled Aug 27 17:50:49 volumio2-vk volumio[2352]: info: Loading plugin "updater_comm"... Aug 27 17:50:49 volumio2-vk volumio[2352]: info: Plugin mpdemulation is not enabled Aug 27 17:50:49 volumio2-vk volumio[2352]: info: Loading plugin "rest_api"... Aug 27 17:50:49 volumio2-vk volumio[2352]: info: Loading plugin "websocket"... Aug 27 17:50:50 volumio2-vk volumio[2352]: info: Loading plugin "backup_restore"... Aug 27 17:50:50 volumio2-vk volumio[2352]: Forking 3 albumart workers Aug 27 17:50:50 volumio2-vk volumio[2352]: info: ___________ START PLUGINS ___________ Aug 27 17:50:50 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:50:50 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:50:50 volumio2-vk volumio[2352]: info: [1630086650724] CoreMusicLibrary::Adding element Media Servers Aug 27 17:50:50 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:50 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:50 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:50:50 volumio2-vk volumio[2352]: info: [1630086650846] CoreMusicLibrary::Adding element Last_100 Aug 27 17:50:50 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:50:50 volumio2-vk volumio[2352]: info: [1630086650854] CoreMusicLibrary::Adding element Webradio Aug 27 17:50:50 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 17:50:50 volumio2-vk volumio[2352]: info: Youtube::onStart Adding to browse sources Aug 27 17:50:50 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:50:50 volumio2-vk volumio[2352]: info: [1630086650869] CoreMusicLibrary::Adding element Youtube Aug 27 17:50:50 volumio2-vk volumio[2352]: info: ------------------------------------------- Aug 27 17:50:50 volumio2-vk volumio[2352]: info: ----- MyVolumio plugins startup ---- Aug 27 17:50:50 volumio2-vk volumio[2352]: info: ------------------------------------------- Aug 27 17:50:50 volumio2-vk volumio[2352]: info: Loading plugins from folder /myvolumio/plugins Aug 27 17:50:50 volumio2-vk volumio[2352]: info: Loading plugins from folder /data/myvolumio/plugins Aug 27 17:50:50 volumio2-vk volumio[2352]: info: Loading plugin "my_volumio"... Aug 27 17:50:51 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:50:51] [connect] Successful connection Aug 27 17:50:52 volumio2-vk volumio[2352]: info: Loading plugin "streaming_services"... Aug 27 17:50:52 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:50:52 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:50:52 volumio2-vk volumio[2352]: info: Streaming services startup Aug 27 17:50:52 volumio2-vk volumio[2352]: info: Loading i18n strings for locale en Aug 27 17:50:52 volumio2-vk volumio[2352]: Updating browse sources language Aug 27 17:50:52 volumio2-vk volumio[2352]: Cannot find translation for sourceMedia Servers Aug 27 17:50:52 volumio2-vk volumio[2352]: Cannot find translation for sourceYoutube Aug 27 17:50:52 volumio2-vk sudo[2408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 17:50:52 volumio2-vk sudo[2408]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:52 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::initPlayerControls Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:53 volumio2-vk systemd[1]: Stopping Volumio Streaming Daemon... Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:50:53 volumio2-vk systemd[1]: Starting Volumio Streaming Daemon... Aug 27 17:50:53 volumio2-vk systemd[1]: Started Volumio Streaming Daemon. Aug 27 17:50:53 volumio2-vk sudo[2408]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:53 volumio2-vk volumio-streaming-daemon[2414]: ############################ Aug 27 17:50:53 volumio2-vk volumio-streaming-daemon[2414]: # Volumio Streaming Daemon # Aug 27 17:50:53 volumio2-vk volumio-streaming-daemon[2414]: # Running on port 7777 # Aug 27 17:50:53 volumio2-vk volumio-streaming-daemon[2414]: ############################ Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:50:53 volumio2-vk volumio[2352]: info: BOOT COMPLETED Aug 27 17:50:53 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:50:53 volumio2-vk volumio[2352]: Starting albumart workers Aug 27 17:50:53 volumio2-vk kernel: usb 1-1.4: current rate 4500480 is different from the runtime rate 44100 Aug 27 17:50:53 volumio2-vk volumio[2352]: Starting albumart workers Aug 27 17:50:53 volumio2-vk volumio[2352]: Starting albumart workers Aug 27 17:50:58 volumio2-vk volumio[2352]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 27 17:50:58 volumio2-vk volumio[2352]: info: CoreCommandRouter::Close All Modals sent Aug 27 17:50:58 volumio2-vk volumio[2352]: info: CoreCommandRouter::Close All Modals sent Aug 27 17:50:58 volumio2-vk volumio[2352]: Express server listening on port 3000 Aug 27 17:50:58 volumio2-vk volumio[2352]: Volumio Calling Home Aug 27 17:50:58 volumio2-vk volumio[2352]: info: MyVolumio not started Aug 27 17:50:58 volumio2-vk volumio[2352]: info: CoreStateMachine::resetVolumioState Aug 27 17:50:58 volumio2-vk volumio[2352]: info: CoreStateMachine::getcurrentVolume Aug 27 17:50:58 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 17:50:58 volumio2-vk volumio[2352]: info: CoreStateMachine::updateTrackBlock Aug 27 17:50:58 volumio2-vk volumio[2352]: info: CorePlayQueue::getTrackBlock Aug 27 17:50:59 volumio2-vk volumio[2352]: info: Cannot mount NAS RiksStation2 at system boot, trial number 2 ,retrying in 5 seconds Aug 27 17:50:59 volumio2-vk sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:50:59 volumio2-vk sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:50:59 volumio2-vk sudo[2443]: pam_unix(sudo:session): session closed for user root Aug 27 17:50:59 volumio2-vk volumio[2352]: info: CoreStateMachine::pushState Aug 27 17:50:59 volumio2-vk volumio[2352]: info: CoreStateMachine::getState Aug 27 17:50:59 volumio2-vk volumio[2352]: info: CorePlayQueue::getTrack 0 Aug 27 17:50:59 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioPushState Aug 27 17:50:59 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:50:59 volumio2-vk volumio[2352]: info: Setting Device type: Raspberry PI Aug 27 17:50:59 volumio2-vk volumio[2352]: info: Cannot initialize MPD Connection: MPD is not running Aug 27 17:50:59 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 17:51:00 volumio2-vk sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Aug 27 17:51:00 volumio2-vk sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:00 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 17:51:00 volumio2-vk sudo[2454]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:00 volumio2-vk volumio[2352]: info: Aug 27 17:51:00 volumio2-vk volumio[2352]: ---------------------------- Client requests Volume 30 Aug 27 17:51:00 volumio2-vk volumio[2352]: info: VolumeController::SetAlsaVolume30 Aug 27 17:51:00 volumio2-vk volumio[2352]: info: CoreStateMachine::pushState Aug 27 17:51:00 volumio2-vk volumio[2352]: info: CoreStateMachine::getState Aug 27 17:51:00 volumio2-vk volumio[2352]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:00 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioPushState Aug 27 17:51:00 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:51:01 volumio2-vk volumio[2352]: info: Reloading queue from file Aug 27 17:51:01 volumio2-vk volumio[2352]: info: Setting volume on startup at 30 Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreStateMachine::setRepeat true single undefined Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreStateMachine::pushState Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreStateMachine::getState Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioPushState Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreStateMachine::setRandom false Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreStateMachine::pushState Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreStateMachine::getState Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioPushState Aug 27 17:51:01 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:51:01 volumio2-vk sudo[2468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 17:51:01 volumio2-vk sudo[2468]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:01 volumio2-vk volumio[2352]: Discovery: adding ae7c4e99-8680-4124-847e-35035ca6085c Aug 27 17:51:01 volumio2-vk volumio[2352]: info: mDNS: Found device Volumio2-ak Aug 27 17:51:01 volumio2-vk sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 27 17:51:01 volumio2-vk systemd[1]: Started UPnP Renderer front-end to MPD. Aug 27 17:51:01 volumio2-vk sudo[2468]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:01 volumio2-vk sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 27 17:51:01 volumio2-vk sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:01 volumio2-vk systemd[1]: Stopping ShairportSync AirTunes receiver... Aug 27 17:51:01 volumio2-vk sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:01 volumio2-vk systemd[1]: Starting ShairportSync AirTunes receiver... Aug 27 17:51:01 volumio2-vk sudo[2471]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:01 volumio2-vk systemd[1]: Started ShairportSync AirTunes receiver. Aug 27 17:51:01 volumio2-vk systemd[1]: Stopping ShairportSync AirTunes receiver... Aug 27 17:51:01 volumio2-vk systemd[1]: Starting ShairportSync AirTunes receiver... Aug 27 17:51:02 volumio2-vk systemd[1]: Started ShairportSync AirTunes receiver. Aug 27 17:51:02 volumio2-vk sudo[2476]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:02 volumio2-vk shairport-sync[2494]: Successful Startup Aug 27 17:51:02 volumio2-vk volumio[2352]: info: Upmpdcli Daemon Started Aug 27 17:51:02 volumio2-vk volumio[2352]: info: Shairport-Sync Started Aug 27 17:51:02 volumio2-vk volumio[2352]: Error adding Membership: Error: addMembership EINVAL Aug 27 17:51:02 volumio2-vk volumio[2352]: info: Shairport-Sync Started Aug 27 17:51:02 volumio2-vk volumio[2352]: Discovery: adding c4e8251b-c190-4f38-99be-64a9d9148f89 Aug 27 17:51:02 volumio2-vk volumio[2352]: info: mDNS: Found device Volumio2-vk Aug 27 17:51:02 volumio2-vk sudo[2501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:02 volumio2-vk sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:02 volumio2-vk sudo[2501]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:02 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioGetState Aug 27 17:51:02 volumio2-vk volumio[2352]: info: CoreStateMachine::getState Aug 27 17:51:02 volumio2-vk volumio[2352]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:02 volumio2-vk sudo[2512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:02 volumio2-vk sudo[2512]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:02 volumio2-vk sudo[2512]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:03 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 17:51:03 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioGetState Aug 27 17:51:03 volumio2-vk volumio[2352]: info: CoreStateMachine::getState Aug 27 17:51:03 volumio2-vk volumio[2352]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:03 volumio2-vk sudo[2525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:03 volumio2-vk sudo[2525]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:03 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 17:51:03 volumio2-vk volumio[2352]: info: Listing playlists Aug 27 17:51:03 volumio2-vk volumio[2352]: info: CoreCommandRouter::volumioGetQueue Aug 27 17:51:03 volumio2-vk volumio[2352]: info: CoreStateMachine::getQueue Aug 27 17:51:03 volumio2-vk volumio[2352]: info: CorePlayQueue::getQueue Aug 27 17:51:03 volumio2-vk sudo[2525]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:03 volumio2-vk sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:03 volumio2-vk sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:03 volumio2-vk sudo[2530]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:04 volumio2-vk volumio[2352]: info: Cannot mount NAS RiksStation2 at system boot, trial number 3 ,retrying in 5 seconds Aug 27 17:51:06 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:51:06] [connect] Successful connection Aug 27 17:51:06 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:51:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1630086666 101 Aug 27 17:51:08 volumio2-vk volumio[2352]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:51:08 volumio2-vk volumio[2352]: info: CURURI: music-library Aug 27 17:51:08 volumio2-vk volumio[2352]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:51:08 volumio2-vk volumio[2352]: TypeError: Cannot read property 'then' of undefined Aug 27 17:51:08 volumio2-vk volumio[2352]: at ControllerMpd.lsInfo (/volumio/app/plugins/music_service/mpd/index.js:1205:16) Aug 27 17:51:08 volumio2-vk volumio[2352]: at ControllerMpd.handleBrowseUri (/volumio/app/plugins/music_service/mpd/index.js:2893:25) Aug 27 17:51:08 volumio2-vk volumio[2352]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1018:29) Aug 27 17:51:08 volumio2-vk volumio[2352]: at CoreMusicLibrary.executeBrowseSource (/volumio/app/musiclibrary.js:329:47) Aug 27 17:51:08 volumio2-vk volumio[2352]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:363:32) Aug 27 17:51:08 volumio2-vk volumio[2352]: at emitTwo (events.js:126:13) Aug 27 17:51:08 volumio2-vk volumio[2352]: at Socket.emit (events.js:214:7) Aug 27 17:51:08 volumio2-vk volumio[2352]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 27 17:51:08 volumio2-vk volumio[2352]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 27 17:51:08 volumio2-vk volumio[2352]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 27 17:51:08 volumio2-vk volumio[2352]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:51:10 volumio2-vk sudo[2554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-08-27 17:50 Aug 27 17:51:10 volumio2-vk sudo[2554]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:10 volumio2-vk sudo[2554]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:10 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:51:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 27 17:51:10 volumio2-vk systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Aug 27 17:51:10 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:51:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 27 17:51:10 volumio2-vk systemd[1]: Unit volumio.service entered failed state. Aug 27 17:51:10 volumio2-vk systemd[1]: Starting dynamicswap service... Aug 27 17:51:10 volumio2-vk systemd[1]: Started dynamicswap service. Aug 27 17:51:10 volumio2-vk systemd[1]: volumio.service holdoff time over, scheduling restart. Aug 27 17:51:10 volumio2-vk systemd[1]: Starting dynamicswap service... Aug 27 17:51:10 volumio2-vk systemd[1]: Started dynamicswap service. Aug 27 17:51:10 volumio2-vk systemd[1]: Stopping Volumio Backend Module... Aug 27 17:51:10 volumio2-vk systemd[1]: Starting Volumio Backend Module... Aug 27 17:51:10 volumio2-vk systemd[1]: Started Volumio Backend Module. Aug 27 17:51:14 volumio2-vk volumio[2575]: info: ------------------------------------------- Aug 27 17:51:14 volumio2-vk volumio[2575]: info: ----- Volumio2 ---- Aug 27 17:51:14 volumio2-vk volumio[2575]: info: ------------------------------------------- Aug 27 17:51:14 volumio2-vk volumio[2575]: info: ----- System startup ---- Aug 27 17:51:14 volumio2-vk volumio[2575]: info: ------------------------------------------- Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Plugin folders cleanup Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning into folder /volumio/app/plugins/ Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning category audio_interface Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning category miscellanea Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning category music_service Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning category plugins.json Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning category system_controller Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning category user_interface Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning into folder /data/plugins/ Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning category music_service Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Scanning category system_controller Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Plugin folders cleanup completed Aug 27 17:51:15 volumio2-vk volumio[2575]: info: ------------------------------------------- Aug 27 17:51:15 volumio2-vk volumio[2575]: info: ----- Core plugins startup ---- Aug 27 17:51:15 volumio2-vk volumio[2575]: info: ------------------------------------------- Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Adding plugin upnp to MyMusic Plugins Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Loading plugins from folder /data/plugins/ Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Loading plugin "system"... Aug 27 17:51:15 volumio2-vk volumio[2575]: info: Loading plugin "appearance"... Aug 27 17:51:15 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:51:15] [connect] Successful connection Aug 27 17:51:17 volumio2-vk volumio[2575]: info: Loading plugin "network"... Aug 27 17:51:18 volumio2-vk volumio[2575]: info: Loading plugin "services"... Aug 27 17:51:18 volumio2-vk volumio[2575]: info: Loading plugin "alsa_controller"... Aug 27 17:51:18 volumio2-vk volumio[2575]: info: Loading plugin "wizard"... Aug 27 17:51:18 volumio2-vk volumio[2575]: info: Loading plugin "volumio_command_line_client"... Aug 27 17:51:18 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:18 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:18 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:18 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:18 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:18 volumio2-vk volumio[2575]: info: Loading plugin "upnp"... Aug 27 17:51:18 volumio2-vk volumio[2575]: info: [1630086678560] Starting Upmpd Daemon Aug 27 17:51:18 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:51:18 volumio2-vk volumio[2575]: info: Loading plugin "my_music"... Aug 27 17:51:18 volumio2-vk volumio[2575]: info: Loading plugin "mpd"... Aug 27 17:51:19 volumio2-vk volumio[2575]: info: Loading plugin "upnp_browser"... Aug 27 17:51:21 volumio2-vk volumio[2575]: info: Loading plugin "networkfs"... Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Cannot mount NAS RiksStation2 at system boot, trial number 1 ,retrying in 5 seconds Aug 27 17:51:22 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Loading plugin "alarm-clock"... Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Loading plugin "airplay_emulation"... Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Starting Shairport Sync Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Loading plugin "last_100"... Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Loading plugin "webradio"... Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Loading plugin "i2s_dacs"... Aug 27 17:51:22 volumio2-vk volumio[2575]: info: I2S DAC not set, start Auto-detection Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Loading plugin "volumiodiscovery"... Aug 27 17:51:22 volumio2-vk volumio[2575]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 17:51:22 volumio2-vk node[2575]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 17:51:22 volumio2-vk node[2575]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:51:22 volumio2-vk node[2575]: *** WARNING *** For more information see Aug 27 17:51:22 volumio2-vk node[2575]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 17:51:22 volumio2-vk node[2575]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:51:22 volumio2-vk node[2575]: *** WARNING *** For more information see Aug 27 17:51:22 volumio2-vk volumio[2575]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:51:22 volumio2-vk volumio[2575]: *** WARNING *** For more information see Aug 27 17:51:22 volumio2-vk volumio[2575]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 17:51:22 volumio2-vk volumio[2575]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 17:51:22 volumio2-vk volumio[2575]: *** WARNING *** For more information see Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 17:51:22 volumio2-vk volumio[2575]: Discovery: StartAdv! undefined Aug 27 17:51:22 volumio2-vk volumio[2575]: Discovery: Started advertising... Volumio2-vk - undefined Aug 27 17:51:22 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 17:51:22 volumio2-vk volumio[2575]: info: Loading plugin "youtube"... Aug 27 17:51:26 volumio2-vk volumio[2575]: info: Loading plugin "albumart"... Aug 27 17:51:26 volumio2-vk volumio[2575]: info: Plugin example_plugin is not enabled Aug 27 17:51:26 volumio2-vk volumio[2575]: info: Loading plugin "updater_comm"... Aug 27 17:51:26 volumio2-vk volumio[2575]: info: Plugin mpdemulation is not enabled Aug 27 17:51:26 volumio2-vk volumio[2575]: info: Loading plugin "rest_api"... Aug 27 17:51:26 volumio2-vk volumio[2575]: info: Loading plugin "websocket"... Aug 27 17:51:26 volumio2-vk volumio[2575]: info: Loading plugin "backup_restore"... Aug 27 17:51:27 volumio2-vk volumio[2575]: Forking 3 albumart workers Aug 27 17:51:27 volumio2-vk volumio[2575]: info: ___________ START PLUGINS ___________ Aug 27 17:51:27 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:51:27 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:51:27 volumio2-vk volumio[2575]: info: [1630086687607] CoreMusicLibrary::Adding element Media Servers Aug 27 17:51:27 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:27 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:27 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:51:27 volumio2-vk volumio[2575]: info: [1630086687710] CoreMusicLibrary::Adding element Last_100 Aug 27 17:51:27 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:51:27 volumio2-vk volumio[2575]: info: [1630086687719] CoreMusicLibrary::Adding element Webradio Aug 27 17:51:27 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 17:51:27 volumio2-vk volumio[2575]: info: Youtube::onStart Adding to browse sources Aug 27 17:51:27 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 17:51:27 volumio2-vk volumio[2575]: info: [1630086687735] CoreMusicLibrary::Adding element Youtube Aug 27 17:51:27 volumio2-vk volumio[2575]: info: ------------------------------------------- Aug 27 17:51:27 volumio2-vk volumio[2575]: info: ----- MyVolumio plugins startup ---- Aug 27 17:51:27 volumio2-vk volumio[2575]: info: ------------------------------------------- Aug 27 17:51:27 volumio2-vk volumio[2575]: info: Loading plugins from folder /myvolumio/plugins Aug 27 17:51:27 volumio2-vk volumio[2575]: info: Loading plugins from folder /data/myvolumio/plugins Aug 27 17:51:27 volumio2-vk volumio[2575]: info: Loading plugin "my_volumio"... Aug 27 17:51:28 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:51:28] [connect] Successful connection Aug 27 17:51:29 volumio2-vk volumio[2575]: info: Loading plugin "streaming_services"... Aug 27 17:51:29 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:51:29 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:51:29 volumio2-vk volumio[2575]: Starting albumart workers Aug 27 17:51:29 volumio2-vk volumio[2575]: Starting albumart workers Aug 27 17:51:29 volumio2-vk volumio[2575]: info: Streaming services startup Aug 27 17:51:29 volumio2-vk volumio[2575]: info: Loading i18n strings for locale en Aug 27 17:51:29 volumio2-vk volumio[2575]: Updating browse sources language Aug 27 17:51:29 volumio2-vk volumio[2575]: Cannot find translation for sourceMedia Servers Aug 27 17:51:29 volumio2-vk volumio[2575]: Cannot find translation for sourceYoutube Aug 27 17:51:29 volumio2-vk volumio[2575]: Starting albumart workers Aug 27 17:51:29 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:51:29 volumio2-vk volumio[2575]: info: CoreCommandRouter::initPlayerControls Aug 27 17:51:29 volumio2-vk sudo[2630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 17:51:30 volumio2-vk sudo[2630]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 17:51:30 volumio2-vk volumio[2575]: info: BOOT COMPLETED Aug 27 17:51:30 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:51:30 volumio2-vk systemd[1]: Stopping Volumio Streaming Daemon... Aug 27 17:51:30 volumio2-vk systemd[1]: Starting Volumio Streaming Daemon... Aug 27 17:51:30 volumio2-vk systemd[1]: Started Volumio Streaming Daemon. Aug 27 17:51:30 volumio2-vk sudo[2630]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:30 volumio2-vk volumio-streaming-daemon[2638]: ############################ Aug 27 17:51:30 volumio2-vk volumio-streaming-daemon[2638]: # Volumio Streaming Daemon # Aug 27 17:51:30 volumio2-vk volumio-streaming-daemon[2638]: # Running on port 7777 # Aug 27 17:51:30 volumio2-vk volumio-streaming-daemon[2638]: ############################ Aug 27 17:51:30 volumio2-vk kernel: usb 1-1.4: current rate 4500480 is different from the runtime rate 44100 Aug 27 17:51:35 volumio2-vk volumio[2575]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 27 17:51:35 volumio2-vk volumio[2575]: info: CoreCommandRouter::Close All Modals sent Aug 27 17:51:35 volumio2-vk volumio[2575]: info: CoreCommandRouter::Close All Modals sent Aug 27 17:51:35 volumio2-vk volumio[2575]: Express server listening on port 3000 Aug 27 17:51:35 volumio2-vk volumio[2575]: Volumio Calling Home Aug 27 17:51:35 volumio2-vk volumio[2575]: info: MyVolumio not started Aug 27 17:51:35 volumio2-vk volumio[2575]: info: CoreStateMachine::resetVolumioState Aug 27 17:51:35 volumio2-vk volumio[2575]: info: CoreStateMachine::getcurrentVolume Aug 27 17:51:35 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 17:51:35 volumio2-vk volumio[2575]: info: CoreStateMachine::updateTrackBlock Aug 27 17:51:35 volumio2-vk volumio[2575]: info: CorePlayQueue::getTrackBlock Aug 27 17:51:36 volumio2-vk volumio[2575]: info: Cannot mount NAS RiksStation2 at system boot, trial number 2 ,retrying in 5 seconds Aug 27 17:51:36 volumio2-vk sudo[2663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:36 volumio2-vk sudo[2663]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:36 volumio2-vk sudo[2663]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:36 volumio2-vk volumio[2575]: info: CoreStateMachine::pushState Aug 27 17:51:36 volumio2-vk volumio[2575]: info: CoreStateMachine::getState Aug 27 17:51:36 volumio2-vk volumio[2575]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:36 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioPushState Aug 27 17:51:36 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:51:36 volumio2-vk volumio[2575]: info: Setting Device type: Raspberry PI Aug 27 17:51:37 volumio2-vk volumio[2575]: info: Cannot initialize MPD Connection: MPD is not running Aug 27 17:51:37 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 17:51:37 volumio2-vk sudo[2675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Aug 27 17:51:37 volumio2-vk sudo[2675]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:37 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 17:51:37 volumio2-vk sudo[2675]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:37 volumio2-vk volumio[2575]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 27 17:51:37 volumio2-vk volumio[2575]: info: Aug 27 17:51:37 volumio2-vk volumio[2575]: ---------------------------- Client requests Volume 30 Aug 27 17:51:37 volumio2-vk volumio[2575]: info: VolumeController::SetAlsaVolume30 Aug 27 17:51:37 volumio2-vk volumio[2575]: info: CoreStateMachine::pushState Aug 27 17:51:37 volumio2-vk volumio[2575]: info: CoreStateMachine::getState Aug 27 17:51:37 volumio2-vk volumio[2575]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:37 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioPushState Aug 27 17:51:37 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 17:51:38 volumio2-vk volumio[2575]: info: Reloading queue from file Aug 27 17:51:38 volumio2-vk volumio[2575]: info: Setting volume on startup at 30 Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreStateMachine::setRepeat true single undefined Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreStateMachine::pushState Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreStateMachine::getState Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioPushState Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreStateMachine::setRandom false Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreStateMachine::pushState Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreStateMachine::getState Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioPushState Aug 27 17:51:38 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 17:51:38 volumio2-vk sudo[2689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 17:51:38 volumio2-vk sudo[2689]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:38 volumio2-vk volumio[2575]: Discovery: adding ae7c4e99-8680-4124-847e-35035ca6085c Aug 27 17:51:38 volumio2-vk sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 27 17:51:38 volumio2-vk volumio[2575]: info: mDNS: Found device Volumio2-ak Aug 27 17:51:38 volumio2-vk systemd[1]: Started UPnP Renderer front-end to MPD. Aug 27 17:51:38 volumio2-vk sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:38 volumio2-vk sudo[2696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 27 17:51:38 volumio2-vk sudo[2689]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:38 volumio2-vk sudo[2696]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:39 volumio2-vk systemd[1]: Stopping ShairportSync AirTunes receiver... Aug 27 17:51:39 volumio2-vk systemd[1]: Starting ShairportSync AirTunes receiver... Aug 27 17:51:39 volumio2-vk systemd[1]: Started ShairportSync AirTunes receiver. Aug 27 17:51:39 volumio2-vk sudo[2692]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:39 volumio2-vk shairport-sync[2709]: Successful Startup Aug 27 17:51:39 volumio2-vk systemd[1]: Stopping ShairportSync AirTunes receiver... Aug 27 17:51:39 volumio2-vk systemd[1]: Starting ShairportSync AirTunes receiver... Aug 27 17:51:39 volumio2-vk systemd[1]: Started ShairportSync AirTunes receiver. Aug 27 17:51:39 volumio2-vk sudo[2696]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:39 volumio2-vk shairport-sync[2716]: Successful Startup Aug 27 17:51:39 volumio2-vk volumio[2575]: info: Upmpdcli Daemon Started Aug 27 17:51:39 volumio2-vk volumio[2575]: info: Shairport-Sync Started Aug 27 17:51:39 volumio2-vk volumio[2575]: Error adding Membership: Error: addMembership EINVAL Aug 27 17:51:39 volumio2-vk volumio[2575]: info: Shairport-Sync Started Aug 27 17:51:39 volumio2-vk volumio[2575]: Discovery: adding c4e8251b-c190-4f38-99be-64a9d9148f89 Aug 27 17:51:39 volumio2-vk volumio[2575]: info: mDNS: Found device Volumio2-vk Aug 27 17:51:39 volumio2-vk sudo[2723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:39 volumio2-vk sudo[2723]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:39 volumio2-vk sudo[2723]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:39 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioGetState Aug 27 17:51:39 volumio2-vk volumio[2575]: info: CoreStateMachine::getState Aug 27 17:51:39 volumio2-vk volumio[2575]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:39 volumio2-vk sudo[2734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:39 volumio2-vk sudo[2734]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:39 volumio2-vk sudo[2734]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:41 volumio2-vk volumio[2575]: info: Cannot mount NAS RiksStation2 at system boot, trial number 3 ,retrying in 5 seconds Aug 27 17:51:42 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 17:51:42 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioGetState Aug 27 17:51:42 volumio2-vk volumio[2575]: info: CoreStateMachine::getState Aug 27 17:51:42 volumio2-vk volumio[2575]: info: CorePlayQueue::getTrack 0 Aug 27 17:51:42 volumio2-vk sudo[2747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:42 volumio2-vk sudo[2747]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:42 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 17:51:42 volumio2-vk volumio[2575]: info: Listing playlists Aug 27 17:51:42 volumio2-vk volumio[2575]: info: CoreCommandRouter::volumioGetQueue Aug 27 17:51:42 volumio2-vk volumio[2575]: info: CoreStateMachine::getQueue Aug 27 17:51:42 volumio2-vk volumio[2575]: info: CorePlayQueue::getQueue Aug 27 17:51:42 volumio2-vk sudo[2751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 17:51:42 volumio2-vk sudo[2747]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:42 volumio2-vk sudo[2751]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 17:51:42 volumio2-vk sudo[2751]: pam_unix(sudo:session): session closed for user root Aug 27 17:51:43 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:51:43] [connect] Successful connection Aug 27 17:51:43 volumio2-vk volumio-remote-updater[595]: [2021-08-27 17:51:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1630086703 101 Aug 27 17:51:45 volumio2-vk volumio[2575]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 27 17:51:45 volumio2-vk volumio[2575]: info: CURURI: artists:// Aug 27 17:51:45 volumio2-vk volumio[2575]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:51:45 volumio2-vk volumio[2575]: TypeError: Cannot read property 'sendCommand' of undefined Aug 27 17:51:45 volumio2-vk volumio[2575]: at ControllerMpd.listArtists (/volumio/app/plugins/music_service/mpd/index.js:3254:17) Aug 27 17:51:45 volumio2-vk volumio[2575]: at ControllerMpd.handleBrowseUri (/volumio/app/plugins/music_service/mpd/index.js:2926:29) Aug 27 17:51:45 volumio2-vk volumio[2575]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1018:29) Aug 27 17:51:45 volumio2-vk volumio[2575]: at CoreMusicLibrary.executeBrowseSource (/volumio/app/musiclibrary.js:319:39) Aug 27 17:51:45 volumio2-vk volumio[2575]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:363:32) Aug 27 17:51:45 volumio2-vk volumio[2575]: at emitTwo (events.js:126:13) Aug 27 17:51:45 volumio2-vk volumio[2575]: at Socket.emit (events.js:214:7) Aug 27 17:51:45 volumio2-vk volumio[2575]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 27 17:51:45 volumio2-vk volumio[2575]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 27 17:51:45 volumio2-vk volumio[2575]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 27 17:51:45 volumio2-vk volumio[2575]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 17:51:47 volumio2-vk sudo[2776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-08-27 17:50 Aug 27 17:51:47 volumio2-vk sudo[2776]: 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="91bc32a3daa69e09170e613c40f8b5c279962098" VOLUMIO_FE_VERSION="9511ab07247d3b65ded550111191469fa3626bd6" VOLUMIO_BE_VERSION="78de34390ebc5df0b086e244ca969a4e0ddc40d1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jan 12 00:37:59 CET 2019" VOLUMIO_VERSION="2.526" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="fe6efee3fc492df251c9b38e25d0dec3"