-- Logs begin at Mon 2021-11-29 21:01:33 UTC. -- Nov 29 21:33:18 volumio-office volumio[1686]: at MPD._onData (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:344:10) Nov 29 21:33:18 volumio-office volumio[1686]: at emitOne (events.js:116:13) Nov 29 21:33:18 volumio-office volumio[1686]: at Socket.emit (events.js:211:7) Nov 29 21:33:18 volumio-office volumio[1686]: at addChunk (_stream_readable.js:263:12) Nov 29 21:33:18 volumio-office volumio[1686]: at readableAddChunk (_stream_readable.js:246:13) Nov 29 21:33:18 volumio-office volumio[1686]: at Socket.Readable.push (_stream_readable.js:208:10) Nov 29 21:33:18 volumio-office volumio[1686]: at TCP.onread (net.js:607:20) Nov 29 21:33:18 volumio-office volumio[1686]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 21:33:19 volumio-office sudo[1988]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Nov 29 21:33:19 volumio-office sudo[1988]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Nov 29 21:33:19 volumio-office sudo[1994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-11-29 21:32 Nov 29 21:33:19 volumio-office sudo[1994]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:19 volumio-office sudo[1994]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:19 volumio-office volumio-remote-updater[668]: [2021-11-29 21:33:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 29 21:33:19 volumio-office volumio-remote-updater[668]: [2021-11-29 21:33:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 29 21:33:19 volumio-office snapserver[1806]: Exception in ControlSession::reader(): read_until: End of file Nov 29 21:33:19 volumio-office systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 29 21:33:19 volumio-office systemd[1]: Unit volumio.service entered failed state. Nov 29 21:33:19 volumio-office systemd[1]: Starting dynamicswap service... Nov 29 21:33:19 volumio-office systemd[1]: Started dynamicswap service. Nov 29 21:33:19 volumio-office systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 29 21:33:19 volumio-office systemd[1]: Starting dynamicswap service... Nov 29 21:33:19 volumio-office systemd[1]: Started dynamicswap service. Nov 29 21:33:19 volumio-office systemd[1]: Stopping Volumio Backend Module... Nov 29 21:33:19 volumio-office systemd[1]: Starting Volumio Backend Module... Nov 29 21:33:19 volumio-office systemd[1]: Started Volumio Backend Module. Nov 29 21:33:21 volumio-office volumio[2015]: info: ------------------------------------------- Nov 29 21:33:21 volumio-office volumio[2015]: info: ----- Volumio2 ---- Nov 29 21:33:21 volumio-office volumio[2015]: info: ------------------------------------------- Nov 29 21:33:21 volumio-office volumio[2015]: info: ----- System startup ---- Nov 29 21:33:21 volumio-office volumio[2015]: info: ------------------------------------------- Nov 29 21:33:22 volumio-office volumio[2015]: info: MYVOLUMIO Environment detected Nov 29 21:33:22 volumio-office volumio[2015]: info: Plugin folders cleanup Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning into folder /volumio/app/plugins/ Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category audio_interface Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category miscellanea Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category music_service Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category plugins.json Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category system_controller Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category user_interface Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning into folder /data/plugins/ Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category miscellanea Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category music_service Nov 29 21:33:22 volumio-office volumio[2015]: info: Scanning category system_controller Nov 29 21:33:22 volumio-office volumio[2015]: info: Plugin folders cleanup completed Nov 29 21:33:22 volumio-office volumio[2015]: info: ------------------------------------------- Nov 29 21:33:22 volumio-office volumio[2015]: info: ----- Core plugins startup ---- Nov 29 21:33:22 volumio-office volumio[2015]: info: ------------------------------------------- Nov 29 21:33:22 volumio-office volumio[2015]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 21:33:22 volumio-office volumio[2015]: info: Adding plugin upnp to MyMusic Plugins Nov 29 21:33:22 volumio-office volumio[2015]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 21:33:22 volumio-office volumio[2015]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 21:33:22 volumio-office volumio[2015]: info: Loading plugins from folder /data/plugins/ Nov 29 21:33:22 volumio-office volumio[2015]: info: Loading plugin "system"... Nov 29 21:33:22 volumio-office volumio[2015]: info: Loading plugin "appearance"... Nov 29 21:33:24 volumio-office volumio-remote-updater[668]: [2021-11-29 21:33:24] [connect] Successful connection Nov 29 21:33:24 volumio-office volumio[2015]: info: Loading plugin "network"... Nov 29 21:33:24 volumio-office volumio[2015]: info: Refreshing Cached IP Addresses Nov 29 21:33:24 volumio-office sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 21:33:24 volumio-office volumio[2015]: info: Loading plugin "services"... Nov 29 21:33:24 volumio-office sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:24 volumio-office volumio[2015]: info: Loading plugin "alsa_controller"... Nov 29 21:33:24 volumio-office sudo[2040]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:24 volumio-office sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 21:33:24 volumio-office sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:24 volumio-office sudo[2044]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:24 volumio-office volumio[2015]: info: Loading plugin "wizard"... Nov 29 21:33:24 volumio-office volumio[2015]: info: Loading plugin "volumio_command_line_client"... Nov 29 21:33:25 volumio-office volumio[2015]: info: Loading plugin "upnp"... Nov 29 21:33:25 volumio-office volumio[2015]: info: [1638221605034] Starting Upmpd Daemon Nov 29 21:33:25 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 21:33:25 volumio-office volumio[2015]: info: Loading plugin "my_music"... Nov 29 21:33:25 volumio-office volumio[2015]: info: Loading plugin "mpd"... Nov 29 21:33:25 volumio-office volumio[2015]: info: Loading plugin "upnp_browser"... Nov 29 21:33:25 volumio-office volumio[2015]: info: Loading plugin "networkfs"... Nov 29 21:33:25 volumio-office volumio[2015]: info: Cannot mount NAS Tower_Music at system boot, trial number 1 ,retrying in 5 seconds Nov 29 21:33:25 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 21:33:25 volumio-office volumio[2015]: info: Loading plugin "alarm-clock"... Nov 29 21:33:26 volumio-office volumio[2015]: info: Loading plugin "airplay_emulation"... Nov 29 21:33:26 volumio-office volumio[2015]: info: Starting Shairport Sync Nov 29 21:33:26 volumio-office volumio[2015]: info: Loading plugin "last_100"... Nov 29 21:33:26 volumio-office volumio[2015]: info: Loading plugin "webradio"... Nov 29 21:33:26 volumio-office volumio[2015]: info: Loading plugin "i2s_dacs"... Nov 29 21:33:26 volumio-office volumio[2015]: info: I2S DAC not set, start Auto-detection Nov 29 21:33:26 volumio-office volumio[2015]: info: Loading plugin "volumiodiscovery"... Nov 29 21:33:26 volumio-office volumio[2015]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 21:33:26 volumio-office node[2015]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 21:33:26 volumio-office node[2015]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 21:33:26 volumio-office node[2015]: *** WARNING *** For more information see Nov 29 21:33:26 volumio-office node[2015]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 21:33:26 volumio-office node[2015]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 21:33:26 volumio-office node[2015]: *** WARNING *** For more information see Nov 29 21:33:26 volumio-office volumio[2015]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 21:33:26 volumio-office volumio[2015]: *** WARNING *** For more information see Nov 29 21:33:26 volumio-office volumio[2015]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 21:33:26 volumio-office volumio[2015]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 21:33:26 volumio-office volumio[2015]: *** WARNING *** For more information see Nov 29 21:33:26 volumio-office volumio[2015]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 21:33:26 volumio-office volumio[2015]: Discovery: StartAdv! undefined Nov 29 21:33:26 volumio-office volumio[2015]: Discovery: Started advertising... Volumio-office - undefined Nov 29 21:33:26 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 21:33:26 volumio-office volumio[2015]: info: Loading plugin "auto_play"... Nov 29 21:33:26 volumio-office volumio[2015]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Nov 29 21:33:26 volumio-office volumio[2015]: info: Loading plugin "soundcloud"... Nov 29 21:33:26 volumio-office volumio[2015]: info: Loading plugin "youtube2"... Nov 29 21:33:30 volumio-office volumio[2015]: info: Loading plugin "outputs"... Nov 29 21:33:30 volumio-office volumio[2015]: info: Loading plugin "albumart"... Nov 29 21:33:30 volumio-office volumio[2015]: info: Plugin example_plugin is not enabled Nov 29 21:33:30 volumio-office volumio[2015]: info: Loading plugin "inputs"... Nov 29 21:33:30 volumio-office volumio[2015]: info: Loading plugin "updater_comm"... Nov 29 21:33:30 volumio-office volumio[2015]: info: Plugin mpdemulation is not enabled Nov 29 21:33:30 volumio-office volumio[2015]: info: Loading plugin "rest_api"... Nov 29 21:33:30 volumio-office volumio[2015]: info: Loading plugin "websocket"... Nov 29 21:33:30 volumio-office volumio[2015]: info: Loading plugin "snapcast"... Nov 29 21:33:30 volumio-office volumio[2015]: Forking 3 albumart workers Nov 29 21:33:31 volumio-office volumio[2015]: info: SnapCast initiated Nov 29 21:33:31 volumio-office volumio[2015]: info: Loading plugin "volusonic"... Nov 29 21:33:31 volumio-office volumio[2015]: Starting albumart workers Nov 29 21:33:31 volumio-office volumio[2015]: Starting albumart workers Nov 29 21:33:31 volumio-office volumio[2015]: Starting albumart workers Nov 29 21:33:32 volumio-office volumio[2015]: info: Applying required configuration parameters for plugin volusonic Nov 29 21:33:32 volumio-office volumio[2015]: info: Loading plugin "backup_restore"... Nov 29 21:33:32 volumio-office volumio[2015]: info: ___________ START PLUGINS ___________ Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:32 volumio-office volumio[2015]: info: [1638221612752] CoreMusicLibrary::Adding element Media Servers Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:32 volumio-office volumio[2015]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 21:33:32 volumio-office volumio[2015]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:32 volumio-office volumio[2015]: info: [1638221612882] CoreMusicLibrary::Adding element Last_100 Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:32 volumio-office volumio[2015]: info: [1638221612886] CoreMusicLibrary::Adding element Webradio Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:32 volumio-office volumio[2015]: info: [1638221612909] CoreMusicLibrary::Adding element SoundCloud Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:32 volumio-office volumio[2015]: info: [youtube2-auth] Refreshing access token... Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:32 volumio-office volumio[2015]: info: [1638221612973] CoreMusicLibrary::Adding element YouTube2 Nov 29 21:33:32 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:32 volumio-office volumio[2015]: info: Starting SnapCast services... Nov 29 21:33:33 volumio-office sudo[2124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapserver Nov 29 21:33:33 volumio-office sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:33 volumio-office systemd[1]: Stopping Snapcast server... Nov 29 21:33:33 volumio-office snapserver[1806]: Received SIGTERM signal. Nov 29 21:33:33 volumio-office volumio[2015]: info: Loading i18n strings for locale en Nov 29 21:33:33 volumio-office volumio[2015]: Updating browse sources language Nov 29 21:33:33 volumio-office volumio[2015]: Cannot find translation for sourceMedia Servers Nov 29 21:33:33 volumio-office volumio[2015]: Cannot find translation for sourceSoundCloud Nov 29 21:33:33 volumio-office volumio[2015]: Cannot find translation for sourceYouTube2 Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:33 volumio-office volumio[2015]: info: [1638221613062] CoreMusicLibrary::Adding element Volusonic Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:33 volumio-office volumio[2015]: info: ------------------------------------------- Nov 29 21:33:33 volumio-office volumio[2015]: info: ----- MyVolumio plugins startup ---- Nov 29 21:33:33 volumio-office volumio[2015]: info: ------------------------------------------- Nov 29 21:33:33 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 29 21:33:33 volumio-office volumio[2015]: info: Loading i18n strings for locale en Nov 29 21:33:33 volumio-office volumio[2015]: Updating browse sources language Nov 29 21:33:33 volumio-office volumio[2015]: Cannot find translation for sourceMedia Servers Nov 29 21:33:33 volumio-office volumio[2015]: Cannot find translation for sourceSoundCloud Nov 29 21:33:33 volumio-office volumio[2015]: Cannot find translation for sourceYouTube2 Nov 29 21:33:33 volumio-office volumio[2015]: Cannot find translation for sourceVolusonic Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::initPlayerControls Nov 29 21:33:33 volumio-office snapserver[1806]: Exception in StreamSession::reader(): read_some: End of file Nov 29 21:33:33 volumio-office snapserver[1806]: Exception in StreamSession::reader(): read_some: End of file Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 29 21:33:33 volumio-office snapserver[1806]: daemon terminated. Nov 29 21:33:33 volumio-office systemd[1]: Starting Snapcast server... Nov 29 21:33:33 volumio-office snapserver[2132]: Settings file: "/var/lib/snapserver/server.json" Nov 29 21:33:33 volumio-office snapserver[2132]: pipe:///tmp/snapfifo?name=VOLUMIO-Office&mode=read&sampleformat=44100:16:2 Nov 29 21:33:33 volumio-office snapserver[2132]: 2021-11-29 21-33-33 [Notice] Settings file: "/var/lib/snapserver/server.json" Nov 29 21:33:33 volumio-office snapserver[2133]: daemon started Nov 29 21:33:33 volumio-office systemd[1]: Started Snapcast server. Nov 29 21:33:33 volumio-office sudo[2124]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:33 volumio-office snapclient[1875]: Exception in Controller::worker(): read_some: End of file Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 21:33:33 volumio-office volumio[2015]: info: BOOT COMPLETED Nov 29 21:33:33 volumio-office volumio[2015]: [Metrics] CommandRouter: 11s 523.32ms Nov 29 21:33:33 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 21:33:34 volumio-office snapserver[2133]: StreamServer::NewConnection: ::ffff:192.168.50.124 Nov 29 21:33:34 volumio-office snapclient[1875]: Connected to 127.0.0.1 Nov 29 21:33:34 volumio-office snapserver[2133]: StreamServer::NewConnection: ::ffff:127.0.0.1 Nov 29 21:33:37 volumio-office volumio-remote-updater[668]: [2021-11-29 21:33:37] [connect] Successful connection Nov 29 21:33:38 volumio-office volumio[2015]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 29 21:33:38 volumio-office volumio[2015]: info: CoreCommandRouter::Close All Modals sent Nov 29 21:33:38 volumio-office volumio[2015]: info: CoreCommandRouter::Close All Modals sent Nov 29 21:33:38 volumio-office volumio[2015]: Express server listening on port 3000 Nov 29 21:33:38 volumio-office volumio[2015]: [Metrics] WebUI: 17s 343.08ms Nov 29 21:33:38 volumio-office volumio[2015]: Volumio Calling Home Nov 29 21:33:38 volumio-office snapserver[2133]: ControlServer::NewConnection: ::ffff:127.0.0.1 Nov 29 21:33:38 volumio-office volumio[2015]: info: CoreStateMachine::resetVolumioState Nov 29 21:33:38 volumio-office volumio[2015]: info: CoreStateMachine::getcurrentVolume Nov 29 21:33:38 volumio-office volumio[2015]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 21:33:38 volumio-office volumio[2015]: info: Cannot mount NAS Tower_Music at system boot, trial number 2 ,retrying in 5 seconds Nov 29 21:33:38 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 21:33:38 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:38 volumio-office sudo[2163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 21:33:38 volumio-office sudo[2163]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:38 volumio-office sudo[2163]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:38 volumio-office sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 21:33:38 volumio-office sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:38 volumio-office sudo[2167]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:38 volumio-office volumio[2015]: info: Setting Device type: Raspberry PI Nov 29 21:33:38 volumio-office volumio[2015]: info: snapserver started Nov 29 21:33:38 volumio-office sudo[2182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapclient Nov 29 21:33:38 volumio-office sudo[2182]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:38 volumio-office volumio[2015]: info: MPD running with PID739 Nov 29 21:33:38 volumio-office volumio[2015]: ,establishing connection Nov 29 21:33:38 volumio-office sudo[2186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 29 21:33:38 volumio-office systemd[1]: Stopping Snapcast client... Nov 29 21:33:38 volumio-office sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:38 volumio-office snapclient[1875]: Received SIGTERM signal. Nov 29 21:33:38 volumio-office sudo[2186]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:39 volumio-office snapserver[2133]: Exception in StreamSession::reader(): read_some: End of file Nov 29 21:33:39 volumio-office snapclient[1875]: daemon terminated. Nov 29 21:33:39 volumio-office systemd[1]: Starting Snapcast client... Nov 29 21:33:39 volumio-office snapclient[2199]: daemon started Nov 29 21:33:39 volumio-office systemd[1]: Started Snapcast client. Nov 29 21:33:39 volumio-office sudo[2182]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:39 volumio-office volumio-remote-updater[668]: [2021-11-29 21:33:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1638221617 101 Nov 29 21:33:39 volumio-office volumio[2015]: info: VolumeController:: Volume=86 Mute =false Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreStateMachine::pushState Nov 29 21:33:39 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::volumioPushState Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreStateMachine::updateTrackBlock Nov 29 21:33:39 volumio-office volumio[2015]: info: CorePlayQueue::getTrackBlock Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 21:33:39 volumio-office snapclient[2199]: Connected to 127.0.0.1 Nov 29 21:33:39 volumio-office snapserver[2133]: StreamServer::NewConnection: ::ffff:127.0.0.1 Nov 29 21:33:39 volumio-office volumio[2015]: info: Volumio called home Nov 29 21:33:39 volumio-office volumio[2015]: info: snapclient started Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 21:33:39 volumio-office volumio[2015]: info: Reloading queue from file Nov 29 21:33:39 volumio-office volumio[2015]: error: updateQueue error: null Nov 29 21:33:39 volumio-office volumio[2015]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreStateMachine::setRepeat null single undefined Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreStateMachine::pushState Nov 29 21:33:39 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::volumioPushState Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreStateMachine::setRandom false Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreStateMachine::pushState Nov 29 21:33:39 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::volumioPushState Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 21:33:39 volumio-office volumio[2015]: info: [youtube2-auth] Access token refreshed. Refreshing again in 3599s Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 21:33:39 volumio-office volumio[2015]: info: VolumeController:: Volume=86 Mute =false Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreStateMachine::pushState Nov 29 21:33:39 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::volumioPushState Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::volumioGetState Nov 29 21:33:39 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::volumioGetState Nov 29 21:33:39 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:39 volumio-office volumio[2015]: info: Starting Shairport Sync Nov 29 21:33:39 volumio-office volumio[2015]: info: Starting Shairport Sync Nov 29 21:33:39 volumio-office sudo[2220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 21:33:39 volumio-office sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:39 volumio-office sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 21:33:39 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 21:33:39 volumio-office sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:39 volumio-office volumio[2015]: Discovery: adding 6c70e27d-d546-49a0-8ce4-00bf0fdb7925 Nov 29 21:33:39 volumio-office volumio[2015]: info: mDNS: Found device Volumio-server Nov 29 21:33:39 volumio-office systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 29 21:33:39 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 21:33:39 volumio-office sudo[2220]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:39 volumio-office sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 29 21:33:39 volumio-office sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:39 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 21:33:39 volumio-office systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 29 21:33:39 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 21:33:39 volumio-office sudo[2223]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:39 volumio-office volumio[2015]: info: CoreCommandRouter::volumioGetState Nov 29 21:33:39 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:39 volumio-office systemd[1]: Started UPnP Renderer front-end to MPD. Nov 29 21:33:39 volumio-office sudo[2231]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:40 volumio-office volumio[2015]: Discovery: adding af124f44-6d9b-43d3-a6ab-97346f0f5be5 Nov 29 21:33:40 volumio-office volumio[2015]: info: mDNS: Found device Volumio-mb Nov 29 21:33:40 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 21:33:40 volumio-office volumio[2015]: info: Listing playlists Nov 29 21:33:40 volumio-office volumio[2015]: info: Shairport-Sync Started Nov 29 21:33:40 volumio-office volumio[2015]: Error adding Membership: Error: addMembership EINVAL Nov 29 21:33:40 volumio-office volumio[2015]: info: Shairport-Sync Started Nov 29 21:33:40 volumio-office volumio[2015]: info: Upmpdcli Daemon Started Nov 29 21:33:40 volumio-office volumio[2015]: Discovery: adding 8ef1850c-5bdb-456f-b784-6f4ab5ebcb6a Nov 29 21:33:40 volumio-office volumio[2015]: info: mDNS: Found device Volumio-office Nov 29 21:33:40 volumio-office volumio[2015]: info: CoreCommandRouter::volumioGetState Nov 29 21:33:40 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:40 volumio-office volumio[2015]: info: CoreCommandRouter::volumioGetState Nov 29 21:33:40 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:40 volumio-office volumio[2015]: Discovery: this is already registered, 8ef1850c-5bdb-456f-b784-6f4ab5ebcb6a Nov 29 21:33:40 volumio-office volumio[2015]: info: mDNS: Found device Volumio-office Nov 29 21:33:40 volumio-office volumio[2015]: info: CoreCommandRouter::volumioGetState Nov 29 21:33:40 volumio-office volumio[2015]: info: CorePlayQueue::getTrack 0 Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 29 21:33:41 volumio-office volumio[2015]: info: Adding plugin bluetooth to MyMusic Plugins Nov 29 21:33:41 volumio-office volumio[2015]: info: Adding plugin cd_controller to MyMusic Plugins Nov 29 21:33:41 volumio-office volumio[2015]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 29 21:33:41 volumio-office volumio[2015]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 29 21:33:41 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 29 21:33:42 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 29 21:33:42 volumio-office volumio[2015]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 29 21:33:42 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 29 21:33:42 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 21:33:42 volumio-office volumio[2015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 21:33:42 volumio-office volumio[2015]: info: Starting MyVolumio Remote Streaming Endpoints Nov 29 21:33:42 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 29 21:33:42 volumio-office volumio[2015]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 29 21:33:42 volumio-office volumio[2015]: info: Streaming services startup Nov 29 21:33:42 volumio-office volumio[2015]: info: Starting Streaming Daemon Nov 29 21:33:42 volumio-office sudo[2267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 29 21:33:42 volumio-office sudo[2267]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:42 volumio-office systemd[1]: Stopping Volumio Streaming Daemon... Nov 29 21:33:42 volumio-office systemd[1]: Starting Volumio Streaming Daemon... Nov 29 21:33:42 volumio-office systemd[1]: Started Volumio Streaming Daemon. Nov 29 21:33:42 volumio-office sudo[2267]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:42 volumio-office volumio-streaming-daemon[2276]: ############################ Nov 29 21:33:42 volumio-office volumio[2015]: info: MyVolumio not started Nov 29 21:33:42 volumio-office volumio[2015]: info: Initializing device activation check Nov 29 21:33:42 volumio-office volumio-streaming-daemon[2276]: # Volumio Streaming Daemon # Nov 29 21:33:42 volumio-office volumio-streaming-daemon[2276]: # Running on port 7777 # Nov 29 21:33:42 volumio-office volumio-streaming-daemon[2276]: ############################ Nov 29 21:33:42 volumio-office volumio-streaming-daemon[2276]: Environment: production Nov 29 21:33:42 volumio-office volumio[2015]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 29 21:33:43 volumio-office volumio[2015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 21:33:43 volumio-office volumio[2015]: Error: Non okay return status: "Title: Alright OK" Nov 29 21:33:43 volumio-office volumio[2015]: at MPD._checkReturn (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:353:10) Nov 29 21:33:43 volumio-office volumio[2015]: at MPD. (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:188:18) Nov 29 21:33:43 volumio-office volumio[2015]: at MPD._handleResponse (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:450:3) Nov 29 21:33:43 volumio-office volumio[2015]: at MPD._onData (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:344:10) Nov 29 21:33:43 volumio-office volumio[2015]: at emitOne (events.js:116:13) Nov 29 21:33:43 volumio-office volumio[2015]: at Socket.emit (events.js:211:7) Nov 29 21:33:43 volumio-office volumio[2015]: at addChunk (_stream_readable.js:263:12) Nov 29 21:33:43 volumio-office volumio[2015]: at readableAddChunk (_stream_readable.js:246:13) Nov 29 21:33:43 volumio-office volumio[2015]: at Socket.Readable.push (_stream_readable.js:208:10) Nov 29 21:33:43 volumio-office volumio[2015]: at TCP.onread (net.js:607:20) Nov 29 21:33:43 volumio-office volumio[2015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 21:33:44 volumio-office sudo[2293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-11-29 21:32 Nov 29 21:33:44 volumio-office sudo[2293]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:44 volumio-office sudo[2293]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:44 volumio-office volumio-remote-updater[668]: [2021-11-29 21:33:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 29 21:33:44 volumio-office volumio-remote-updater[668]: [2021-11-29 21:33:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 29 21:33:44 volumio-office systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 29 21:33:44 volumio-office snapserver[2133]: Exception in ControlSession::reader(): read_until: End of file Nov 29 21:33:44 volumio-office systemd[1]: Unit volumio.service entered failed state. Nov 29 21:33:44 volumio-office systemd[1]: Starting dynamicswap service... Nov 29 21:33:44 volumio-office systemd[1]: Started dynamicswap service. Nov 29 21:33:44 volumio-office systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 29 21:33:44 volumio-office systemd[1]: Starting dynamicswap service... Nov 29 21:33:44 volumio-office systemd[1]: Started dynamicswap service. Nov 29 21:33:44 volumio-office systemd[1]: Stopping Volumio Backend Module... Nov 29 21:33:44 volumio-office systemd[1]: Starting Volumio Backend Module... Nov 29 21:33:44 volumio-office systemd[1]: Started Volumio Backend Module. Nov 29 21:33:46 volumio-office volumio[2314]: info: ------------------------------------------- Nov 29 21:33:46 volumio-office volumio[2314]: info: ----- Volumio2 ---- Nov 29 21:33:46 volumio-office volumio[2314]: info: ------------------------------------------- Nov 29 21:33:46 volumio-office volumio[2314]: info: ----- System startup ---- Nov 29 21:33:46 volumio-office volumio[2314]: info: ------------------------------------------- Nov 29 21:33:47 volumio-office volumio[2314]: info: MYVOLUMIO Environment detected Nov 29 21:33:47 volumio-office volumio[2314]: info: Plugin folders cleanup Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning into folder /volumio/app/plugins/ Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category audio_interface Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category miscellanea Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category music_service Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category plugins.json Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category system_controller Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category user_interface Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning into folder /data/plugins/ Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category miscellanea Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category music_service Nov 29 21:33:47 volumio-office volumio[2314]: info: Scanning category system_controller Nov 29 21:33:47 volumio-office volumio[2314]: info: Plugin folders cleanup completed Nov 29 21:33:47 volumio-office volumio[2314]: info: ------------------------------------------- Nov 29 21:33:47 volumio-office volumio[2314]: info: ----- Core plugins startup ---- Nov 29 21:33:47 volumio-office volumio[2314]: info: ------------------------------------------- Nov 29 21:33:47 volumio-office volumio[2314]: info: Loading plugins from folder /volumio/app/plugins/ Nov 29 21:33:47 volumio-office volumio[2314]: info: Adding plugin upnp to MyMusic Plugins Nov 29 21:33:47 volumio-office volumio[2314]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 29 21:33:47 volumio-office volumio[2314]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 29 21:33:47 volumio-office volumio[2314]: info: Loading plugins from folder /data/plugins/ Nov 29 21:33:47 volumio-office volumio[2314]: info: Loading plugin "system"... Nov 29 21:33:47 volumio-office volumio[2314]: info: Loading plugin "appearance"... Nov 29 21:33:49 volumio-office volumio-remote-updater[668]: [2021-11-29 21:33:49] [connect] Successful connection Nov 29 21:33:49 volumio-office volumio[2314]: info: Loading plugin "network"... Nov 29 21:33:49 volumio-office volumio[2314]: info: Refreshing Cached IP Addresses Nov 29 21:33:49 volumio-office sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 21:33:49 volumio-office volumio[2314]: info: Loading plugin "services"... Nov 29 21:33:49 volumio-office sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:49 volumio-office volumio[2314]: info: Loading plugin "alsa_controller"... Nov 29 21:33:49 volumio-office sudo[2339]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:49 volumio-office sudo[2343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 21:33:49 volumio-office sudo[2343]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:49 volumio-office sudo[2343]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:49 volumio-office volumio[2314]: info: Loading plugin "wizard"... Nov 29 21:33:49 volumio-office volumio[2314]: info: Loading plugin "volumio_command_line_client"... Nov 29 21:33:49 volumio-office volumio[2314]: info: Loading plugin "upnp"... Nov 29 21:33:49 volumio-office volumio[2314]: info: [1638221629819] Starting Upmpd Daemon Nov 29 21:33:49 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 21:33:49 volumio-office volumio[2314]: info: Loading plugin "my_music"... Nov 29 21:33:49 volumio-office volumio[2314]: info: Loading plugin "mpd"... Nov 29 21:33:50 volumio-office volumio[2314]: info: Loading plugin "upnp_browser"... Nov 29 21:33:50 volumio-office volumio[2314]: info: Loading plugin "networkfs"... Nov 29 21:33:50 volumio-office volumio[2314]: info: Cannot mount NAS Tower_Music at system boot, trial number 1 ,retrying in 5 seconds Nov 29 21:33:50 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 21:33:50 volumio-office volumio[2314]: info: Loading plugin "alarm-clock"... Nov 29 21:33:50 volumio-office volumio[2314]: info: Loading plugin "airplay_emulation"... Nov 29 21:33:51 volumio-office volumio[2314]: info: Starting Shairport Sync Nov 29 21:33:51 volumio-office volumio[2314]: info: Loading plugin "last_100"... Nov 29 21:33:51 volumio-office volumio[2314]: info: Loading plugin "webradio"... Nov 29 21:33:51 volumio-office volumio[2314]: info: Loading plugin "i2s_dacs"... Nov 29 21:33:51 volumio-office volumio[2314]: info: I2S DAC not set, start Auto-detection Nov 29 21:33:51 volumio-office volumio[2314]: info: Loading plugin "volumiodiscovery"... Nov 29 21:33:51 volumio-office volumio[2314]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 21:33:51 volumio-office node[2314]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 29 21:33:51 volumio-office node[2314]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 21:33:51 volumio-office node[2314]: *** WARNING *** For more information see Nov 29 21:33:51 volumio-office node[2314]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 21:33:51 volumio-office node[2314]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 21:33:51 volumio-office node[2314]: *** WARNING *** For more information see Nov 29 21:33:51 volumio-office volumio[2314]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 21:33:51 volumio-office volumio[2314]: *** WARNING *** For more information see Nov 29 21:33:51 volumio-office volumio[2314]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 29 21:33:51 volumio-office volumio[2314]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 29 21:33:51 volumio-office volumio[2314]: *** WARNING *** For more information see Nov 29 21:33:51 volumio-office volumio[2314]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 29 21:33:51 volumio-office volumio[2314]: Discovery: StartAdv! undefined Nov 29 21:33:51 volumio-office volumio[2314]: Discovery: Started advertising... Volumio-office - undefined Nov 29 21:33:51 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 29 21:33:51 volumio-office volumio[2314]: info: Loading plugin "auto_play"... Nov 29 21:33:51 volumio-office volumio[2314]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Nov 29 21:33:51 volumio-office volumio[2314]: info: Loading plugin "soundcloud"... Nov 29 21:33:51 volumio-office volumio[2314]: info: Loading plugin "youtube2"... Nov 29 21:33:55 volumio-office volumio[2314]: info: Loading plugin "outputs"... Nov 29 21:33:55 volumio-office volumio[2314]: info: Loading plugin "albumart"... Nov 29 21:33:55 volumio-office volumio[2314]: info: Plugin example_plugin is not enabled Nov 29 21:33:55 volumio-office volumio[2314]: info: Loading plugin "inputs"... Nov 29 21:33:55 volumio-office volumio[2314]: info: Loading plugin "updater_comm"... Nov 29 21:33:55 volumio-office volumio[2314]: info: Plugin mpdemulation is not enabled Nov 29 21:33:55 volumio-office volumio[2314]: info: Loading plugin "rest_api"... Nov 29 21:33:55 volumio-office volumio[2314]: info: Loading plugin "websocket"... Nov 29 21:33:55 volumio-office volumio[2314]: info: Loading plugin "snapcast"... Nov 29 21:33:55 volumio-office volumio[2314]: Forking 3 albumart workers Nov 29 21:33:56 volumio-office volumio[2314]: info: SnapCast initiated Nov 29 21:33:56 volumio-office volumio[2314]: info: Loading plugin "volusonic"... Nov 29 21:33:56 volumio-office volumio[2314]: Starting albumart workers Nov 29 21:33:56 volumio-office volumio[2314]: Starting albumart workers Nov 29 21:33:56 volumio-office volumio[2314]: Starting albumart workers Nov 29 21:33:57 volumio-office volumio[2314]: info: Applying required configuration parameters for plugin volusonic Nov 29 21:33:57 volumio-office volumio[2314]: info: Loading plugin "backup_restore"... Nov 29 21:33:57 volumio-office volumio[2314]: info: ___________ START PLUGINS ___________ Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:57 volumio-office volumio[2314]: info: [1638221637525] CoreMusicLibrary::Adding element Media Servers Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:57 volumio-office volumio[2314]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 21:33:57 volumio-office volumio[2314]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:57 volumio-office volumio[2314]: info: [1638221637654] CoreMusicLibrary::Adding element Last_100 Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:57 volumio-office volumio[2314]: info: [1638221637659] CoreMusicLibrary::Adding element Webradio Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:57 volumio-office volumio[2314]: info: [1638221637680] CoreMusicLibrary::Adding element SoundCloud Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:57 volumio-office volumio[2314]: info: [youtube2-auth] Refreshing access token... Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:57 volumio-office volumio[2314]: info: [1638221637743] CoreMusicLibrary::Adding element YouTube2 Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:57 volumio-office volumio[2314]: info: Starting SnapCast services... Nov 29 21:33:57 volumio-office sudo[2440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapserver Nov 29 21:33:57 volumio-office sudo[2440]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 21:33:57 volumio-office systemd[1]: Stopping Snapcast server... Nov 29 21:33:57 volumio-office snapserver[2133]: Received SIGTERM signal. Nov 29 21:33:57 volumio-office volumio[2314]: info: Loading i18n strings for locale en Nov 29 21:33:57 volumio-office snapserver[2133]: Exception in StreamSession::reader(): read_some: End of file Nov 29 21:33:57 volumio-office snapserver[2133]: Exception in StreamSession::reader(): read_some: End of file Nov 29 21:33:57 volumio-office volumio[2314]: Updating browse sources language Nov 29 21:33:57 volumio-office volumio[2314]: Cannot find translation for sourceMedia Servers Nov 29 21:33:57 volumio-office volumio[2314]: Cannot find translation for sourceSoundCloud Nov 29 21:33:57 volumio-office volumio[2314]: Cannot find translation for sourceYouTube2 Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 21:33:57 volumio-office volumio[2314]: info: [1638221637839] CoreMusicLibrary::Adding element Volusonic Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:57 volumio-office volumio[2314]: info: ------------------------------------------- Nov 29 21:33:57 volumio-office volumio[2314]: info: ----- MyVolumio plugins startup ---- Nov 29 21:33:57 volumio-office volumio[2314]: info: ------------------------------------------- Nov 29 21:33:57 volumio-office volumio[2314]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 29 21:33:57 volumio-office volumio[2314]: info: Loading i18n strings for locale en Nov 29 21:33:57 volumio-office volumio[2314]: Updating browse sources language Nov 29 21:33:57 volumio-office volumio[2314]: Cannot find translation for sourceMedia Servers Nov 29 21:33:57 volumio-office volumio[2314]: Cannot find translation for sourceSoundCloud Nov 29 21:33:57 volumio-office volumio[2314]: Cannot find translation for sourceYouTube2 Nov 29 21:33:57 volumio-office volumio[2314]: Cannot find translation for sourceVolusonic Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::initPlayerControls Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 29 21:33:57 volumio-office snapclient[2199]: Exception in Controller::worker(): read_some: End of file Nov 29 21:33:57 volumio-office snapserver[2133]: daemon terminated. Nov 29 21:33:57 volumio-office systemd[1]: Starting Snapcast server... Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 21:33:57 volumio-office snapserver[2448]: Settings file: "/var/lib/snapserver/server.json" Nov 29 21:33:57 volumio-office snapserver[2448]: pipe:///tmp/snapfifo?name=VOLUMIO-Office&mode=read&sampleformat=44100:16:2 Nov 29 21:33:57 volumio-office snapserver[2448]: 2021-11-29 21-33-57 [Notice] Settings file: "/var/lib/snapserver/server.json" Nov 29 21:33:57 volumio-office snapserver[2449]: daemon started Nov 29 21:33:57 volumio-office systemd[1]: Started Snapcast server. Nov 29 21:33:57 volumio-office sudo[2440]: pam_unix(sudo:session): session closed for user root Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 29 21:33:57 volumio-office volumio[2314]: info: BOOT COMPLETED Nov 29 21:33:57 volumio-office volumio[2314]: [Metrics] CommandRouter: 11s 499.76ms Nov 29 21:33:57 volumio-office volumio[2314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam