Apr 15 10:31:29 volumiostudy volumio[4791]: info: ------------------------------------------- Apr 15 10:31:29 volumiostudy volumio[4791]: info: ----- Volumio2 ---- Apr 15 10:31:29 volumiostudy volumio[4791]: info: ------------------------------------------- Apr 15 10:31:29 volumiostudy volumio[4791]: info: ----- System startup ---- Apr 15 10:31:29 volumiostudy volumio[4791]: info: ------------------------------------------- Apr 15 10:31:29 volumiostudy ntpd[4879]: Soliciting pool server 195.219.205.9 Apr 15 10:31:29 volumiostudy ntpd[4879]: Soliciting pool server 139.162.219.252 Apr 15 10:31:30 volumiostudy volumio[4791]: info: MYVOLUMIO Environment detected Apr 15 10:31:30 volumiostudy volumio[4791]: info: Plugin folders cleanup Apr 15 10:31:30 volumiostudy volumio[4791]: info: Scanning into folder /volumio/app/plugins/ Apr 15 10:31:30 volumiostudy volumio[4791]: info: Scanning category audio_interface Apr 15 10:31:30 volumiostudy volumio[4791]: info: Scanning category miscellanea Apr 15 10:31:30 volumiostudy volumio[4791]: info: Scanning category music_service Apr 15 10:31:30 volumiostudy volumio[4791]: info: Scanning category plugins.json Apr 15 10:31:30 volumiostudy volumio[4791]: info: Scanning category system_controller Apr 15 10:31:30 volumiostudy volumio[4791]: info: Scanning category user_interface Apr 15 10:31:30 volumiostudy volumio[4791]: info: Scanning into folder /data/plugins/ Apr 15 10:31:30 volumiostudy volumio[4791]: info: Plugin folders cleanup completed Apr 15 10:31:30 volumiostudy volumio[4791]: info: ------------------------------------------- Apr 15 10:31:30 volumiostudy volumio[4791]: info: ----- Core plugins startup ---- Apr 15 10:31:30 volumiostudy volumio[4791]: info: ------------------------------------------- Apr 15 10:31:30 volumiostudy volumio[4791]: info: Loading plugins from folder /volumio/app/plugins/ Apr 15 10:31:30 volumiostudy volumio[4791]: info: Adding plugin upnp to MyMusic Plugins Apr 15 10:31:30 volumiostudy volumio[4791]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 15 10:31:30 volumiostudy volumio[4791]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 15 10:31:30 volumiostudy volumio[4791]: info: Loading plugins from folder /data/plugins/ Apr 15 10:31:30 volumiostudy volumio[4791]: info: Loading plugin "system"... Apr 15 10:31:30 volumiostudy volumio[4791]: info: Loading plugin "appearance"... Apr 15 10:31:30 volumiostudy ntpd[4879]: Soliciting pool server 188.114.116.1 Apr 15 10:31:30 volumiostudy ntpd[4879]: Soliciting pool server 162.159.200.1 Apr 15 10:31:31 volumiostudy ntpd[4879]: Soliciting pool server 194.0.5.123 Apr 15 10:31:31 volumiostudy volumio[4791]: info: Loading plugin "network"... Apr 15 10:31:31 volumiostudy volumio[4791]: info: Refreshing Cached IP Addresses Apr 15 10:31:31 volumiostudy sudo[4930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 10:31:31 volumiostudy sudo[4930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:31 volumiostudy volumio[4791]: info: Loading plugin "services"... Apr 15 10:31:31 volumiostudy sudo[4930]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:31 volumiostudy volumio[4791]: info: Loading plugin "alsa_controller"... Apr 15 10:31:31 volumiostudy sudo[4932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 10:31:31 volumiostudy sudo[4932]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:31 volumiostudy sudo[4932]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:31 volumiostudy volumio[4791]: info: Loading plugin "wizard"... Apr 15 10:31:31 volumiostudy volumio[4791]: info: Loading plugin "volumio_command_line_client"... Apr 15 10:31:31 volumiostudy volumio[4791]: info: Loading plugin "upnp"... Apr 15 10:31:31 volumiostudy volumio[4791]: info: [1618482691943] Starting Upmpd Daemon Apr 15 10:31:31 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 10:31:31 volumiostudy volumio[4791]: info: Loading plugin "my_music"... Apr 15 10:31:31 volumiostudy volumio[4791]: info: Loading plugin "mpd"... Apr 15 10:31:31 volumiostudy ntpd[4879]: Soliciting pool server 212.71.255.35 Apr 15 10:31:31 volumiostudy ntpd[4879]: Soliciting pool server 178.79.155.116 Apr 15 10:31:31 volumiostudy ntpd[4879]: Soliciting pool server 85.199.214.99 Apr 15 10:31:32 volumiostudy ntpd[4879]: Soliciting pool server 217.114.59.3 Apr 15 10:31:32 volumiostudy volumio[4791]: info: Creating MPD Configuration file Apr 15 10:31:32 volumiostudy sudo[4942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 10:31:32 volumiostudy volumio[4791]: info: Loading plugin "upnp_browser"... Apr 15 10:31:32 volumiostudy sudo[4942]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:32 volumiostudy sudo[4942]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:32 volumiostudy sudo[4944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 10:31:32 volumiostudy sudo[4944]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:32 volumiostudy systemd[1]: Stopping Music Player Daemon... Apr 15 10:31:32 volumiostudy systemd[1]: mpd.service: Succeeded. Apr 15 10:31:32 volumiostudy systemd[1]: Stopped Music Player Daemon. Apr 15 10:31:32 volumiostudy systemd[1]: Starting Music Player Daemon... Apr 15 10:31:32 volumiostudy volumio[4791]: info: Loading plugin "networkfs"... Apr 15 10:31:32 volumiostudy ntpd[4879]: Soliciting pool server 185.53.93.157 Apr 15 10:31:32 volumiostudy ntpd[4879]: Soliciting pool server 2a00:1f98:1001::123 Apr 15 10:31:32 volumiostudy ntpd[4879]: Soliciting pool server 162.159.200.123 Apr 15 10:31:32 volumiostudy volumio[4791]: info: Starting Udev Watcher for removable devices Apr 15 10:31:32 volumiostudy sudo[4964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.2.210:/volume1/Music /mnt/NAS/TheSutherlands Apr 15 10:31:32 volumiostudy sudo[4964]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:33 volumiostudy kernel: FS-Cache: Loaded Apr 15 10:31:33 volumiostudy kernel: FS-Cache: Netfs 'nfs' registered for caching Apr 15 10:31:33 volumiostudy kernel: NFS: Registering the id_resolver key type Apr 15 10:31:33 volumiostudy kernel: Key type id_resolver registered Apr 15 10:31:33 volumiostudy kernel: Key type id_legacy registered Apr 15 10:31:33 volumiostudy systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Apr 15 10:31:33 volumiostudy systemd[1]: Reached target Host and Network Name Lookups. Apr 15 10:31:33 volumiostudy systemd[1]: Starting Preprocess NFS configuration... Apr 15 10:31:33 volumiostudy systemd[1]: nfs-config.service: Succeeded. Apr 15 10:31:33 volumiostudy systemd[1]: Started Preprocess NFS configuration. Apr 15 10:31:33 volumiostudy systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Apr 15 10:31:33 volumiostudy systemd[1]: Starting Notify NFS peers of a restart... Apr 15 10:31:33 volumiostudy sm-notify[5008]: Version 1.3.3 starting Apr 15 10:31:33 volumiostudy systemd[1]: rpc-statd-notify.service: Succeeded. Apr 15 10:31:33 volumiostudy systemd[1]: Started Notify NFS peers of a restart. Apr 15 10:31:33 volumiostudy rpc.statd[5043]: Version 1.3.3 starting Apr 15 10:31:33 volumiostudy rpc.statd[5043]: Flags: TI-RPC Apr 15 10:31:33 volumiostudy volumio[4791]: info: Ignoring mount for partition: boot Apr 15 10:31:33 volumiostudy volumio[4791]: info: Ignoring mount for partition: volumio Apr 15 10:31:33 volumiostudy volumio[4791]: info: Ignoring mount for partition: volumio_data Apr 15 10:31:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "alarm-clock"... Apr 15 10:31:33 volumiostudy systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Apr 15 10:31:33 volumiostudy sudo[4964]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "airplay_emulation"... Apr 15 10:31:33 volumiostudy volumio[4791]: info: Starting Shairport Sync Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "last_100"... Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "webradio"... Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "i2s_dacs"... Apr 15 10:31:33 volumiostudy volumio[4791]: info: I2S DAC not set, start Auto-detection Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "volumiodiscovery"... Apr 15 10:31:33 volumiostudy volumio[4791]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 15 10:31:33 volumiostudy volumio[4791]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 10:31:33 volumiostudy node[4791]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 15 10:31:33 volumiostudy volumio[4791]: *** WARNING *** For more information see Apr 15 10:31:33 volumiostudy volumio[4791]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 15 10:31:33 volumiostudy volumio[4791]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 10:31:33 volumiostudy volumio[4791]: *** WARNING *** For more information see Apr 15 10:31:33 volumiostudy node[4791]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 10:31:33 volumiostudy node[4791]: *** WARNING *** For more information see Apr 15 10:31:33 volumiostudy node[4791]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 15 10:31:33 volumiostudy node[4791]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 15 10:31:33 volumiostudy node[4791]: *** WARNING *** For more information see Apr 15 10:31:33 volumiostudy volumio[4791]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 15 10:31:33 volumiostudy volumio[4791]: info: Discovery: Started advertising with name: VolumioStudy Apr 15 10:31:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "outputs"... Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "albumart"... Apr 15 10:31:33 volumiostudy volumio[4791]: info: Plugin example_plugin is not enabled Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "inputs"... Apr 15 10:31:33 volumiostudy volumio[4791]: info: Loading plugin "updater_comm"... Apr 15 10:31:33 volumiostudy ntpd[4879]: Soliciting pool server 80.4.151.252 Apr 15 10:31:33 volumiostudy mpd[4961]: Apr 15 10:31 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 15 10:31:33 volumiostudy systemd[1]: Started Music Player Daemon. Apr 15 10:31:33 volumiostudy sudo[4944]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:34 volumiostudy volumio[4791]: info: Plugin mpdemulation is not enabled Apr 15 10:31:34 volumiostudy volumio[4791]: info: Loading plugin "rest_api"... Apr 15 10:31:34 volumiostudy volumio[4791]: info: Loading plugin "websocket"... Apr 15 10:31:34 volumiostudy volumio[4791]: info: Loading i18n strings for locale en Apr 15 10:31:34 volumiostudy volumio[4791]: Updating browse sources language Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::initPlayerControls Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: Express server listening on port 3000 Apr 15 10:31:34 volumiostudy volumio[4791]: [Metrics] WebUI: 5s 16.18ms Apr 15 10:31:34 volumiostudy volumio[4791]: Forking 3 albumart workers Apr 15 10:31:34 volumiostudy volumio[4791]: Volumio Calling Home Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreStateMachine::resetVolumioState Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreStateMachine::getcurrentVolume Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 10:31:34 volumiostudy volumio[4791]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 15 10:31:34 volumiostudy volumio[4791]: info: Completed loading Core Plugins Apr 15 10:31:34 volumiostudy volumio[4791]: info: Preparing to generate the ALSA configuration file Apr 15 10:31:34 volumiostudy volumio[4791]: info: Cannot read play queue from file Apr 15 10:31:34 volumiostudy volumio[4791]: info: MPD Permissions set Apr 15 10:31:34 volumiostudy sudo[5101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 15 10:31:34 volumiostudy sudo[5101]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:34 volumiostudy sudo[5101]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:34 volumiostudy volumio[4791]: info: MPD running with PID4961 Apr 15 10:31:34 volumiostudy volumio[4791]: ,establishing connection Apr 15 10:31:34 volumiostudy volumio[4791]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Apr 15 10:31:34 volumiostudy volumio[4791]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Apr 15 10:31:34 volumiostudy volumio[4791]: info: Volumio called home Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:34 volumiostudy volumio[4791]: error: updateQueue error: null Apr 15 10:31:34 volumiostudy volumio[4791]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 15 10:31:34 volumiostudy volumio[4791]: info: VolumeController:: Volume=32 Mute =false Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreStateMachine::pushState Apr 15 10:31:34 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioPushState Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreStateMachine::updateTrackBlock Apr 15 10:31:34 volumiostudy volumio[4791]: info: CorePlayQueue::getTrackBlock Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreStateMachine::setRepeat null single undefined Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreStateMachine::pushState Apr 15 10:31:34 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioPushState Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreStateMachine::setRandom null Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreStateMachine::pushState Apr 15 10:31:34 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioPushState Apr 15 10:31:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 10:31:34 volumiostudy volumio[4791]: Starting albumart workers Apr 15 10:31:34 volumiostudy volumio[4791]: Starting albumart workers Apr 15 10:31:34 volumiostudy volumio[4791]: Starting albumart workers Apr 15 10:31:34 volumiostudy volumio[4791]: error: updateQueue error: null Apr 15 10:31:34 volumiostudy ntpd[4879]: Soliciting pool server 89.238.136.135 Apr 15 10:31:34 volumiostudy volumio[4791]: info: Asound.conf file written Apr 15 10:31:35 volumiostudy sudo[5123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 15 10:31:35 volumiostudy sudo[5123]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:35 volumiostudy sudo[5123]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:35 volumiostudy kernel: out of value, fixed it Apr 15 10:31:35 volumiostudy kernel: id=0 set inskew=0 Apr 15 10:31:35 volumiostudy kernel: out of value, fixed it Apr 15 10:31:35 volumiostudy kernel: id=0 set inskew=0 Apr 15 10:31:35 volumiostudy volumio[4791]: Found hardware: "ODROID-HDMI" "" "" "" "" Apr 15 10:31:35 volumiostudy volumio[4791]: Hardware is initialized using a generic method Apr 15 10:31:35 volumiostudy volumio[4791]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 15 10:31:35 volumiostudy volumio[4791]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 15 10:31:35 volumiostudy volumio[4791]: Found hardware: "ODROID-LINEOUT" "" "" "" "" Apr 15 10:31:35 volumiostudy volumio[4791]: Hardware is initialized using a generic method Apr 15 10:31:35 volumiostudy volumio[4791]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Apr 15 10:31:35 volumiostudy volumio[4791]: info: Output device has changed, restarting MPD Apr 15 10:31:35 volumiostudy sudo[5129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 10:31:35 volumiostudy sudo[5129]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:35 volumiostudy volumio[4791]: info: Output device has changed, restarting Shairport Sync Apr 15 10:31:35 volumiostudy sudo[5129]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:35 volumiostudy sudo[5131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 10:31:35 volumiostudy sudo[5131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:35 volumiostudy systemd[1]: Stopping Music Player Daemon... Apr 15 10:31:35 volumiostudy volumio[4791]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 10:31:35 volumiostudy volumio[4791]: info: ___________ START PLUGINS ___________ Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 10:31:35 volumiostudy volumio[4791]: info: [1618482695313] CoreMusicLibrary::Adding element Media Servers Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 10:31:35 volumiostudy volumio[4791]: Cannot find translation for sourceMedia Servers Apr 15 10:31:35 volumiostudy systemd[1]: mpd.service: Succeeded. Apr 15 10:31:35 volumiostudy systemd[1]: Stopped Music Player Daemon. Apr 15 10:31:35 volumiostudy systemd[1]: Starting Music Player Daemon... Apr 15 10:31:35 volumiostudy volumio[4791]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 10:31:35 volumiostudy volumio[4791]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 10:31:35 volumiostudy volumio[4791]: info: [1618482695498] CoreMusicLibrary::Adding element Last_100 Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 10:31:35 volumiostudy volumio[4791]: Cannot find translation for sourceMedia Servers Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 10:31:35 volumiostudy volumio[4791]: info: [1618482695503] CoreMusicLibrary::Adding element Webradio Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 10:31:35 volumiostudy volumio[4791]: Cannot find translation for sourceMedia Servers Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 10:31:35 volumiostudy volumio[4791]: info: Completed starting Core Plugins Apr 15 10:31:35 volumiostudy volumio[4791]: info: ------------------------------------------- Apr 15 10:31:35 volumiostudy volumio[4791]: info: ----- MyVolumio plugins startup ---- Apr 15 10:31:35 volumiostudy volumio[4791]: info: ------------------------------------------- Apr 15 10:31:35 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 15 10:31:35 volumiostudy volumio[4791]: info: Discovery: adding 81397ccf-fca4-4a32-92c7-8e49781a19d1 Apr 15 10:31:35 volumiostudy volumio[4791]: info: mDNS: Found device Volumiobookroom Apr 15 10:31:35 volumiostudy volumio[4791]: info: Discovery: adding 407941ba-df70-4dad-86de-3bac7063c999 Apr 15 10:31:35 volumiostudy volumio[4791]: info: mDNS: Found device VolumioStudy Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetState Apr 15 10:31:35 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:35 volumiostudy volumio[4791]: info: MPD Permissions set Apr 15 10:31:35 volumiostudy volumio[4791]: info: VolumeController:: Volume=32 Mute =false Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreStateMachine::pushState Apr 15 10:31:35 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioPushState Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 10:31:35 volumiostudy volumio[4791]: info: Discovery: this is already registered, 407941ba-df70-4dad-86de-3bac7063c999 Apr 15 10:31:35 volumiostudy volumio[4791]: info: mDNS: Found device VolumioStudy Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetState Apr 15 10:31:35 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:31:35 volumiostudy volumio[4791]: info: Discovery: adding 014ba9f1-b22f-4b7b-8bc5-4ab9f0ce2b45 Apr 15 10:31:35 volumiostudy volumio[4791]: info: mDNS: Found device Volumiokitchen Apr 15 10:31:35 volumiostudy volumio[4791]: info: Discovery: this is already registered, 81397ccf-fca4-4a32-92c7-8e49781a19d1 Apr 15 10:31:35 volumiostudy volumio[4791]: info: mDNS: Found device Volumiobookroom Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetState Apr 15 10:31:35 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetState Apr 15 10:31:35 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetVisibleSources Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetQueue Apr 15 10:31:35 volumiostudy volumio[4791]: info: CoreStateMachine::getQueue Apr 15 10:31:35 volumiostudy volumio[4791]: info: CorePlayQueue::getQueue Apr 15 10:31:35 volumiostudy volumio[4791]: info: Listing playlists Apr 15 10:31:52 volumiostudy ntpd[4879]: receive: Unexpected origin timestamp 0xe4229487.f6216da1 does not match aorg 0000000000.00000000 from server@194.0.5.123 xmt 0xe4229498.1a2b08bc Apr 15 10:31:52 volumiostudy volumio[4791]: info: Starting Shairport Sync Apr 15 10:31:52 volumiostudy ntpd[4879]: Soliciting pool server 45.66.39.122 Apr 15 10:31:52 volumiostudy volumio[4791]: info: Starting Shairport Sync Apr 15 10:31:52 volumiostudy volumio[4791]: info: Starting Shairport Sync Apr 15 10:31:52 volumiostudy sudo[5206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 10:31:52 volumiostudy sudo[5206]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:52 volumiostudy sudo[5208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 10:31:52 volumiostudy sudo[5208]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:52 volumiostudy systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 10:31:52 volumiostudy systemd[1]: shairport-sync.service: Succeeded. Apr 15 10:31:52 volumiostudy systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 10:31:52 volumiostudy sudo[5211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 10:31:52 volumiostudy sudo[5211]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:52 volumiostudy systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 10:31:52 volumiostudy sudo[5206]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:52 volumiostudy systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 10:31:52 volumiostudy systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 15 10:31:52 volumiostudy systemd[1]: shairport-sync.service: Succeeded. Apr 15 10:31:52 volumiostudy systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 10:31:52 volumiostudy systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 10:31:52 volumiostudy sudo[5208]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:52 volumiostudy sudo[5211]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:52 volumiostudy volumio[4791]: info: Shairport-Sync Started Apr 15 10:31:52 volumiostudy volumio[4791]: Error adding Membership: Error: addMembership EINVAL Apr 15 10:31:52 volumiostudy volumio[4791]: info: Shairport-Sync Started Apr 15 10:31:52 volumiostudy volumio[4791]: info: Shairport-Sync Started Apr 15 10:31:52 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetState Apr 15 10:31:52 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:52 volumiostudy volumio[4791]: info: Discovery: this is already registered, 014ba9f1-b22f-4b7b-8bc5-4ab9f0ce2b45 Apr 15 10:31:52 volumiostudy volumio[4791]: info: mDNS: Found device Volumiokitchen Apr 15 10:31:52 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetState Apr 15 10:31:52 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:31:52 volumiostudy mpd[5197]: Apr 15 10:31 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 15 10:31:52 volumiostudy systemd[1]: Started Music Player Daemon. Apr 15 10:31:52 volumiostudy sudo[5131]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:52 volumiostudy volumio[4791]: error: updateQueue error: null Apr 15 10:31:53 volumiostudy ntpd[4879]: Soliciting pool server 46.17.88.211 Apr 15 10:31:53 volumiostudy volumio-remote-updater[3507]: [2021-04-15 10:31:53] [connect] Successful connection Apr 15 10:31:53 volumiostudy volumio-remote-updater[3507]: [2021-04-15 10:31:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1618482713 101 Apr 15 10:31:54 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:31:58 volumiostudy sudo[5243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 10:31:58 volumiostudy sudo[5243]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:58 volumiostudy sudo[5243]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:58 volumiostudy sudo[5245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 10:31:58 volumiostudy sudo[5245]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:58 volumiostudy sudo[5245]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:58 volumiostudy sudo[5249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 15 10:31:58 volumiostudy sudo[5249]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:31:58 volumiostudy systemd[1]: Started UPnP Renderer front-end to MPD. Apr 15 10:31:58 volumiostudy sudo[5249]: pam_unix(sudo:session): session closed for user root Apr 15 10:31:58 volumiostudy volumio[4791]: info: Upmpdcli Daemon Started Apr 15 10:31:58 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:31:58 volumiostudy volumio[5251]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 15 10:31:59 volumiostudy volumio[5251]: ..............................++++ Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 15 10:32:00 volumiostudy volumio[4791]: info: Adding plugin bluetooth to MyMusic Plugins Apr 15 10:32:00 volumiostudy volumio[4791]: info: Adding plugin cd_controller to MyMusic Plugins Apr 15 10:32:00 volumiostudy volumio[4791]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 15 10:32:00 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 15 10:32:00 volumiostudy systemd[1]: systemd-fsckd.service: Succeeded. Apr 15 10:32:01 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 15 10:32:01 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 15 10:32:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:01 volumiostudy volumio[4791]: info: Starting MyVolumio Remote Streaming Endpoints Apr 15 10:32:01 volumiostudy volumio[4791]: info: MyVolumio login type: Token Apr 15 10:32:01 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 15 10:32:01 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 15 10:32:01 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 15 10:32:01 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 15 10:32:01 volumiostudy volumio[4791]: info: Streaming services startup Apr 15 10:32:01 volumiostudy volumio[4791]: info: Starting Streaming Daemon Apr 15 10:32:01 volumiostudy sudo[5303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 15 10:32:01 volumiostudy sudo[5303]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:01 volumiostudy systemd[1]: Stopping Volumio Streaming Daemon... Apr 15 10:32:01 volumiostudy systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Apr 15 10:32:01 volumiostudy systemd[1]: volumio-streaming-daemon.service: Succeeded. Apr 15 10:32:01 volumiostudy systemd[1]: Stopped Volumio Streaming Daemon. Apr 15 10:32:01 volumiostudy systemd[1]: Started Volumio Streaming Daemon. Apr 15 10:32:01 volumiostudy sudo[5303]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:01 volumiostudy volumio-streaming-daemon[5310]: ############################ Apr 15 10:32:01 volumiostudy volumio-streaming-daemon[5310]: # Volumio Streaming Daemon # Apr 15 10:32:01 volumiostudy volumio-streaming-daemon[5310]: # Running on port 7777 # Apr 15 10:32:01 volumiostudy volumio-streaming-daemon[5310]: ############################ Apr 15 10:32:01 volumiostudy volumio-streaming-daemon[5310]: Environment: production Apr 15 10:32:02 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:02 volumiostudy volumio[5251]: .....................................................................++++ Apr 15 10:32:02 volumiostudy volumio[5251]: e is 65537 (0x010001) Apr 15 10:32:03 volumiostudy volumio[5251]: writing RSA key Apr 15 10:32:03 volumiostudy volumio[4791]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 15 10:32:03 volumiostudy volumio[4791]: info: MYVOLUMIO: Adding device Apr 15 10:32:03 volumiostudy volumio[4791]: info: MYVOLUMIO: Evaluating Server Apr 15 10:32:04 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 15 10:32:04 volumiostudy volumio[4791]: info: Adding audio output: Apr 15 10:32:04 volumiostudy volumio[4791]: info: MYVOLUMIO: Adding device Apr 15 10:32:04 volumiostudy volumio[4791]: info: MYVOLUMIO: Evaluating Server Apr 15 10:32:04 volumiostudy volumio[4791]: info: Remote config written successfully Apr 15 10:32:04 volumiostudy volumio[4791]: info: Starting Tunnel 1 Apr 15 10:32:04 volumiostudy volumio-streaming-daemon[5310]: Environment: production Apr 15 10:32:04 volumiostudy volumio[4791]: info: Starting Tunnel Connection Checker Apr 15 10:32:04 volumiostudy volumio[4791]: info: Completed starting MyVolumio Plugin Apr 15 10:32:04 volumiostudy sudo[5378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 15 10:32:04 volumiostudy sudo[5378]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:04 volumiostudy systemd[1]: Started MyVolumio SSH Tunnel. Apr 15 10:32:04 volumiostudy sudo[5378]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:04 volumiostudy autossh[5393]: port set to 0, monitoring disabled Apr 15 10:32:04 volumiostudy autossh[5393]: starting ssh (count 1) Apr 15 10:32:04 volumiostudy autossh[5393]: ssh child pid is 5408 Apr 15 10:32:04 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 15 10:32:04 volumiostudy volumio[4791]: info: MyVolumio status changed Apr 15 10:32:04 volumiostudy volumio[4791]: info: Streaming services startup Apr 15 10:32:04 volumiostudy volumio[4791]: info: Re-Starting Streaming Daemon Apr 15 10:32:04 volumiostudy volumio[4791]: info: Remote SSH Started Apr 15 10:32:04 volumiostudy volumio[4791]: info: Setting Geolocation for MyVolumio to eu3 Apr 15 10:32:04 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:04 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:04 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:04 volumiostudy volumio-streaming-daemon[5310]: Restarting daemon Apr 15 10:32:04 volumiostudy volumio-streaming-daemon[5310]: Environment: production Apr 15 10:32:04 volumiostudy volumiossh-tunnel[5380]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[188.166.162.121]:2222' (RSA) to the list of known hosts. Apr 15 10:32:04 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 15 10:32:04 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Apr 15 10:32:04 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 15 10:32:04 volumiostudy volumio-streaming-daemon[5310]: Enabled services: Apr 15 10:32:04 volumiostudy volumio-streaming-daemon[5310]: 1) tidal Apr 15 10:32:04 volumiostudy volumio-streaming-daemon[5310]: 2) qobuz Apr 15 10:32:04 volumiostudy volumio[4791]: info: [1618482724773] Starting BluetoothController Apr 15 10:32:04 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 15 10:32:04 volumiostudy volumio[4791]: info: MultiRoom plugin initialized Apr 15 10:32:04 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 15 10:32:04 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 15 10:32:05 volumiostudy volumio[4791]: info: Preparing to generate the ALSA configuration file Apr 15 10:32:05 volumiostudy volumio[4791]: info: Fetching Streaming Services browse cache Apr 15 10:32:05 volumiostudy volumio[4791]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 15 10:32:05 volumiostudy volumio[4791]: info: Reading ALSA contributions from plugins. Apr 15 10:32:05 volumiostudy volumio[4791]: info: Updating MyVolumio device info Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetState Apr 15 10:32:05 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:32:05 volumiostudy volumio[4791]: info: Asound.conf file written Apr 15 10:32:05 volumiostudy sudo[5432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 15 10:32:05 volumiostudy sudo[5432]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:05 volumiostudy sudo[5432]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:05 volumiostudy kernel: out of value, fixed it Apr 15 10:32:05 volumiostudy kernel: id=0 set inskew=0 Apr 15 10:32:05 volumiostudy kernel: out of value, fixed it Apr 15 10:32:05 volumiostudy kernel: id=0 set inskew=0 Apr 15 10:32:05 volumiostudy volumio[4791]: Found hardware: "ODROID-HDMI" "" "" "" "" Apr 15 10:32:05 volumiostudy volumio[4791]: Hardware is initialized using a generic method Apr 15 10:32:05 volumiostudy volumio[4791]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 15 10:32:05 volumiostudy volumio[4791]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 15 10:32:05 volumiostudy volumio[4791]: Found hardware: "ODROID-LINEOUT" "" "" "" "" Apr 15 10:32:05 volumiostudy volumio[4791]: Hardware is initialized using a generic method Apr 15 10:32:05 volumiostudy volumio[4791]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Apr 15 10:32:05 volumiostudy volumio[4791]: info: Output device has changed, restarting MPD Apr 15 10:32:05 volumiostudy sudo[5437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 10:32:05 volumiostudy sudo[5437]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:05 volumiostudy volumio[4791]: info: Output device has changed, restarting Shairport Sync Apr 15 10:32:05 volumiostudy sudo[5437]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:05 volumiostudy sudo[5439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 10:32:05 volumiostudy sudo[5439]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:05 volumiostudy systemd[1]: Stopping Music Player Daemon... Apr 15 10:32:05 volumiostudy volumio[4791]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 15 10:32:05 volumiostudy systemd[1]: mpd.service: Succeeded. Apr 15 10:32:05 volumiostudy systemd[1]: Stopped Music Player Daemon. Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 15 10:32:05 volumiostudy systemd[1]: Starting Music Player Daemon... Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding Multiroom REST Endpoints Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding setMultiroomClient REST Endpoint for plugin: audio_interface/multiroom Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding addClient REST Endpoint for plugin: audio_interface/multiroom Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding setMultiroomSingle REST Endpoint for plugin: audio_interface/multiroom Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding getMultiroomLeader REST Endpoint for plugin: audio_interface/multiroom Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreStateMachine::pushState Apr 15 10:32:05 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioPushState Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 10:32:05 volumiostudy sudo[5447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name VolumioStudy Apr 15 10:32:05 volumiostudy sudo[5447]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding METAVOLUMIO REST API Endpoints Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 15 10:32:05 volumiostudy volumio[4791]: info: Preparing CD Folders Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding CD REST API Endpoints Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 15 10:32:05 volumiostudy volumio[4791]: info: Detecting CD presence Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding inputs REST Endpoints Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 15 10:32:05 volumiostudy volumio[4791]: info: Scanning Audio Inputs Apr 15 10:32:05 volumiostudy kernel: Bluetooth: Core ver 2.22 Apr 15 10:32:05 volumiostudy kernel: Bluetooth: Starting self testing Apr 15 10:32:05 volumiostudy kernel: Bluetooth: ECDH test passed in 12468 usecs Apr 15 10:32:05 volumiostudy volumio[4791]: info: Checking against Known Cards name Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 15 10:32:05 volumiostudy volumio[4791]: info: [1618482725826] CoreMusicLibrary::Adding element ODROID-LINEOUT Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 10:32:05 volumiostudy volumio[4791]: Cannot find translation for sourceMedia Servers Apr 15 10:32:05 volumiostudy volumio[4791]: Cannot find translation for sourceODROID-LINEOUT Apr 15 10:32:05 volumiostudy volumio[4791]: info: Checking against Known Cards name Apr 15 10:32:05 volumiostudy kernel: Bluetooth: SMP test passed in 25 usecs Apr 15 10:32:05 volumiostudy kernel: Bluetooth: Finished self testing Apr 15 10:32:05 volumiostudy kernel: NET: Registered protocol family 31 Apr 15 10:32:05 volumiostudy kernel: Bluetooth: HCI device and connection manager initialized Apr 15 10:32:05 volumiostudy kernel: Bluetooth: HCI socket layer initialized Apr 15 10:32:05 volumiostudy kernel: Bluetooth: L2CAP socket layer initialized Apr 15 10:32:05 volumiostudy kernel: Bluetooth: SCO socket layer initialized Apr 15 10:32:05 volumiostudy volumio[4791]: info: Adding Server instance for streaming Apr 15 10:32:05 volumiostudy sudo[5447]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 15 10:32:05 volumiostudy volumio[4791]: error: Hi Res Audio Failed Login: Missing Login Data Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 15 10:32:05 volumiostudy volumio[4791]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 15 10:32:05 volumiostudy volumio[4791]: info: Stopping AccessToken refresher cron Apr 15 10:32:05 volumiostudy volumio[4791]: info: AccessToken refresher cron started Apr 15 10:32:05 volumiostudy volumio[4791]: info: Stopping AccessToken refresher cron for QOBUZ Apr 15 10:32:05 volumiostudy volumio[4791]: info: AccessToken refresher cron started for QOBUZ Apr 15 10:32:05 volumiostudy volumio[4791]: info: BOOT COMPLETED Apr 15 10:32:05 volumiostudy volumio[4791]: [Metrics] CommandRouter: 20s 42.27ms Apr 15 10:32:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:06 volumiostudy ALSA-SWITCH[5475]: Incoming Audio format S16_LE : 44100 : 2 is being directed to volumioMultiRoomLocalPlayback Apr 15 10:32:06 volumiostudy ALSA-SWITCH[5475]: as set in /tmp/multiroom/server/switch.target Apr 15 10:32:06 volumiostudy kernel: aml_tdm_open Apr 15 10:32:06 volumiostudy kernel: Not init audio effects Apr 15 10:32:06 volumiostudy kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@2 Apr 15 10:32:06 volumiostudy kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 15 10:32:06 volumiostudy kernel: asoc aml_dai_set_tdm_fmt, 0x4011, ffffffc049b1e618, id(2), clksel(2) Apr 15 10:32:06 volumiostudy kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 15 10:32:06 volumiostudy kernel: dump_pcm_setting(ffffffc049b1e618) Apr 15 10:32:06 volumiostudy kernel: pcm_mode(1) Apr 15 10:32:06 volumiostudy kernel: sysclk(11289600) Apr 15 10:32:06 volumiostudy kernel: sysclk_bclk_ratio(4) Apr 15 10:32:06 volumiostudy kernel: bclk(2822400) Apr 15 10:32:06 volumiostudy kernel: bclk_lrclk_ratio(64) Apr 15 10:32:06 volumiostudy kernel: lrclk(44100) Apr 15 10:32:06 volumiostudy kernel: tx_mask(0x3) Apr 15 10:32:06 volumiostudy kernel: rx_mask(0x3) Apr 15 10:32:06 volumiostudy kernel: slots(2) Apr 15 10:32:06 volumiostudy kernel: slot_width(32) Apr 15 10:32:06 volumiostudy kernel: lane_mask_in(0x2) Apr 15 10:32:06 volumiostudy kernel: lane_mask_out(0x1) Apr 15 10:32:06 volumiostudy kernel: lane_oe_mask_in(0x0) Apr 15 10:32:06 volumiostudy kernel: lane_oe_mask_out(0x0) Apr 15 10:32:06 volumiostudy kernel: lane_lb_mask_in(0x0) Apr 15 10:32:06 volumiostudy kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 15 10:32:06 volumiostudy kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 15 10:32:06 volumiostudy kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 15 10:32:06 volumiostudy kernel: aml_dai_set_clkdiv, div 4, clksel(2) Apr 15 10:32:06 volumiostudy kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 15 10:32:06 volumiostudy kernel: aml_tdm_prepare(), reset fddr Apr 15 10:32:06 volumiostudy kernel: tdm playback mute: 0, lane_cnt = 4 Apr 15 10:32:06 volumiostudy kernel: asoc-aml-card odroid_lineout: tdm playback enable Apr 15 10:32:06 volumiostudy mpd[5476]: Apr 15 10:32 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 15 10:32:06 volumiostudy systemd[1]: Started Music Player Daemon. Apr 15 10:32:06 volumiostudy sudo[5439]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:11 volumiostudy kernel: asoc-aml-card odroid_lineout: tdm playback stop Apr 15 10:32:11 volumiostudy kernel: tdm playback mute: 1, lane_cnt = 4 Apr 15 10:32:11 volumiostudy kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@2 Apr 15 10:32:11 volumiostudy ALSA-SWITCH[5475]: Reached the end of the incoming Audio stream being sent to volumioMultiRoomLocalPlayback Apr 15 10:32:11 volumiostudy ALSA-SWITCH[5475]: as set in /tmp/multiroom/server/switch.target Apr 15 10:32:11 volumiostudy ALSA-SWITCH[5475]: Released volumioMultiRoomLocalPlayback Apr 15 10:32:11 volumiostudy ALSA-SWITCH[5475]: as set in /tmp/multiroom/server/switch.target Apr 15 10:32:11 volumiostudy volumio[4791]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::Close All Modals sent Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::Close All Modals sent Apr 15 10:32:11 volumiostudy sudo[5487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 15 10:32:11 volumiostudy sudo[5487]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:11 volumiostudy volumio[4791]: info: Setting Geolocation for MyVolumio to eu3 Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:11 volumiostudy systemd[1]: Started Volumio Bluetooth Module. Apr 15 10:32:11 volumiostudy sudo[5487]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:11 volumiostudy volumio[4791]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'VolumioStudy' Apr 15 10:32:11 volumiostudy volumio[4791]: Can't get device info: No such device Apr 15 10:32:11 volumiostudy volumio[4791]: info: MPD Permissions set Apr 15 10:32:11 volumiostudy sudo[5508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 15 10:32:11 volumiostudy sudo[5508]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:11 volumiostudy volumiobt[5489]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 15 10:32:11 volumiostudy sudo[5508]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:11 volumiostudy volumio[4791]: info: Volumio BT Module successfully started Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:11 volumiostudy volumio[4791]: error: updateQueue error: null Apr 15 10:32:11 volumiostudy volumio[4791]: info: Starting Shairport Sync Apr 15 10:32:11 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:11 volumiostudy sudo[5528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 10:32:11 volumiostudy sudo[5528]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:11 volumiostudy systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 10:32:11 volumiostudy systemd[1]: shairport-sync.service: Succeeded. Apr 15 10:32:11 volumiostudy systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 10:32:11 volumiostudy systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 10:32:11 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:32:11 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:32:11 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:32:11 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:32:11 volumiostudy sudo[5528]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:11 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 10:32:11 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:32:11 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:32:11 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:32:11 volumiostudy volumio[4791]: info: Shairport-Sync Started Apr 15 10:32:11 volumiostudy volumio-streaming-daemon[5310]: Environment: production Apr 15 10:32:11 volumiostudy volumio-streaming-daemon[5310]: Environment: production Apr 15 10:32:12 volumiostudy volumio[4791]: info: Fetching Streaming Services browse cache Apr 15 10:32:12 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 15 10:32:12 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 10:32:12 volumiostudy volumio[4791]: xcb_connection_has_error() returned true Apr 15 10:32:12 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 15 10:32:12 volumiostudy volumio[4791]: info: Updating MyVolumio device info Apr 15 10:32:12 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:12 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:12 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:12 volumiostudy sudo[5552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 15 10:32:12 volumiostudy sudo[5552]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:32:12 volumiostudy volumiobt[5489]: pulseaudio: no process found Apr 15 10:32:12 volumiostudy sudo[5552]: pam_unix(sudo:session): session closed for user root Apr 15 10:32:12 volumiostudy dbus-daemon[3528]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.18' (uid=1000 pid=5556 comm="/usr/bin/pulseaudio " label="kernel") Apr 15 10:32:12 volumiostudy systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 15 10:32:12 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:32:12 volumiostudy dbus-daemon[3528]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 15 10:32:12 volumiostudy systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 15 10:32:12 volumiostudy rtkit-daemon[5557]: Successfully called chroot. Apr 15 10:32:12 volumiostudy rtkit-daemon[5557]: Successfully dropped privileges. Apr 15 10:32:12 volumiostudy rtkit-daemon[5557]: Successfully limited resources. Apr 15 10:32:12 volumiostudy rtkit-daemon[5557]: Running. Apr 15 10:32:12 volumiostudy rtkit-daemon[5557]: Canary thread running. Apr 15 10:32:12 volumiostudy rtkit-daemon[5557]: Watchdog thread running. Apr 15 10:32:12 volumiostudy dbus-daemon[3528]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.19' (uid=0 pid=5557 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Apr 15 10:32:12 volumiostudy systemd[1]: Starting Authorization Manager... Apr 15 10:32:12 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:32:12 volumiostudy polkitd[5579]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 15 10:32:12 volumiostudy dbus-daemon[3528]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 15 10:32:12 volumiostudy systemd[1]: Started Authorization Manager. Apr 15 10:32:12 volumiostudy dbus-daemon[3528]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.39' (uid=1000 pid=5556 comm="/usr/bin/pulseaudio " label="kernel") Apr 15 10:32:12 volumiostudy systemd[1]: Starting Bluetooth service... Apr 15 10:32:12 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:32:12 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:32:12 volumiostudy pulseaudio[5556]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 15 10:32:12 volumiostudy pulseaudio[5556]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 15 10:32:12 volumiostudy volumiobt[5489]: Applying permissions Apr 15 10:32:12 volumiostudy volumiobt[5489]: Setting BT discoverable and pairable Apr 15 10:32:12 volumiostudy bluetoothd[5603]: Bluetooth daemon 5.50 Apr 15 10:32:12 volumiostudy bluetoothd[5603]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 15 10:32:12 volumiostudy dbus-daemon[3528]: [system] Successfully activated service 'org.bluez' Apr 15 10:32:12 volumiostudy systemd[1]: Started Bluetooth service. Apr 15 10:32:12 volumiostudy bluetoothd[5603]: Starting SDP server Apr 15 10:32:12 volumiostudy bluetoothd[5603]: Excluding (cli) sap Apr 15 10:32:12 volumiostudy kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 15 10:32:12 volumiostudy kernel: Bluetooth: BNEP filters: protocol multicast Apr 15 10:32:12 volumiostudy kernel: Bluetooth: BNEP socket layer initialized Apr 15 10:32:12 volumiostudy dbus-daemon[3528]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.40' (uid=0 pid=5603 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap " label="kernel") Apr 15 10:32:12 volumiostudy bluetoothd[5603]: Bluetooth management interface 1.14 initialized Apr 15 10:32:13 volumiostudy volumiobt[5489]: [bluetooth]# -e power on Apr 15 10:32:13 volumiostudy volumiobt[5489]: Invalid command in menu main: -e Apr 15 10:32:13 volumiostudy volumiobt[5489]: Apr 15 10:32:13 volumiostudy volumiobt[5489]: Use "help" for a list of available commands in a menu. Apr 15 10:32:13 volumiostudy volumiobt[5489]: Use "menu " if you want to enter any submenu. Apr 15 10:32:13 volumiostudy volumiobt[5489]: Use "back" if you want to return to menu main. Apr 15 10:32:13 volumiostudy volumiobt[5489]: [bluetooth]# agent on Apr 15 10:32:13 volumiostudy volumiobt[5489]: Failed to register agent object Apr 15 10:32:13 volumiostudy volumiobt[5489]: [bluetooth]# discoverable on Apr 15 10:32:13 volumiostudy volumiobt[5489]: No default controller available Apr 15 10:32:13 volumiostudy volumiobt[5489]: [bluetooth]# pairable on Apr 15 10:32:13 volumiostudy volumiobt[5489]: No default controller available Apr 15 10:32:13 volumiostudy volumiobt[5489]: [bluetooth]# agent NoInputNoOutput Apr 15 10:32:13 volumiostudy volumiobt[5489]: Failed to register agent object Apr 15 10:32:13 volumiostudy volumiobt[5489]: [bluetooth]# default-agent Apr 15 10:32:13 volumiostudy volumiobt[5489]: No agent is registered Apr 15 10:32:13 volumiostudy volumiobt[5489]: [bluetooth]# quit Apr 15 10:32:13 volumiostudy pulseaudio[5556]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Apr 15 10:32:13 volumiostudy volumiobt[5489]: [59B blob data] Apr 15 10:32:13 volumiostudy systemd[1]: Starting Hostname Service... Apr 15 10:32:13 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:32:13 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:32:13 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:32:13 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:32:13 volumiostudy dbus-daemon[3528]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 15 10:32:13 volumiostudy systemd[1]: Started Hostname Service. Apr 15 10:32:14 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:18 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 10:32:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 10:32:22 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:23 volumiostudy kernel: fb: mem_free_work, free memory: addr:800000 Apr 15 10:32:26 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:30 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:38 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:42 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:43 volumiostudy systemd[1]: systemd-hostnamed.service: Succeeded. Apr 15 10:32:46 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:50 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:54 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:32:58 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:02 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:06 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:10 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:14 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:18 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:22 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:26 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:30 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:38 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:42 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:46 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:50 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:54 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:33:58 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:02 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:06 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:10 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:14 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:18 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:22 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:26 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:30 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:34 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:38 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:42 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:46 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:50 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:54 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:34:58 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:02 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:06 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:10 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:14 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:18 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:22 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:35:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:04 volumiostudy volumio[4791]: info: Tunnel connection is inactive, restarting it Apr 15 10:36:04 volumiostudy volumio[4791]: info: Starting Tunnel 1 Apr 15 10:36:04 volumiostudy volumio[4791]: info: Starting Tunnel Connection Checker Apr 15 10:36:04 volumiostudy sudo[5660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 15 10:36:04 volumiostudy sudo[5660]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 10:36:04 volumiostudy autossh[5393]: received signal to exit (15) Apr 15 10:36:04 volumiostudy systemd[1]: Stopping MyVolumio SSH Tunnel... Apr 15 10:36:04 volumiostudy systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Apr 15 10:36:04 volumiostudy systemd[1]: sshtunnel.service: Succeeded. Apr 15 10:36:04 volumiostudy systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 15 10:36:04 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:36:04 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:36:04 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:36:04 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:36:04 volumiostudy systemd[1]: Started MyVolumio SSH Tunnel. Apr 15 10:36:04 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 10:36:04 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:36:04 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:36:04 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:36:04 volumiostudy sudo[5660]: pam_unix(sudo:session): session closed for user root Apr 15 10:36:04 volumiostudy volumio[4791]: info: Remote SSH Started Apr 15 10:36:04 volumiostudy autossh[5679]: port set to 0, monitoring disabled Apr 15 10:36:04 volumiostudy autossh[5679]: starting ssh (count 1) Apr 15 10:36:04 volumiostudy autossh[5679]: ssh child pid is 5689 Apr 15 10:36:04 volumiostudy volumio[4791]: info: CoreCommandRouter::volumioGetState Apr 15 10:36:04 volumiostudy volumio[4791]: info: CorePlayQueue::getTrack 0 Apr 15 10:36:04 volumiostudy volumiossh-tunnel[5662]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Apr 15 10:36:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:23 volumiostudy sshd[5695]: Accepted password for volumio from 192.168.2.59 port 40990 ssh2 Apr 15 10:36:23 volumiostudy sshd[5695]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Apr 15 10:36:23 volumiostudy systemd[1]: Created slice User Slice of UID 1000. Apr 15 10:36:23 volumiostudy systemd[1]: Starting User Runtime Directory /run/user/1000... Apr 15 10:36:23 volumiostudy systemd-logind[3555]: New session 1 of user volumio. Apr 15 10:36:23 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:36:23 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:36:23 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:36:23 volumiostudy systemd[1]: Started User Runtime Directory /run/user/1000. Apr 15 10:36:23 volumiostudy systemd[1]: Starting User Manager for UID 1000... Apr 15 10:36:23 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:36:23 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:36:23 volumiostudy systemd[5734]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Apr 15 10:36:23 volumiostudy systemd[5734]: Reached target Timers. Apr 15 10:36:23 volumiostudy systemd[5734]: Listening on GnuPG cryptographic agent and passphrase cache. Apr 15 10:36:23 volumiostudy systemd[5734]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Apr 15 10:36:23 volumiostudy systemd[5734]: Reached target Paths. Apr 15 10:36:23 volumiostudy systemd[5734]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 15 10:36:23 volumiostudy systemd[5734]: Listening on GnuPG network certificate management daemon. Apr 15 10:36:23 volumiostudy systemd[5734]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Apr 15 10:36:23 volumiostudy systemd[5734]: Starting D-Bus User Message Bus Socket. Apr 15 10:36:23 volumiostudy systemd[5734]: Listening on Sound System. Apr 15 10:36:23 volumiostudy systemd[5734]: Listening on D-Bus User Message Bus Socket. Apr 15 10:36:23 volumiostudy systemd[5734]: Reached target Sockets. Apr 15 10:36:23 volumiostudy systemd[5734]: Reached target Basic System. Apr 15 10:36:23 volumiostudy systemd[5734]: Reached target Default. Apr 15 10:36:23 volumiostudy systemd[5734]: Startup finished in 157ms. Apr 15 10:36:23 volumiostudy systemd[1]: Started User Manager for UID 1000. Apr 15 10:36:23 volumiostudy systemd[1]: Started Session 1 of user volumio. Apr 15 10:36:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:29 volumiostudy sudo[5808]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Apr 15 10:36:29 volumiostudy sudo[5808]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 15 10:36:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:36:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:30 volumiostudy ntpd[4879]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 15 10:37:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:37:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:38:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:39:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:40:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:32 volumiostudy kernel: fb: osd[0] enable: 0 (kworker/0:1) Apr 15 10:41:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:41:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:42:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 10:43:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:43 volumiostudy sudo[5808]: pam_unix(sudo:session): session closed for user root Apr 15 10:43:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:43:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:44:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:34 volumiostudy ntpd[4879]: 80.4.151.252 local addr 192.168.2.224 -> Apr 15 10:45:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:45:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:21 volumiostudy sudo[5854]: volumio : TTY=pts/0 ; PWD=/var/log ; USER=root ; COMMAND=/bin/journalctl -u mpd Apr 15 10:46:21 volumiostudy sudo[5854]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 15 10:46:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:29 volumiostudy systemd[1]: Starting Cleanup of Temporary Directories... Apr 15 10:46:29 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:46:29 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:46:29 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:46:29 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:46:29 volumiostudy systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Apr 15 10:46:29 volumiostudy systemd[1]: Started Cleanup of Temporary Directories. Apr 15 10:46:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:43 volumiostudy sudo[5854]: pam_unix(sudo:session): session closed for user root Apr 15 10:46:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:46:55 volumiostudy sudo[5902]: volumio : TTY=pts/0 ; PWD=/var/log ; USER=root ; COMMAND=/bin/systemctl status mpd Apr 15 10:46:55 volumiostudy sudo[5902]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 15 10:46:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:17 volumiostudy sudo[5902]: pam_unix(sudo:session): session closed for user root Apr 15 10:47:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:50 volumiostudy sudo[5908]: volumio : TTY=pts/0 ; PWD=/var/log ; USER=root ; COMMAND=/bin/systemctl -b Apr 15 10:47:50 volumiostudy sudo[5908]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 15 10:47:50 volumiostudy sudo[5908]: pam_unix(sudo:session): session closed for user root Apr 15 10:47:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:54 volumiostudy volumio[4791]: info: Adding audio output: Apr 15 10:47:54 volumiostudy volumio[4791]: info: Adding audio output: Apr 15 10:47:54 volumiostudy volumio[4791]: info: Adding audio output: Apr 15 10:47:54 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:54 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:54 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:54 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:54 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:54 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:55 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:55 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:55 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:55 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:56 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:56 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:47:57 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:57 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:58 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:58 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:58 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:58 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:59 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:59 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:59 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:59 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:47:59 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:47:59 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:00 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:00 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:00 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:00 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:00 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:00 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:01 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:01 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:01 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:01 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:02 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:02 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:02 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:02 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:12 volumiostudy sudo[5912]: volumio : TTY=pts/0 ; PWD=/var/log ; USER=root ; COMMAND=/bin/journalctl -b Apr 15 10:48:12 volumiostudy sudo[5912]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 15 10:48:12 volumiostudy sudo[5912]: pam_unix(sudo:session): session closed for user root Apr 15 10:48:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:19 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:19 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:19 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:19 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:19 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:19 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:21 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:21 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:22 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:22 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:22 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:22 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:23 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:23 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:23 volumiostudy sshd[5799]: Received disconnect from 192.168.2.59 port 40990:11: disconnected by user Apr 15 10:48:23 volumiostudy sshd[5799]: Disconnected from user volumio 192.168.2.59 port 40990 Apr 15 10:48:23 volumiostudy sshd[5695]: pam_unix(sshd:session): session closed for user volumio Apr 15 10:48:23 volumiostudy systemd[1]: session-1.scope: Succeeded. Apr 15 10:48:23 volumiostudy systemd-logind[3555]: Session 1 logged out. Waiting for processes to exit. Apr 15 10:48:23 volumiostudy systemd-logind[3555]: Removed session 1. Apr 15 10:48:24 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:24 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:25 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:25 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:25 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:25 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:26 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:26 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:27 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:27 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:28 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:28 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:30 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:30 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:31 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:31 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:33 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:33 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:34 volumiostudy systemd[1]: Stopping User Manager for UID 1000... Apr 15 10:48:34 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:48:34 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:48:34 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:48:34 volumiostudy systemd[5734]: Stopped target Default. Apr 15 10:48:34 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:48:34 volumiostudy systemd[5734]: Stopped target Basic System. Apr 15 10:48:34 volumiostudy systemd[5734]: Stopped target Paths. Apr 15 10:48:34 volumiostudy systemd[5734]: Stopped target Sockets. Apr 15 10:48:34 volumiostudy systemd[5734]: dirmngr.socket: Succeeded. Apr 15 10:48:34 volumiostudy systemd[5734]: Closed GnuPG network certificate management daemon. Apr 15 10:48:34 volumiostudy systemd[5734]: dbus.socket: Succeeded. Apr 15 10:48:34 volumiostudy systemd[5734]: Closed D-Bus User Message Bus Socket. Apr 15 10:48:34 volumiostudy systemd[5734]: gpg-agent-ssh.socket: Succeeded. Apr 15 10:48:34 volumiostudy systemd[5734]: Closed GnuPG cryptographic agent (ssh-agent emulation). Apr 15 10:48:34 volumiostudy systemd[5734]: gpg-agent-extra.socket: Succeeded. Apr 15 10:48:34 volumiostudy systemd[5734]: Closed GnuPG cryptographic agent and passphrase cache (restricted). Apr 15 10:48:34 volumiostudy systemd[5734]: gpg-agent-browser.socket: Succeeded. Apr 15 10:48:34 volumiostudy systemd[5734]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 15 10:48:34 volumiostudy systemd[5734]: pulseaudio.socket: Succeeded. Apr 15 10:48:34 volumiostudy systemd[5734]: Closed Sound System. Apr 15 10:48:34 volumiostudy systemd[5734]: gpg-agent.socket: Succeeded. Apr 15 10:48:34 volumiostudy systemd[5734]: Closed GnuPG cryptographic agent and passphrase cache. Apr 15 10:48:34 volumiostudy systemd[5734]: Reached target Shutdown. Apr 15 10:48:34 volumiostudy systemd[5734]: systemd-exit.service: Succeeded. Apr 15 10:48:34 volumiostudy systemd[5734]: Started Exit the Session. Apr 15 10:48:34 volumiostudy systemd[5734]: Reached target Exit the Session. Apr 15 10:48:34 volumiostudy systemd[5734]: Stopped target Timers. Apr 15 10:48:34 volumiostudy systemd[5754]: pam_unix(systemd-user:session): session closed for user volumio Apr 15 10:48:34 volumiostudy systemd[1]: user@1000.service: Succeeded. Apr 15 10:48:34 volumiostudy systemd[1]: Stopped User Manager for UID 1000. Apr 15 10:48:34 volumiostudy systemd[1]: Stopping User Runtime Directory /run/user/1000... Apr 15 10:48:34 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:48:34 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:48:34 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:48:34 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:48:34 volumiostudy systemd[1]: run-user-1000.mount: Succeeded. Apr 15 10:48:34 volumiostudy systemd[1]: user-runtime-dir@1000.service: Succeeded. Apr 15 10:48:34 volumiostudy systemd[1]: Stopped User Runtime Directory /run/user/1000. Apr 15 10:48:34 volumiostudy systemd[1]: Removed slice User Slice of UID 1000. Apr 15 10:48:35 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:35 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:37 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:37 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:39 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.220 Apr 15 10:48:39 volumiostudy volumio[4791]: error: Failed to look up multiroom leader for http://192.168.2.222 Apr 15 10:48:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:43 volumiostudy ntpd[4879]: 217.114.59.3 local addr 192.168.2.224 -> Apr 15 10:48:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:49 volumiostudy sshd[5950]: Accepted password for volumio from 192.168.2.59 port 41004 ssh2 Apr 15 10:48:49 volumiostudy sshd[5950]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Apr 15 10:48:49 volumiostudy systemd[1]: Created slice User Slice of UID 1000. Apr 15 10:48:49 volumiostudy systemd[1]: Starting User Runtime Directory /run/user/1000... Apr 15 10:48:49 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:48:49 volumiostudy systemd-logind[3555]: New session 3 of user volumio. Apr 15 10:48:49 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:48:49 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:48:49 volumiostudy systemd[1]: Started User Runtime Directory /run/user/1000. Apr 15 10:48:49 volumiostudy systemd[1]: Starting User Manager for UID 1000... Apr 15 10:48:49 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:48:49 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:48:49 volumiostudy systemd[5977]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Apr 15 10:48:49 volumiostudy systemd[5977]: Listening on Sound System. Apr 15 10:48:49 volumiostudy systemd[5977]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Apr 15 10:48:49 volumiostudy systemd[5977]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Apr 15 10:48:49 volumiostudy systemd[5977]: Starting D-Bus User Message Bus Socket. Apr 15 10:48:49 volumiostudy systemd[5977]: Reached target Timers. Apr 15 10:48:49 volumiostudy systemd[5977]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 15 10:48:49 volumiostudy systemd[5977]: Listening on GnuPG cryptographic agent and passphrase cache. Apr 15 10:48:49 volumiostudy systemd[5977]: Listening on GnuPG network certificate management daemon. Apr 15 10:48:49 volumiostudy systemd[5977]: Reached target Paths. Apr 15 10:48:49 volumiostudy systemd[5977]: Listening on D-Bus User Message Bus Socket. Apr 15 10:48:49 volumiostudy systemd[5977]: Reached target Sockets. Apr 15 10:48:49 volumiostudy systemd[5977]: Reached target Basic System. Apr 15 10:48:49 volumiostudy systemd[5977]: Reached target Default. Apr 15 10:48:49 volumiostudy systemd[5977]: Startup finished in 135ms. Apr 15 10:48:49 volumiostudy systemd[1]: Started User Manager for UID 1000. Apr 15 10:48:49 volumiostudy systemd[1]: Started Session 3 of user volumio. Apr 15 10:48:49 volumiostudy sshd[6037]: Received disconnect from 192.168.2.59 port 41004:11: disconnected by user Apr 15 10:48:49 volumiostudy sshd[6037]: Disconnected from user volumio 192.168.2.59 port 41004 Apr 15 10:48:49 volumiostudy sshd[5950]: pam_unix(sshd:session): session closed for user volumio Apr 15 10:48:49 volumiostudy systemd[1]: session-3.scope: Succeeded. Apr 15 10:48:49 volumiostudy systemd-logind[3555]: Session 3 logged out. Waiting for processes to exit. Apr 15 10:48:49 volumiostudy systemd-logind[3555]: Removed session 3. Apr 15 10:48:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:48:59 volumiostudy systemd[1]: Stopping User Manager for UID 1000... Apr 15 10:48:59 volumiostudy systemd[5977]: Stopped target Default. Apr 15 10:48:59 volumiostudy systemd[5977]: Stopped target Basic System. Apr 15 10:48:59 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:48:59 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:48:59 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:48:59 volumiostudy systemd[5977]: Stopped target Timers. Apr 15 10:48:59 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:48:59 volumiostudy systemd[5977]: Stopped target Paths. Apr 15 10:48:59 volumiostudy systemd[5977]: Stopped target Sockets. Apr 15 10:48:59 volumiostudy systemd[5977]: dirmngr.socket: Succeeded. Apr 15 10:48:59 volumiostudy systemd[5977]: Closed GnuPG network certificate management daemon. Apr 15 10:48:59 volumiostudy systemd[5977]: dbus.socket: Succeeded. Apr 15 10:48:59 volumiostudy systemd[5977]: Closed D-Bus User Message Bus Socket. Apr 15 10:48:59 volumiostudy systemd[5977]: gpg-agent-browser.socket: Succeeded. Apr 15 10:48:59 volumiostudy systemd[5977]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 15 10:48:59 volumiostudy systemd[5977]: gpg-agent.socket: Succeeded. Apr 15 10:48:59 volumiostudy systemd[5977]: Closed GnuPG cryptographic agent and passphrase cache. Apr 15 10:48:59 volumiostudy systemd[5977]: gpg-agent-extra.socket: Succeeded. Apr 15 10:48:59 volumiostudy systemd[5977]: Closed GnuPG cryptographic agent and passphrase cache (restricted). Apr 15 10:48:59 volumiostudy systemd[5977]: pulseaudio.socket: Succeeded. Apr 15 10:48:59 volumiostudy systemd[5977]: Closed Sound System. Apr 15 10:48:59 volumiostudy systemd[5977]: gpg-agent-ssh.socket: Succeeded. Apr 15 10:48:59 volumiostudy systemd[5977]: Closed GnuPG cryptographic agent (ssh-agent emulation). Apr 15 10:48:59 volumiostudy systemd[5977]: Reached target Shutdown. Apr 15 10:48:59 volumiostudy systemd[5977]: systemd-exit.service: Succeeded. Apr 15 10:48:59 volumiostudy systemd[5977]: Started Exit the Session. Apr 15 10:48:59 volumiostudy systemd[5977]: Reached target Exit the Session. Apr 15 10:48:59 volumiostudy systemd[5996]: pam_unix(systemd-user:session): session closed for user volumio Apr 15 10:48:59 volumiostudy systemd[1]: user@1000.service: Succeeded. Apr 15 10:48:59 volumiostudy systemd[1]: Stopped User Manager for UID 1000. Apr 15 10:48:59 volumiostudy systemd[1]: Stopping User Runtime Directory /run/user/1000... Apr 15 10:48:59 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:48:59 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:48:59 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:48:59 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:48:59 volumiostudy systemd[1]: run-user-1000.mount: Succeeded. Apr 15 10:48:59 volumiostudy systemd[1]: user-runtime-dir@1000.service: Succeeded. Apr 15 10:48:59 volumiostudy systemd[1]: Stopped User Runtime Directory /run/user/1000. Apr 15 10:48:59 volumiostudy systemd[1]: Removed slice User Slice of UID 1000. Apr 15 10:49:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:41 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:45 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:49 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:53 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:49:55 volumiostudy sshd[6118]: Accepted password for volumio from 192.168.2.59 port 41006 ssh2 Apr 15 10:49:55 volumiostudy sshd[6118]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Apr 15 10:49:55 volumiostudy systemd[1]: Created slice User Slice of UID 1000. Apr 15 10:49:55 volumiostudy systemd[1]: Starting User Runtime Directory /run/user/1000... Apr 15 10:49:55 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:49:55 volumiostudy systemd-logind[3555]: New session 5 of user volumio. Apr 15 10:49:55 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:49:55 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:49:55 volumiostudy systemd[1]: Started User Runtime Directory /run/user/1000. Apr 15 10:49:55 volumiostudy systemd[1]: Starting User Manager for UID 1000... Apr 15 10:49:55 volumiostudy volumio[4791]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: Playing: false Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: VOLATILE: Apr 15 10:49:55 volumiostudy volumio[4791]: BT PLUGIN MESSAGE: STATE:undefined Apr 15 10:49:55 volumiostudy systemd[6144]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Apr 15 10:49:55 volumiostudy systemd[6144]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Apr 15 10:49:55 volumiostudy systemd[6144]: Listening on Sound System. Apr 15 10:49:55 volumiostudy systemd[6144]: Listening on GnuPG cryptographic agent and passphrase cache. Apr 15 10:49:55 volumiostudy systemd[6144]: Reached target Timers. Apr 15 10:49:55 volumiostudy systemd[6144]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Apr 15 10:49:55 volumiostudy systemd[6144]: Reached target Paths. Apr 15 10:49:55 volumiostudy systemd[6144]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 15 10:49:55 volumiostudy systemd[6144]: Starting D-Bus User Message Bus Socket. Apr 15 10:49:55 volumiostudy systemd[6144]: Listening on GnuPG network certificate management daemon. Apr 15 10:49:55 volumiostudy systemd[6144]: Listening on D-Bus User Message Bus Socket. Apr 15 10:49:55 volumiostudy systemd[6144]: Reached target Sockets. Apr 15 10:49:55 volumiostudy systemd[6144]: Reached target Basic System. Apr 15 10:49:55 volumiostudy systemd[6144]: Reached target Default. Apr 15 10:49:55 volumiostudy systemd[6144]: Startup finished in 128ms. Apr 15 10:49:55 volumiostudy systemd[1]: Started User Manager for UID 1000. Apr 15 10:49:55 volumiostudy systemd[1]: Started Session 5 of user volumio. Apr 15 10:49:57 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:01 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:05 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:09 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:13 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:17 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:21 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:25 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:29 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:33 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:37 volumiostudy volumio[4791]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 10:50:41 volumiostudy sudo[6221]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -b Apr 15 10:50:41 volumiostudy sudo[6221]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)