-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-08-29 16:41:15 CEST. -- Aug 29 16:40:24 volumiosr volumio[1111]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:29 volumiosr volumio[1111]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 16:40:31 volumiosr volumio[1111]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:40:36 volumiosr volumio[1111]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 16:40:38 volumiosr volumio[1111]: info: CALLMETHOD: system_controller backup_restore restore [object Object] Aug 29 16:40:38 volumiosr volumio[1111]: info: CoreCommandRouter::executeOnPlugin: backup_restore , restore Aug 29 16:40:42 volumiosr volumio[1111]: info: CALLMETHOD: system_controller backup_restore relaunch [object Object] Aug 29 16:40:42 volumiosr volumio[1111]: info: CoreCommandRouter::executeOnPlugin: backup_restore , relaunch Aug 29 16:40:42 volumiosr sudo[2336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio Aug 29 16:40:42 volumiosr sudo[2336]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:42 volumiosr systemd[1]: Started dynamicswap service. Aug 29 16:40:42 volumiosr systemd[1]: Stopping Volumio Backend Module... Aug 29 16:40:42 volumiosr sudo[2336]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:42 volumiosr systemd[1]: dynamicswap.service: Succeeded. Aug 29 16:40:42 volumiosr volumio-remote-updater[620]: [2024-08-29 16:40:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 16:40:42 volumiosr volumio-remote-updater[620]: [2024-08-29 16:40:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 16:40:42 volumiosr systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Aug 29 16:40:42 volumiosr systemd[1]: volumio.service: Succeeded. Aug 29 16:40:42 volumiosr systemd[1]: Stopped Volumio Backend Module. Aug 29 16:40:42 volumiosr systemd[1]: Started Volumio Backend Module. Aug 29 16:40:43 volumiosr volumio[2344]: info: ------------------------------------------- Aug 29 16:40:43 volumiosr volumio[2344]: info: ----- Volumio3 ---- Aug 29 16:40:43 volumiosr volumio[2344]: info: ------------------------------------------- Aug 29 16:40:43 volumiosr volumio[2344]: info: ----- System startup ---- Aug 29 16:40:43 volumiosr volumio[2344]: info: ------------------------------------------- Aug 29 16:40:44 volumiosr volumio[2344]: info: MYVOLUMIO Environment detected Aug 29 16:40:44 volumiosr volumio[2344]: info: Plugin folders cleanup Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning into folder /volumio/app/plugins/ Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning category audio_interface Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning category miscellanea Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning category music_service Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning category plugins.json Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning category system_controller Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning category user_interface Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning into folder /data/plugins/ Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning category system_controller Aug 29 16:40:44 volumiosr volumio[2344]: info: Scanning category user_interface Aug 29 16:40:44 volumiosr volumio[2344]: info: Plugin folders cleanup completed Aug 29 16:40:44 volumiosr volumio[2344]: info: ------------------------------------------- Aug 29 16:40:44 volumiosr volumio[2344]: info: ----- Core plugins startup ---- Aug 29 16:40:44 volumiosr volumio[2344]: info: ------------------------------------------- Aug 29 16:40:44 volumiosr volumio[2344]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 16:40:44 volumiosr volumio[2344]: info: Adding plugin upnp to MyMusic Plugins Aug 29 16:40:44 volumiosr volumio[2344]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 16:40:44 volumiosr volumio[2344]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 16:40:44 volumiosr volumio[2344]: info: Loading plugins from folder /data/plugins/ Aug 29 16:40:44 volumiosr volumio[2344]: info: Loading plugin "system"... Aug 29 16:40:44 volumiosr volumio[2344]: info: Loading plugin "appearance"... Aug 29 16:40:44 volumiosr volumio[2344]: info: Loading plugin "network"... Aug 29 16:40:44 volumiosr volumio[2344]: info: Refreshing Cached IP Addresses Aug 29 16:40:44 volumiosr sudo[2365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 16:40:44 volumiosr sudo[2365]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:44 volumiosr sudo[2365]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:44 volumiosr sudo[2367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 16:40:44 volumiosr sudo[2367]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:44 volumiosr volumio[2344]: info: Loading plugin "services"... Aug 29 16:40:44 volumiosr sudo[2367]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:44 volumiosr volumio[2344]: info: Loading plugin "alsa_controller"... Aug 29 16:40:44 volumiosr sudo[2370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 16:40:44 volumiosr sudo[2370]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:44 volumiosr volumio[2344]: warn: Unable to locate the audio output device IQaudIO DAC Plus. Please configure a valid output device. Aug 29 16:40:44 volumiosr volumio[2344]: Invalid card number. Aug 29 16:40:44 volumiosr volumio[2344]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Aug 29 16:40:45 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 16:40:45 volumiosr volumio[2344]: Invalid card number. Aug 29 16:40:45 volumiosr volumio[2344]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 16:40:45 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "wizard"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "networkfs"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Starting Udev Watcher for removable devices Aug 29 16:40:45 volumiosr sudo[2384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Roland,password=d1spvaml,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.200/music/work /mnt/NAS/music Aug 29 16:40:45 volumiosr sudo[2384]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:45 volumiosr volumio[2344]: info: Ignoring mount for partition: boot Aug 29 16:40:45 volumiosr volumio[2344]: info: Ignoring mount for partition: volumio Aug 29 16:40:45 volumiosr volumio[2344]: info: Ignoring mount for partition: volumio_data Aug 29 16:40:45 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "volumio_command_line_client"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "upnp"... Aug 29 16:40:45 volumiosr volumio[2344]: info: [1724942445155] Starting Upmpd Daemon Aug 29 16:40:45 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "my_music"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "mpd"... Aug 29 16:40:45 volumiosr kernel: Key type cifs.spnego registered Aug 29 16:40:45 volumiosr kernel: Key type cifs.idmap registered Aug 29 16:40:45 volumiosr kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Aug 29 16:40:45 volumiosr kernel: CIFS: Attempting to mount \\10.0.0.200\music Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "upnp_browser"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "alarm-clock"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "airplay_emulation"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Starting Shairport Sync Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "last_100"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "webradio"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "i2s_dacs"... Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "volumiodiscovery"... Aug 29 16:40:45 volumiosr volumio[2344]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 16:40:45 volumiosr volumio[2344]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 16:40:45 volumiosr node[2344]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 16:40:45 volumiosr volumio[2344]: *** WARNING *** For more information see Aug 29 16:40:45 volumiosr volumio[2344]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 16:40:45 volumiosr volumio[2344]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 16:40:45 volumiosr volumio[2344]: *** WARNING *** For more information see Aug 29 16:40:45 volumiosr node[2344]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 16:40:45 volumiosr node[2344]: *** WARNING *** For more information see Aug 29 16:40:45 volumiosr node[2344]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 16:40:45 volumiosr node[2344]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 16:40:45 volumiosr node[2344]: *** WARNING *** For more information see Aug 29 16:40:45 volumiosr volumio[2344]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 16:40:45 volumiosr volumio[2344]: info: Discovery: Started advertising with name: Volumio Aug 29 16:40:45 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 16:40:45 volumiosr volumio[2344]: info: Loading plugin "autostart"... Aug 29 16:40:46 volumiosr volumio[2344]: info: Applying required configuration parameters for plugin autostart Aug 29 16:40:46 volumiosr volumio[2344]: info: AutoStart - onVolumioStart - read config.json Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading plugin "outputs"... Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading plugin "albumart"... Aug 29 16:40:46 volumiosr volumio[2344]: info: Plugin example_plugin is not enabled Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading plugin "inputs"... Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading plugin "updater_comm"... Aug 29 16:40:46 volumiosr volumio[2344]: info: Plugin mpdemulation is not enabled Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading plugin "rest_api"... Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading plugin "websocket"... Aug 29 16:40:46 volumiosr volumio[2344]: info: Starting Socket.io Server version 2.3.0 Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading plugin "backup_restore"... Aug 29 16:40:46 volumiosr volumio[2344]: Forking 3 albumart workers Aug 29 16:40:46 volumiosr volumio[2344]: info: Applying required configuration parameters for plugin backup_restore Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading plugin "touch_display"... Aug 29 16:40:46 volumiosr volumio[2344]: info: Applying required configuration parameters for plugin touch_display Aug 29 16:40:46 volumiosr volumio[2344]: info: Loading i18n strings for locale de Aug 29 16:40:46 volumiosr volumio[2344]: Updating browse sources language Aug 29 16:40:46 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 16:40:47 volumiosr volumio[2344]: Starting albumart workers Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::initPlayerControls Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: Express server listening on port 3000 Aug 29 16:40:47 volumiosr volumio[2344]: [Metrics] WebUI: 3s 902.23ms Aug 29 16:40:47 volumiosr volumio[2344]: Starting albumart workers Aug 29 16:40:47 volumiosr volumio[2344]: Starting albumart workers Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::resetVolumioState Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::getcurrentVolume Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 16:40:47 volumiosr volumio[2344]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 16:40:47 volumiosr volumio[2344]: verbose: New Socket.io Connection to 192.168.75.230 from 192.168.75.207 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 16:40:47 volumiosr volumio[2344]: verbose: New Socket.io Connection to 192.168.75.230 from 192.168.75.207 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 16:40:47 volumiosr volumio-remote-updater[620]: [2024-08-29 16:40:47] [connect] Successful connection Aug 29 16:40:47 volumiosr volumio[2344]: verbose: New Socket.io Connection to 192.168.75.230 from 192.168.75.207 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 16:40:47 volumiosr volumio[2344]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 16:40:47 volumiosr volumio[2344]: verbose: New Socket.io Connection to 192.168.75.230 from 192.168.75.207 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 16:40:47 volumiosr volumio[2344]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:40:47 volumiosr volumio[2344]: info: Executing start script for DAC IQaudIO DAC Plus Aug 29 16:40:47 volumiosr volumio[2344]: info: Reloading queue from file Aug 29 16:40:47 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:47 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 16:40:47 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:47 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: Listing playlists Aug 29 16:40:47 volumiosr volumio-remote-updater[620]: [2024-08-29 16:40:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724942447 101 Aug 29 16:40:47 volumiosr volumio[2344]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Aug 29 16:40:47 volumiosr volumio[2344]: info: VolumeController:: Volume=undefined Mute =false Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::updateTrackBlock Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrackBlock Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::setRepeat null single undefined Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::setRandom null Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:40:47 volumiosr volumio[2344]: info: Setting Device type: Raspberry PI Aug 29 16:40:47 volumiosr volumio[2344]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 29 16:40:47 volumiosr volumio[2344]: info: DAC script executed Aug 29 16:40:47 volumiosr volumio[2344]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 16:40:47 volumiosr volumio[2344]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Aug 29 16:40:47 volumiosr volumio[2344]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 16:40:47 volumiosr volumio[2344]: info: VolumeController::SetAlsaVolume0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:40:47 volumiosr volumio[2344]: info: VolumeController:: Volume=undefined Mute =false Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:40:47 volumiosr volumio[2344]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 29 16:40:47 volumiosr volumio[2344]: info: Discovery: adding 448d7900-3008-4917-8dc0-2890d0b65ac8 Aug 29 16:40:47 volumiosr volumio[2344]: info: Discovery: Found device Volumio Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:47 volumiosr volumio[2344]: info: Discovery: this is already registered, 448d7900-3008-4917-8dc0-2890d0b65ac8 Aug 29 16:40:47 volumiosr volumio[2344]: info: Discovery: Found device Volumio Aug 29 16:40:47 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:47 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:48 volumiosr sudo[2370]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreStateMachine::getcurrentVolume Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 16:40:49 volumiosr volumio[2344]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 29 16:40:49 volumiosr volumio[2344]: info: VolumeController:: Volume=undefined Mute =false Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:40:49 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreStateMachine::updateTrackBlock Aug 29 16:40:49 volumiosr volumio[2344]: info: CorePlayQueue::getTrackBlock Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 16:40:49 volumiosr volumio[2344]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 29 16:40:49 volumiosr volumio[2344]: info: VolumeController:: Volume=undefined Mute =false Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:40:49 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:49 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:40:50 volumiosr volumio[2344]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 29 16:40:50 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:50 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:51 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 16:40:51 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:51 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: Listing playlists Aug 29 16:40:51 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:51 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetQueue Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreStateMachine::getQueue Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getQueue Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 16:40:51 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:51 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: Listing playlists Aug 29 16:40:51 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:51 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetQueue Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreStateMachine::getQueue Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getQueue Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 16:40:51 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:51 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: Listing playlists Aug 29 16:40:51 volumiosr volumio[2344]: info: Received Get System Info Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 16:40:51 volumiosr volumio[2344]: info: Discovery: Getting this device information Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetQueue Aug 29 16:40:51 volumiosr volumio[2344]: info: CoreStateMachine::getQueue Aug 29 16:40:51 volumiosr volumio[2344]: info: CorePlayQueue::getQueue Aug 29 16:40:54 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 16:40:54 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 16:40:55 volumiosr sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 16:40:55 volumiosr sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr sudo[2475]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:55 volumiosr sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 16:40:55 volumiosr sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr sudo[2478]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:55 volumiosr sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 16:40:55 volumiosr sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr sudo[2481]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:55 volumiosr volumio[2344]: info: Upmpdcli Daemon Started Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 16:40:55 volumiosr sudo[2384]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:55 volumiosr volumio[2344]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Aug 29 16:40:55 volumiosr volumio[2344]: info: Completed loading Core Plugins Aug 29 16:40:55 volumiosr volumio[2344]: info: Preparing to generate the ALSA configuration file Aug 29 16:40:55 volumiosr kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 29 16:40:55 volumiosr kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Aug 29 16:40:55 volumiosr volumio[2344]: info: Asound.conf file written Aug 29 16:40:55 volumiosr sudo[2484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 29 16:40:55 volumiosr sudo[2484]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr sudo[2484]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:55 volumiosr volumio[2344]: info: Output device has changed, restarting MPD Aug 29 16:40:55 volumiosr sudo[2489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 16:40:55 volumiosr sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr volumio[2344]: info: Output device has changed, restarting Shairport Sync Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:40:55 volumiosr sudo[2489]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:55 volumiosr sudo[2491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 16:40:55 volumiosr sudo[2491]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr systemd[1]: Stopping Music Player Daemon... Aug 29 16:40:55 volumiosr volumio[2344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 16:40:55 volumiosr systemd[1]: mpd.service: Succeeded. Aug 29 16:40:55 volumiosr systemd[1]: Stopped Music Player Daemon. Aug 29 16:40:55 volumiosr volumio[2344]: info: ___________ START PLUGINS ___________ Aug 29 16:40:55 volumiosr volumio[2344]: info: ControllerMpd::onStart: Initializing MPD Aug 29 16:40:55 volumiosr volumio[2344]: info: Creating MPD Configuration file Aug 29 16:40:55 volumiosr sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 16:40:55 volumiosr sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 16:40:55 volumiosr volumio[2344]: info: [1724942455631] CoreMusicLibrary::Adding element Medienserver Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 16:40:55 volumiosr sudo[2499]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:55 volumiosr sudo[2502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 16:40:55 volumiosr systemd[1]: Starting Music Player Daemon... Aug 29 16:40:55 volumiosr sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:40:55 volumiosr systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 16:40:55 volumiosr systemd[1]: mpd.service: Succeeded. Aug 29 16:40:55 volumiosr systemd[1]: Stopped Music Player Daemon. Aug 29 16:40:55 volumiosr systemd[1]: Starting Music Player Daemon... Aug 29 16:40:55 volumiosr volumio[2344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:40:55 volumiosr sudo[2505]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 16:40:55 volumiosr sudo[2505]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:55 volumiosr volumio[2344]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 16:40:55 volumiosr volumio[2344]: info: [1724942455751] CoreMusicLibrary::Adding element Last_100 Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 16:40:55 volumiosr volumio[2344]: info: [1724942455753] CoreMusicLibrary::Adding element Webradio Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 16:40:55 volumiosr sudo[2505]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 16:40:55 volumiosr volumio[2344]: info: Initializing BBC Radios Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:40:55 volumiosr volumio[2344]: info: AutoStart - onStart Aug 29 16:40:55 volumiosr volumio[2344]: info: Loading i18n strings for locale de Aug 29 16:40:55 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 16:40:55 volumiosr volumio[2344]: info: Volumio Calling Home Aug 29 16:40:55 volumiosr sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 16:40:55 volumiosr sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:56 volumiosr sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 16:40:56 volumiosr sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:56 volumiosr sudo[2527]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:56 volumiosr systemd[1]: Reloading. Aug 29 16:40:56 volumiosr sudo[2533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 16:40:56 volumiosr sudo[2533]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:56 volumiosr volumio[2344]: info: touch_display: No backlight interface detected. Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 16:40:56 volumiosr volumio[2344]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 16:40:56 volumiosr volumio[2344]: info: MPD Permissions set Aug 29 16:40:56 volumiosr volumio[2344]: info: MPD Permissions set Aug 29 16:40:56 volumiosr volumio[2344]: info: Volumio called home Aug 29 16:40:56 volumiosr volumio[2344]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 16:40:56 volumiosr sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 16:40:56 volumiosr sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 16:40:56 volumiosr sudo[2557]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 16:40:56 volumiosr volumio[2344]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 16:40:56 volumiosr volumio[2344]: info: Starting Shairport Sync Aug 29 16:40:56 volumiosr volumio[2344]: info: Starting Shairport Sync Aug 29 16:40:56 volumiosr volumio[2344]: info: Starting Shairport Sync Aug 29 16:40:56 volumiosr sudo[2563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 16:40:56 volumiosr sudo[2563]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:56 volumiosr sudo[2560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 16:40:56 volumiosr sudo[2560]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:56 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:56 volumiosr mpd[2512]: Aug 29 16:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 16:40:56 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:56 volumiosr sudo[2564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 16:40:56 volumiosr sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:56 volumiosr systemd[1]: Started Music Player Daemon. Aug 29 16:40:56 volumiosr sudo[2491]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:56 volumiosr sudo[2502]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:56 volumiosr sudo[2530]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:56 volumiosr systemd[1]: Reloading. Aug 29 16:40:56 volumiosr volumio[2344]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 16:40:56 volumiosr volumio[2344]: error: MPD error: The expression evaluated to a falsy value: Aug 29 16:40:56 volumiosr volumio[2344]: assert.ok(self.idling) Aug 29 16:40:56 volumiosr volumio[2344]: error: The expression evaluated to a falsy value: Aug 29 16:40:56 volumiosr volumio[2344]: assert.ok(self.idling) Aug 29 16:40:57 volumiosr volumio[2344]: error: updateQueue error: null Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 29 16:40:57 volumiosr volumio[2344]: info: MPD running with PID2512 Aug 29 16:40:57 volumiosr volumio[2344]: ,establishing connection Aug 29 16:40:57 volumiosr volumio[2344]: error: MPD error: The expression evaluated to a falsy value: Aug 29 16:40:57 volumiosr volumio[2344]: assert.ok(self.idling) Aug 29 16:40:57 volumiosr volumio[2344]: error: The expression evaluated to a falsy value: Aug 29 16:40:57 volumiosr volumio[2344]: assert.ok(self.idling) Aug 29 16:40:57 volumiosr volumio[2344]: error: updateQueue error: null Aug 29 16:40:57 volumiosr systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 16:40:57 volumiosr sudo[2533]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:57 volumiosr volumio[2344]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 16:40:57 volumiosr systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 16:40:57 volumiosr systemd[1]: shairport-sync.service: Succeeded. Aug 29 16:40:57 volumiosr systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 16:40:57 volumiosr sudo[2590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 16:40:57 volumiosr sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:57 volumiosr systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 16:40:57 volumiosr sudo[2563]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:57 volumiosr sudo[2564]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:57 volumiosr sudo[2560]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:57 volumiosr volumio[2344]: info: Shairport-Sync Started Aug 29 16:40:57 volumiosr volumio[2344]: Error adding Membership: Error: addMembership EINVAL Aug 29 16:40:57 volumiosr volumio[2344]: info: Shairport-Sync Started Aug 29 16:40:57 volumiosr volumio[2344]: info: Shairport-Sync Started Aug 29 16:40:57 volumiosr sudo[2590]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:57 volumiosr volumio[2344]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 16:40:57 volumiosr volumio[2344]: info: touch_display: Volumio Kiosk started. Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:57 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:57 volumiosr volumio[2344]: info: Completed starting Core Plugins Aug 29 16:40:57 volumiosr volumio[2344]: info: ------------------------------------------- Aug 29 16:40:57 volumiosr volumio[2344]: info: ----- MyVolumio plugins startup ---- Aug 29 16:40:57 volumiosr volumio[2344]: info: ------------------------------------------- Aug 29 16:40:57 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetState Aug 29 16:40:57 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:57 volumiosr volumio[2344]: info: touch_display: X display number found: 0 Aug 29 16:40:57 volumiosr volumio[2344]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 16:40:57 volumiosr volumio[2344]: info: touch_display: X display number found: 0 Aug 29 16:40:57 volumiosr volumio[2344]: info: AutoStart - Plugin is starting Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreCommandRouter::volumioGetQueue Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreStateMachine::getQueue Aug 29 16:40:57 volumiosr volumio[2344]: info: CorePlayQueue::getQueue Aug 29 16:40:57 volumiosr volumio[2344]: info: AutoStart - start playing Aug 29 16:40:57 volumiosr volumio[2344]: info: AutoStart - start playing with no specific position Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPlay Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreStateMachine::play index 0 Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreStateMachine::stop Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreStateMachine::play index undefined Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 16:40:57 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:57 volumiosr volumio[2344]: info: CoreStateMachine::startPlaybackTimer Aug 29 16:40:57 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:40:57 volumiosr volumio[2344]: info: [1724942457836] ControllerWebradio::clearAddPlayTrack Aug 29 16:40:57 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 16:40:57 volumiosr volumio[2344]: info: sendMpdCommand stop took 1 milliseconds Aug 29 16:40:57 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 16:40:57 volumiosr volumio[2344]: info: Aug 29 16:40:57 volumiosr volumio[2344]: ---------------------------- MPD announces system playlist update Aug 29 16:40:57 volumiosr volumio[2344]: info: Ignoring MPD Status Update Aug 29 16:40:57 volumiosr volumio[2344]: info: sendMpdCommand clear took 19 milliseconds Aug 29 16:40:57 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand load "http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 29 16:40:57 volumiosr volumio[2344]: info: Aug 29 16:40:57 volumiosr volumio[2344]: ---------------------------- MPD announces system playlist update Aug 29 16:40:57 volumiosr volumio[2344]: info: Ignoring MPD Status Update Aug 29 16:40:57 volumiosr volumio[2344]: info: Aug 29 16:40:57 volumiosr volumio[2344]: ---------------------------- MPD announces system playlist update Aug 29 16:40:57 volumiosr volumio[2344]: info: Ignoring MPD Status Update Aug 29 16:40:57 volumiosr sudo[2602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 16:40:57 volumiosr sudo[2602]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:40:57 volumiosr sudo[2602]: pam_unix(sudo:session): session closed for user root Aug 29 16:40:57 volumiosr volumio[2344]: error: updateQueue error: null Aug 29 16:40:57 volumiosr volumio[2344]: info: ------------------------------ 25ms Aug 29 16:40:57 volumiosr volumio[2344]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 16:40:57 volumiosr volumio[2344]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand add "http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com" Aug 29 16:41:00 volumiosr volumio[2344]: error: updateQueue error: null Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 2237ms Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 2235ms Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces system playlist update Aug 29 16:41:00 volumiosr volumio[2344]: info: Ignoring MPD Status Update Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand add "http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com" took 3 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand play Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces system playlist update Aug 29 16:41:00 volumiosr volumio[2344]: info: Ignoring MPD Status Update Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces system playlist update Aug 29 16:41:00 volumiosr volumio[2344]: info: Ignoring MPD Status Update Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 6ms Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand play took 4 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 4ms Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 2ms Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces state update: player Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces state update: player Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces state update: player Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand status took 18 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces state update: player Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand status took 27 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand status took 27 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand clearerror took 13 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:00 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:00 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 75ms Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces state update: player Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:00 volumiosr volumio[2344]: info: Aug 29 16:41:00 volumiosr volumio[2344]: ---------------------------- MPD announces state update: player Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand status took 61 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand clearerror took 57 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand clearerror took 56 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 48 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 46 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand status took 11 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand status took 10 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:00 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:00 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:00 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:00 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 113ms Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 113ms Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 50 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 49 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 49 milliseconds Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:00 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:00 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:00 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:00 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:00 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:00 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:00 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:00 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:00 volumiosr sudo[2612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Roland,password=d1spvaml,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.200/music/work /mnt/NAS/music Aug 29 16:41:00 volumiosr sudo[2612]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 153ms Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 101ms Aug 29 16:41:00 volumiosr volumio[2344]: info: ------------------------------ 100ms Aug 29 16:41:00 volumiosr kernel: CIFS: Attempting to mount \\10.0.0.200\music Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 16:41:00 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Aug 29 16:41:01 volumiosr volumio[2344]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 29 16:41:01 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 29 16:41:04 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 16:41:05 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 29 16:41:05 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand update Aug 29 16:41:05 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:05 volumiosr volumio[2344]: info: Aug 29 16:41:05 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:05 volumiosr volumio[2344]: info: sendMpdCommand update took 4 milliseconds Aug 29 16:41:05 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:05 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:05 volumiosr volumio[2344]: info: sendMpdCommand status took 2 milliseconds Aug 29 16:41:05 volumiosr volumio[2344]: info: sendMpdCommand status took 2 milliseconds Aug 29 16:41:05 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatetrue Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: Aug 29 16:41:06 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: Aug 29 16:41:06 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: MPD Database updated - AlbumList cache refreshed Aug 29 16:41:06 volumiosr volumio[2344]: info: Aug 29 16:41:06 volumiosr volumio[2344]: ---------------------------- MPD announces state update: database Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: Aug 29 16:41:06 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 8 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 8 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 7 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 6 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 377ms Aug 29 16:41:06 volumiosr volumio[2344]: info: MPD Database updated - AlbumList cache refreshed Aug 29 16:41:06 volumiosr volumio[2344]: info: Aug 29 16:41:06 volumiosr volumio[2344]: ---------------------------- MPD announces state update: database Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: Aug 29 16:41:06 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: MPD Database updated - AlbumList cache refreshed Aug 29 16:41:06 volumiosr volumio[2344]: info: Aug 29 16:41:06 volumiosr volumio[2344]: ---------------------------- MPD announces state update: database Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: Aug 29 16:41:06 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 250 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 251 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 252 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 218 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 166 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 71 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 69 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 66 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 59 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 55 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand status took 53 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 439ms Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 438ms Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 131 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 131 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 79 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 78 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:06 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:06 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:06 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 500ms Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 501ms Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 315ms Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 312ms Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 299ms Aug 29 16:41:06 volumiosr volumio[2344]: info: ------------------------------ 295ms Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 29 16:41:06 volumiosr volumio[2344]: info: Adding plugin bluetooth to MyMusic Plugins Aug 29 16:41:06 volumiosr volumio[2344]: info: Adding plugin multiroom to MyMusic Plugins Aug 29 16:41:06 volumiosr volumio[2344]: info: Adding plugin metavolumio to MyMusic Plugins Aug 29 16:41:06 volumiosr volumio[2344]: info: Adding plugin cd_controller to MyMusic Plugins Aug 29 16:41:06 volumiosr volumio[2344]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 29 16:41:06 volumiosr volumio[2344]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 29 16:41:06 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 29 16:41:07 volumiosr volumio[2344]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 29 16:41:07 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 29 16:41:07 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:07 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:07 volumiosr volumio[2344]: info: Starting MyVolumio Remote Streaming Endpoints Aug 29 16:41:07 volumiosr volumio[2344]: info: MyVolumio login type: Token Aug 29 16:41:07 volumiosr volumio[2344]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 29 16:41:07 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 29 16:41:07 volumiosr volumio[2344]: info: Starting Streaming Service Transparent Proxy Aug 29 16:41:07 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 29 16:41:07 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 29 16:41:07 volumiosr volumio[2344]: info: Streaming services startup Aug 29 16:41:07 volumiosr volumio[2344]: info: Starting Streaming Daemon Aug 29 16:41:07 volumiosr sudo[2638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 16:41:07 volumiosr sudo[2638]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:41:07 volumiosr sudo[2638]: pam_unix(sudo:session): session closed for user root Aug 29 16:41:07 volumiosr volumio[2344]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 29 16:41:07 volumiosr volumio[2344]: error: Cannot start Volumio Streaming Daemon Aug 29 16:41:07 volumiosr volumio[2344]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 16:41:07 volumiosr volumio[2344]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 16:41:08 volumiosr volumio[2344]: error: MyVolumio Custom Token format not valid, refreshing it Aug 29 16:41:08 volumiosr volumio[2344]: STREAMING PROXY: Starting server on port 3245 Aug 29 16:41:08 volumiosr volumio[2344]: Node JS runtime: 14 Aug 29 16:41:08 volumiosr volumio[2344]: info: MyVolumio login type: Token Aug 29 16:41:08 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand rescan Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand rescan took 5 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: MPD Database updated - AlbumList cache refreshed Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: database Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 15 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 11 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 11 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 9 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 8 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 8 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:08 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:08 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:08 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:08 volumiosr volumio[2344]: info: MPD Database updated - AlbumList cache refreshed Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: database Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: MPD Database updated - AlbumList cache refreshed Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: database Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: Aug 29 16:41:08 volumiosr volumio[2344]: ---------------------------- MPD announces state update: update Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: ControllerMpd::getState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand status Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 135 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 133 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 132 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 66 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 10 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 9 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 8 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 8 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 7 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: info: sendMpdCommand status took 7 milliseconds Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:08 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:08 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:08 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:09 volumiosr volumio[2344]: info: Command Router : Notfying DB Updatefalse Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::Close All Modals sent Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::parseState Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 259ms Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 255ms Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 252ms Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 16:41:09 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 108 milliseconds Aug 29 16:41:09 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 109 milliseconds Aug 29 16:41:09 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 86 milliseconds Aug 29 16:41:09 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 86 milliseconds Aug 29 16:41:09 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 64 milliseconds Aug 29 16:41:09 volumiosr volumio[2344]: info: sendMpdCommand playlistinfo took 65 milliseconds Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:09 volumiosr volumio[2344]: verbose: ControllerMpd::parseTrackInfo Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ControllerMpd::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::servicePushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CorePlayQueue::getTrack 0 Aug 29 16:41:09 volumiosr volumio[2344]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.antenne.de/greatest-hits/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Aug 29 16:41:09 volumiosr volumio[2344]: verbose: CURRENT POSITION 0 Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState stateService pause Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::syncState currentStatus stop Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreStateMachine::pushState Aug 29 16:41:09 volumiosr volumio[2344]: info: CoreCommandRouter::volumioPushState Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 344ms Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 345ms Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 219ms Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 220ms Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 217ms Aug 29 16:41:09 volumiosr volumio[2344]: info: ------------------------------ 218ms Aug 29 16:41:09 volumiosr volumio[2344]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 29 16:41:09 volumiosr volumio[2344]: info: MyVolumio token set successfully Aug 29 16:41:09 volumiosr volumio[2344]: info: MYVOLUMIO: Adding device Aug 29 16:41:09 volumiosr volumio[2344]: info: MYVOLUMIO: Evaluating Server Aug 29 16:41:10 volumiosr volumio[2344]: info: MyVolumio status changed Aug 29 16:41:10 volumiosr volumio[2344]: info: Streaming services startup Aug 29 16:41:10 volumiosr volumio[2344]: info: Starting Streaming Daemon Aug 29 16:41:10 volumiosr volumio[2344]: info: Removing browser output: myVolumio user plan is not superstar Aug 29 16:41:10 volumiosr volumio[2344]: info: Removing audio output: Aug 29 16:41:10 volumiosr volumio[2344]: info: Stoppping Tunnel 1 Aug 29 16:41:10 volumiosr sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 16:41:10 volumiosr sudo[2676]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:41:10 volumiosr sudo[2679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 29 16:41:10 volumiosr sudo[2679]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 16:41:10 volumiosr sudo[2676]: pam_unix(sudo:session): session closed for user root Aug 29 16:41:10 volumiosr volumio[2344]: error: Cannot start Volumio Streaming Daemon Aug 29 16:41:10 volumiosr volumio[2344]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 16:41:10 volumiosr volumio[2344]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 16:41:10 volumiosr sudo[2679]: pam_unix(sudo:session): session closed for user root Aug 29 16:41:10 volumiosr volumio[2344]: info: Remote SSH Stopped Aug 29 16:41:10 volumiosr volumio[2344]: info: Setting Geolocation for MyVolumio to eu6 Aug 29 16:41:10 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:10 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:10 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:10 volumiosr sudo[2612]: pam_unix(sudo:session): session closed for user root Aug 29 16:41:10 volumiosr volumio[2344]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Aug 29 16:41:10 volumiosr kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 29 16:41:10 volumiosr kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Aug 29 16:41:10 volumiosr volumio[2344]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 29 16:41:11 volumiosr volumio[2344]: info: Updating MyVolumio device info Aug 29 16:41:11 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:11 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:11 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:11 volumiosr volumio[2344]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 29 16:41:12 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 16:41:14 volumiosr volumio[2344]: info: MYVOLUMIO: Adding device Aug 29 16:41:14 volumiosr volumio[2344]: info: MYVOLUMIO: Evaluating Server Aug 29 16:41:15 volumiosr volumio[2344]: info: Setting Geolocation for MyVolumio to eu6 Aug 29 16:41:15 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:15 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:15 volumiosr volumio[2344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 16:41:15 volumiosr volumio[2344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 16:41:15 volumiosr volumio[2344]: TypeError: Cannot read property 'replace' of undefined Aug 29 16:41:15 volumiosr volumio[2344]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Aug 29 16:41:15 volumiosr volumio[2344]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Aug 29 16:41:15 volumiosr volumio[2344]: at listOnTimeout (internal/timers.js:554:17) Aug 29 16:41:15 volumiosr volumio[2344]: at processTimers (internal/timers.js:497:7) Aug 29 16:41:15 volumiosr volumio[2344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 16:41:15 volumiosr sudo[2706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 16:40 Aug 29 16:41:15 volumiosr sudo[2706]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"