-- Logs begin at Thu 2019-02-14 15:11:59 +05, end at Tue 2025-03-18 13:17:20 +05. -- Mar 18 13:16:29 volumio systemd-timedated[1030]: Changed local time to Tue Mar 18 13:16:29 2025 Mar 18 13:16:29 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:29 volumio volumio-time-update[643]: volumio-time-update-util: System time updated successfully. Mar 18 13:16:29 volumio systemd[1]: Starting Daily apt download activities... Mar 18 13:16:29 volumio systemd[1]: Starting Daily man-db regeneration... Mar 18 13:16:29 volumio systemd[1]: Started Volumio Time Update Utility. Mar 18 13:16:29 volumio systemd[1]: man-db.service: Succeeded. Mar 18 13:16:29 volumio systemd[1]: Started Daily man-db regeneration. Mar 18 13:16:30 volumio nmbd[771]: [2025/03/18 13:16:30.766206, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 18 13:16:30 volumio nmbd[771]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 18 13:16:30 volumio systemd[1]: Started Samba NMB Daemon. Mar 18 13:16:30 volumio volumio[1006]: info: ------------------------------------------- Mar 18 13:16:30 volumio volumio[1006]: info: ----- Volumio3 ---- Mar 18 13:16:30 volumio volumio[1006]: info: ------------------------------------------- Mar 18 13:16:30 volumio volumio[1006]: info: ----- System startup ---- Mar 18 13:16:30 volumio volumio[1006]: info: ------------------------------------------- Mar 18 13:16:30 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 18 13:16:31 volumio winbindd[1062]: [2025/03/18 13:16:31.303659, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 18 13:16:31 volumio winbindd[1062]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 18 13:16:31 volumio winbindd[1062]: [2025/03/18 13:16:31.382816, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 18 13:16:31 volumio winbindd[1062]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 18 13:16:31 volumio systemd[1]: Started Samba Winbind Daemon. Mar 18 13:16:31 volumio systemd[1]: Starting Samba SMB Daemon... Mar 18 13:16:32 volumio smbd[1067]: [2025/03/18 13:16:32.293109, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 18 13:16:32 volumio systemd[1]: Started Samba SMB Daemon. Mar 18 13:16:32 volumio smbd[1067]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 18 13:16:32 volumio systemd[1]: Reached target Multi-User System. Mar 18 13:16:32 volumio systemd[1]: Reached target Graphical Interface. Mar 18 13:16:32 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 18 13:16:32 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 18 13:16:32 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 18 13:16:32 volumio volumio[1006]: info: MYVOLUMIO Environment detected Mar 18 13:16:32 volumio volumio[1006]: info: Plugin folders cleanup Mar 18 13:16:32 volumio volumio[1006]: info: Scanning into folder /volumio/app/plugins/ Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category audio_interface Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category miscellanea Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category music_service Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category plugins.json Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category system_controller Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category user_interface Mar 18 13:16:32 volumio volumio[1006]: info: Scanning into folder /data/plugins/ Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category audio_interface Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category music_service Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category system_controller Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category system_hardware Mar 18 13:16:32 volumio volumio[1006]: info: Scanning category user_interface Mar 18 13:16:32 volumio volumio[1006]: info: Plugin folders cleanup completed Mar 18 13:16:32 volumio volumio[1006]: info: ------------------------------------------- Mar 18 13:16:32 volumio volumio[1006]: info: ----- Core plugins startup ---- Mar 18 13:16:32 volumio volumio[1006]: info: ------------------------------------------- Mar 18 13:16:32 volumio volumio[1006]: info: Loading plugins from folder /volumio/app/plugins/ Mar 18 13:16:32 volumio volumio[1006]: info: Adding plugin upnp to MyMusic Plugins Mar 18 13:16:32 volumio volumio[1006]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 18 13:16:32 volumio volumio[1006]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 18 13:16:32 volumio volumio[1006]: info: Loading plugins from folder /data/plugins/ Mar 18 13:16:32 volumio volumio[1006]: info: Loading plugin "system"... Mar 18 13:16:32 volumio volumio[1006]: info: Loading plugin "appearance"... Mar 18 13:16:33 volumio systemd[1]: apt-daily.service: Succeeded. Mar 18 13:16:33 volumio systemd[1]: Started Daily apt download activities. Mar 18 13:16:33 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Mar 18 13:16:34 volumio volumio[1006]: info: Loading plugin "network"... Mar 18 13:16:34 volumio volumio[1006]: info: Refreshing Cached IP Addresses Mar 18 13:16:35 volumio sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 18 13:16:35 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:35 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:35 volumio sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 18 13:16:35 volumio sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:35 volumio sudo[1138]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:35 volumio volumio[1006]: info: Loading plugin "services"... Mar 18 13:16:35 volumio volumio[1006]: info: Loading plugin "alsa_controller"... Mar 18 13:16:35 volumio sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 18 13:16:35 volumio sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:35 volumio volumio[1006]: warn: Unable to locate the audio output device USB Advanced Audio Device. Please configure a valid output device. Mar 18 13:16:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 13:16:35 volumio volumio[1006]: info: Loading plugin "wizard"... Mar 18 13:16:35 volumio volumio[1006]: info: Loading plugin "networkfs"... Mar 18 13:16:35 volumio volumio[1006]: info: Starting Udev Watcher for removable devices Mar 18 13:16:35 volumio volumio[1006]: info: Ignoring mount for partition: boot Mar 18 13:16:35 volumio volumio[1006]: info: Ignoring mount for partition: volumio Mar 18 13:16:35 volumio volumio[1006]: info: Ignoring mount for partition: volumio_data Mar 18 13:16:35 volumio volumio[1006]: info: Mounting Device НОВЫЙ_ТОМ Mar 18 13:16:35 volumio sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/НОВЫЙ_ТОМ -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 18 13:16:35 volumio sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:35 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 18 13:16:35 volumio systemd[1]: Started Daily apt upgrade and clean activities. Mar 18 13:16:35 volumio systemd[1]: Startup finished in 13.744s (kernel) + 28.458s (userspace) = 42.202s. Mar 18 13:16:35 volumio ntfs-3g[1191]: Version 2017.3.23AR.3 integrated FUSE 28 Mar 18 13:16:35 volumio ntfs-3g[1191]: Mounted /dev/sda1 (Read-Write, label "НОВЫЙ ТОМ", NTFS 3.1) Mar 18 13:16:35 volumio ntfs-3g[1191]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 18 13:16:35 volumio ntfs-3g[1191]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Mar 18 13:16:35 volumio ntfs-3g[1191]: Global ownership and permissions enforced, configuration type 7 Mar 18 13:16:35 volumio sudo[1183]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 13:16:35 volumio volumio-remote-updater[651]: [2025-03-18 13:16:35] [connect] Successful connection Mar 18 13:16:35 volumio volumio[1006]: info: Loading plugin "volumio_command_line_client"... Mar 18 13:16:35 volumio volumio[1006]: info: Plugin upnp is not enabled Mar 18 13:16:35 volumio volumio[1006]: info: Loading plugin "my_music"... Mar 18 13:16:35 volumio volumio[1006]: info: Loading plugin "mpd"... Mar 18 13:16:35 volumio sudo[1149]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:36 volumio volumio[1006]: info: Loading plugin "upnp_browser"... Mar 18 13:16:36 volumio volumio[1006]: info: Loading plugin "alarm-clock"... Mar 18 13:16:36 volumio volumio[1006]: info: Loading plugin "airplay_emulation"... Mar 18 13:16:36 volumio volumio[1006]: info: Starting Shairport Sync Mar 18 13:16:36 volumio volumio[1006]: info: Loading plugin "last_100"... Mar 18 13:16:36 volumio volumio[1006]: info: Loading plugin "webradio"... Mar 18 13:16:37 volumio volumio[1006]: info: Loading plugin "i2s_dacs"... Mar 18 13:16:37 volumio volumio[1006]: info: I2S DAC not set, start Auto-detection Mar 18 13:16:37 volumio volumio[1006]: info: Loading plugin "volumiodiscovery"... Mar 18 13:16:37 volumio volumio[1006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 18 13:16:37 volumio volumio[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 13:16:37 volumio volumio[1006]: *** WARNING *** For more information see Mar 18 13:16:37 volumio volumio[1006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 18 13:16:37 volumio volumio[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 13:16:37 volumio node[1006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 18 13:16:37 volumio volumio[1006]: *** WARNING *** For more information see Mar 18 13:16:37 volumio node[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 13:16:37 volumio node[1006]: *** WARNING *** For more information see Mar 18 13:16:37 volumio node[1006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 18 13:16:37 volumio node[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 18 13:16:37 volumio node[1006]: *** WARNING *** For more information see Mar 18 13:16:37 volumio volumio[1006]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 18 13:16:37 volumio volumio[1006]: info: Discovery: Started advertising with name: Volumio Mar 18 13:16:37 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 18 13:16:37 volumio volumio[1006]: info: Loading plugin "autostart"... Mar 18 13:16:37 volumio volumio[1006]: info: Applying required configuration parameters for plugin autostart Mar 18 13:16:37 volumio volumio[1006]: info: AutoStart - onVolumioStart - read config.json Mar 18 13:16:37 volumio volumio[1006]: info: Loading plugin "outputs"... Mar 18 13:16:37 volumio volumio[1006]: info: Loading plugin "albumart"... Mar 18 13:16:37 volumio volumio[1006]: info: Plugin example_plugin is not enabled Mar 18 13:16:37 volumio volumio[1006]: info: Loading plugin "inputs"... Mar 18 13:16:37 volumio volumio[1006]: info: Loading plugin "updater_comm"... Mar 18 13:16:38 volumio volumio[1006]: info: Plugin mpdemulation is not enabled Mar 18 13:16:38 volumio volumio[1006]: info: Loading plugin "rest_api"... Mar 18 13:16:38 volumio volumio[1006]: info: Loading plugin "websocket"... Mar 18 13:16:38 volumio volumio[1006]: info: Starting Socket.io Server version 2.3.0 Mar 18 13:16:38 volumio volumio[1006]: info: Loading plugin "fusiondsp"... Mar 18 13:16:38 volumio volumio[1006]: Forking 3 albumart workers Mar 18 13:16:38 volumio volumio[1006]: info: Applying required configuration parameters for plugin fusiondsp Mar 18 13:16:38 volumio volumio[1006]: info: Loading plugin "yandex_music"... Mar 18 13:16:39 volumio volumio[1006]: Starting albumart workers Mar 18 13:16:39 volumio volumio[1006]: Starting albumart workers Mar 18 13:16:39 volumio volumio[1006]: Starting albumart workers Mar 18 13:16:40 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 18 13:16:40 volumio volumio[1006]: info: Applying required configuration parameters for plugin yandex_music Mar 18 13:16:40 volumio volumio[1006]: info: Plugin audiophonics_evo_sabre is not enabled Mar 18 13:16:40 volumio volumio[1006]: info: Plugin touch_display is not enabled Mar 18 13:16:40 volumio volumio[1006]: info: Loading i18n strings for locale ru Mar 18 13:16:40 volumio volumio[1006]: Updating browse sources language Mar 18 13:16:40 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::initPlayerControls Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: Express server listening on port 3000 Mar 18 13:16:41 volumio volumio[1006]: [Metrics] WebUI: 11s 959.98ms Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::resetVolumioState Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::getcurrentVolume Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioRetrievevolume Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: Volumio Network Manager: Network status updated: 2 Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:16:41 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Mar 18 13:16:41 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioRetrievevolume Mar 18 13:16:41 volumio volumio[1006]: info: Scanning new location : "USB/НОВЫЙ_ТОМ" Mar 18 13:16:41 volumio volumio[1006]: info: Reloading queue from file Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::setRepeat false single undefined Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:16:41 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::setRandom false Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:16:41 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:16:41 volumio volumio[1006]: info: Setting Device type: Raspberry PI Mar 18 13:16:41 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:16:41 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:16:41 volumio volumio[1006]: info: Completed loading Core Plugins Mar 18 13:16:41 volumio volumio[1006]: info: Preparing to generate the ALSA configuration file Mar 18 13:16:41 volumio volumio[1006]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 18 13:16:41 volumio volumio[1006]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 18 13:16:41 volumio volumio[1006]: info: Reading ALSA contributions from plugins. Mar 18 13:16:41 volumio volumio[1006]: info: Asound.conf file unchanged, so no further update is needed Mar 18 13:16:41 volumio volumio[1006]: info: Output device has changed, restarting MPD Mar 18 13:16:41 volumio volumio[1006]: info: Output device has changed, restarting Shairport Sync Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:41 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 18 13:16:41 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:41 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:41 volumio sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 18 13:16:41 volumio sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:41 volumio systemd[1]: Stopping Music Player Daemon... Mar 18 13:16:41 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 13:16:41 volumio volumio[1006]: info: ___________ START PLUGINS ___________ Mar 18 13:16:41 volumio volumio[1006]: info: ControllerMpd::onStart: Initializing MPD Mar 18 13:16:41 volumio volumio[1006]: info: Creating MPD Configuration file Mar 18 13:16:41 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 18 13:16:41 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 13:16:41 volumio volumio[1006]: info: [1742285801799] CoreMusicLibrary::Adding element Медиа-серверы Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 13:16:41 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:41 volumio sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 18 13:16:41 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:41 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 13:16:41 volumio systemd[1]: mpd.service: Succeeded. Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 13:16:41 volumio volumio[1006]: info: [1742285801938] CoreMusicLibrary::Adding element Last_100 Mar 18 13:16:41 volumio systemd[1]: Stopped Music Player Daemon. Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 13:16:41 volumio volumio[1006]: info: [1742285801948] CoreMusicLibrary::Adding element Webradio Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 13:16:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 13:16:41 volumio volumio[1006]: info: Initializing BBC Radios Mar 18 13:16:42 volumio systemd[1]: Starting Music Player Daemon... Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: AutoStart - onStart Mar 18 13:16:42 volumio volumio[1006]: info: Loading i18n strings for locale ru Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: FusionDsp - mixtype--------------------- Software Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 18 13:16:42 volumio volumio[1006]: info: [1742285802052] CoreMusicLibrary::Adding element Яндекс Музыка Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 18 13:16:42 volumio volumio[1006]: Cannot find translation for source Яндекс Музыка Mar 18 13:16:42 volumio volumio[1006]: info: Volumio Calling Home Mar 18 13:16:42 volumio sudo[1294]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 18 13:16:42 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:42 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:42 volumio volumio[1006]: info: Preparing to generate the ALSA configuration file Mar 18 13:16:42 volumio volumio[1006]: info: Discovery: adding c24a69e6-d6a2-4939-b5e7-3bf3caef7980 Mar 18 13:16:42 volumio volumio[1006]: info: Discovery: Found device Volumio Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Mar 18 13:16:42 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:16:42 volumio volumio[1006]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 18 13:16:42 volumio volumio[1006]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 18 13:16:42 volumio volumio[1006]: info: Reading ALSA contributions from plugins. Mar 18 13:16:42 volumio volumio[1006]: info: MPD Permissions set Mar 18 13:16:42 volumio volumio[1006]: info: MPD Permissions set Mar 18 13:16:42 volumio volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: Volumio called home Mar 18 13:16:42 volumio volumio[1006]: info: Starting Shairport Sync Mar 18 13:16:42 volumio volumio[1006]: info: Starting Shairport Sync Mar 18 13:16:42 volumio volumio[1006]: info: Starting Shairport Sync Mar 18 13:16:42 volumio sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 13:16:42 volumio sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:42 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 13:16:42 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:42 volumio sudo[1324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 13:16:42 volumio sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Mar 18 13:16:42 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:16:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 18 13:16:42 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 18 13:16:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 18 13:16:42 volumio volumio[1006]: info: Asound.conf file unchanged, so no further update is needed Mar 18 13:16:42 volumio volumio[1006]: info: Output device has changed, restarting MPD Mar 18 13:16:42 volumio volumio[1006]: info: Output device has changed, restarting Shairport Sync Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:42 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 18 13:16:42 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 18 13:16:42 volumio sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 18 13:16:42 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:42 volumio sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:42 volumio sudo[1319]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:42 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:42 volumio sudo[1324]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:42 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 18 13:16:42 volumio systemd[1]: mpd.service: Succeeded. Mar 18 13:16:42 volumio systemd[1]: Stopped Music Player Daemon. Mar 18 13:16:42 volumio systemd[1]: Starting Music Player Daemon... Mar 18 13:16:42 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 18 13:16:43 volumio volumio[1006]: info: MPD Permissions set Mar 18 13:16:43 volumio volumio[1006]: info: Shairport-Sync Started Mar 18 13:16:43 volumio volumio[1006]: Error adding Membership: Error: addMembership EINVAL Mar 18 13:16:43 volumio volumio[1006]: info: Shairport-Sync Started Mar 18 13:16:43 volumio volumio[1006]: info: Shairport-Sync Started Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:43 volumio sudo[1338]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:43 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:43 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:43 volumio volumio[1006]: info: Starting Shairport Sync Mar 18 13:16:43 volumio sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 18 13:16:43 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 18 13:16:43 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 18 13:16:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 18 13:16:43 volumio sh[547]: timed out Mar 18 13:16:43 volumio dhcpcd[595]: timed out Mar 18 13:16:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 18 13:16:43 volumio sh[547]: dhcpcd exited Mar 18 13:16:43 volumio dhcpcd[595]: dhcpcd exited Mar 18 13:16:43 volumio sh[547]: ifup: failed to bring up eth0 Mar 18 13:16:43 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 18 13:16:43 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 18 13:16:43 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:43 volumio volumio[1006]: info: Shairport-Sync Started Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1360, instance 1, run: true Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp service started and running in background, instance 1 Mar 18 13:16:44 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:16:44 volumio volumio[1006]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 18 13:16:44 volumio volumio[1006]: cannot open device 'volumioHw': No such device Mar 18 13:16:44 volumio volumio[1006]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Mar 18 13:16:44 volumio volumio[1006]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 18 13:16:44 volumio volumio[1006]: cannot open device 'volumioHw': No such device Mar 18 13:16:44 volumio volumio[1006]: info: FusionDsp loaded Mar 18 13:16:44 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 18 13:16:44 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 18 13:16:44 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:44 volumio volumio[1006]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 18 13:16:44 volumio volumio[1006]: info: Adding Signal Path Element [object Object] Mar 18 13:16:44 volumio volumio[1006]: info: Adding fusiondspeq DSP Signal Path Element Mar 18 13:16:44 volumio volumio[1006]: info: FusionDsp - ---- installed callbackRead Mar 18 13:16:44 volumio volumio[1006]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 18 13:16:44 volumio volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 18 13:16:44 volumio volumio[1006]: error: FusionDsp - WebSocket error: [object Object] Mar 18 13:16:44 volumio systemd[1]: Started FusionDsp Daemon. Mar 18 13:16:44 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1373, instance 1, run: true Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1380, instance 1, run: true Mar 18 13:16:44 volumio mpd[1342]: Mar 18 13:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 18 13:16:44 volumio systemd[1]: Started Music Player Daemon. Mar 18 13:16:44 volumio sudo[1285]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:44 volumio sudo[1331]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:44 volumio sudo[1276]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:44 volumio volumio[1006]: info: Completed starting Core Plugins Mar 18 13:16:44 volumio volumio[1006]: info: ------------------------------------------- Mar 18 13:16:44 volumio volumio[1006]: info: ----- MyVolumio plugins startup ---- Mar 18 13:16:44 volumio volumio[1006]: info: ------------------------------------------- Mar 18 13:16:44 volumio volumio[1006]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 18 13:16:44 volumio volumio[1006]: info: MPD running with PID1342 Mar 18 13:16:44 volumio volumio[1006]: ,establishing connection Mar 18 13:16:44 volumio volumio[1006]: error: MPD error: The expression evaluated to a falsy value: Mar 18 13:16:44 volumio volumio[1006]: assert.ok(self.idling) Mar 18 13:16:44 volumio volumio[1006]: error: The expression evaluated to a falsy value: Mar 18 13:16:44 volumio volumio[1006]: assert.ok(self.idling) Mar 18 13:16:44 volumio volumio[1006]: error: MPD error: The expression evaluated to a falsy value: Mar 18 13:16:44 volumio volumio[1006]: assert.ok(self.idling) Mar 18 13:16:44 volumio volumio[1006]: error: The expression evaluated to a falsy value: Mar 18 13:16:44 volumio volumio[1006]: assert.ok(self.idling) Mar 18 13:16:44 volumio volumio[1006]: error: MPD error: The expression evaluated to a falsy value: Mar 18 13:16:44 volumio volumio[1006]: assert.ok(self.idling) Mar 18 13:16:44 volumio volumio[1006]: error: The expression evaluated to a falsy value: Mar 18 13:16:44 volumio volumio[1006]: assert.ok(self.idling) Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1391, instance 1, run: true Mar 18 13:16:44 volumio volumio[1006]: error: updateQueue error: null Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1398, instance 1, run: true Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1405, instance 1, run: true Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1412, instance 1, run: true Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1419, instance 1, run: true Mar 18 13:16:44 volumio volumio[1006]: info: camilladsp spawned new process with pid 1426, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1433, instance 1, run: true Mar 18 13:16:45 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1446, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1463, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1470, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1478, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1485, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1492, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1499, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1506, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1513, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1520, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1527, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1534, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1541, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1548, instance 1, run: true Mar 18 13:16:45 volumio volumio[1006]: info: camilladsp spawned new process with pid 1555, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1562, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1569, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1576, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1583, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1590, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1597, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1604, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1611, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1618, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1625, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1632, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1639, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1646, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1653, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1660, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1667, instance 1, run: true Mar 18 13:16:46 volumio volumio[1006]: info: camilladsp spawned new process with pid 1674, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1681, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1688, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1695, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1702, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1709, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1716, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1723, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1730, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1737, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1744, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1751, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1758, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1765, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1772, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1779, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1786, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1793, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1800, instance 1, run: true Mar 18 13:16:47 volumio volumio[1006]: info: camilladsp spawned new process with pid 1807, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1814, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1821, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1828, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1835, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1842, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1849, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1856, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1863, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1870, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1877, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1884, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1891, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1898, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1905, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1912, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1919, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1926, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1933, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1940, instance 1, run: true Mar 18 13:16:48 volumio volumio[1006]: info: camilladsp spawned new process with pid 1947, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 1954, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 1961, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 1968, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 1975, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 1982, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 1989, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 1996, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2003, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2010, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2017, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2024, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2031, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2038, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2045, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2052, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2059, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2066, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2073, instance 1, run: true Mar 18 13:16:49 volumio volumio[1006]: info: camilladsp spawned new process with pid 2080, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2087, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2094, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2101, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2108, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2115, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2122, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2129, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2136, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2143, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2150, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2157, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2164, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2171, instance 1, run: true Mar 18 13:16:50 volumio volumio-remote-updater[651]: [2025-03-18 13:16:50] [connect] Successful connection Mar 18 13:16:50 volumio volumio-remote-updater[651]: [2025-03-18 13:16:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742285810 101 Mar 18 13:16:50 volumio volumio[1006]: 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: 3 Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2178, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2185, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2192, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2199, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2206, instance 1, run: true Mar 18 13:16:50 volumio volumio[1006]: info: camilladsp spawned new process with pid 2213, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2220, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2227, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2234, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2241, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2248, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2255, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2262, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2269, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2276, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2283, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2290, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2297, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2304, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2311, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2318, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2325, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2332, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2339, instance 1, run: true Mar 18 13:16:51 volumio volumio[1006]: info: camilladsp spawned new process with pid 2346, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2353, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2360, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2367, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2374, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2381, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2388, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2395, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2402, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2409, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2416, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2423, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2430, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2437, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2444, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2451, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2458, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2465, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2472, instance 1, run: true Mar 18 13:16:52 volumio volumio[1006]: info: camilladsp spawned new process with pid 2479, instance 1, run: true Mar 18 13:16:53 volumio volumio[1006]: info: camilladsp spawned new process with pid 2486, instance 1, run: true Mar 18 13:16:53 volumio volumio[1006]: info: camilladsp spawned new process with pid 2493, instance 1, run: true Mar 18 13:16:53 volumio volumio[1006]: info: camilladsp spawned new process with pid 2500, instance 1, run: true Mar 18 13:16:53 volumio volumio[1006]: info: camilladsp spawned new process with pid 2507, instance 1, run: true Mar 18 13:16:53 volumio volumio[1006]: info: camilladsp spawned new process with pid 2514, instance 1, run: true Mar 18 13:16:53 volumio volumio[1006]: info: camilladsp spawned new process with pid 2521, instance 1, run: true Mar 18 13:16:53 volumio volumio[1006]: info: camilladsp spawned new process with pid 2528, instance 1, run: true Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 18 13:16:53 volumio volumio[1006]: info: Adding plugin bluetooth to MyMusic Plugins Mar 18 13:16:53 volumio volumio[1006]: info: Adding plugin multiroom to MyMusic Plugins Mar 18 13:16:53 volumio volumio[1006]: info: Adding plugin metavolumio to MyMusic Plugins Mar 18 13:16:53 volumio volumio[1006]: info: Adding plugin cd_controller to MyMusic Plugins Mar 18 13:16:53 volumio volumio[1006]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 18 13:16:53 volumio volumio[1006]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 18 13:16:53 volumio volumio[1006]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 18 13:16:54 volumio volumio[1006]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 18 13:16:54 volumio volumio[1006]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 18 13:16:54 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:54 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:16:54 volumio volumio[1006]: info: Starting MyVolumio Remote Streaming Endpoints Mar 18 13:16:54 volumio volumio[1006]: info: MyVolumio login type: Token Mar 18 13:16:54 volumio volumio[1006]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 18 13:16:54 volumio volumio[1006]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 18 13:16:56 volumio volumio[1006]: info: Starting Streaming Service Transparent Proxy Mar 18 13:16:56 volumio volumio[1006]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 18 13:16:56 volumio volumio[1006]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 18 13:16:56 volumio volumio[1006]: info: Streaming services startup Mar 18 13:16:56 volumio volumio[1006]: info: Starting Streaming Daemon Mar 18 13:16:56 volumio sudo[2556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 18 13:16:56 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:16:56 volumio volumio[1006]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 18 13:16:56 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Mar 18 13:16:56 volumio volumio[1006]: error: Cannot start Volumio Streaming Daemon Mar 18 13:16:56 volumio volumio[1006]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 18 13:16:56 volumio volumio[1006]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 18 13:16:56 volumio volumio[1006]: info: camilladsp spawned new process with pid 2563, instance 1, run: true Mar 18 13:16:56 volumio volumio[1006]: info: camilladsp spawned new process with pid 2570, instance 1, run: true Mar 18 13:16:56 volumio volumio[1006]: info: camilladsp spawned new process with pid 2577, instance 1, run: true Mar 18 13:16:56 volumio volumio[1006]: info: camilladsp spawned new process with pid 2584, instance 1, run: true Mar 18 13:16:56 volumio volumio[1006]: info: camilladsp spawned new process with pid 2591, instance 1, run: true Mar 18 13:16:56 volumio volumio[1006]: info: camilladsp spawned new process with pid 2598, instance 1, run: true Mar 18 13:16:56 volumio volumio[1006]: info: camilladsp spawned new process with pid 2605, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2612, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: error: MyVolumio Custom Token format not valid, refreshing it Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2619, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2626, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2633, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2640, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2647, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2654, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2661, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2668, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2675, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2682, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2689, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: STREAMING PROXY: Starting server on port 3245 Mar 18 13:16:57 volumio volumio[1006]: Node JS runtime: 14 Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2696, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2703, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2710, instance 1, run: true Mar 18 13:16:57 volumio volumio[1006]: info: camilladsp spawned new process with pid 2717, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2724, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2731, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2738, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2745, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2752, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2759, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: MyVolumio login type: Token Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2768, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2776, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2783, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2790, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2797, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2804, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2811, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2818, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2825, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2832, instance 1, run: true Mar 18 13:16:58 volumio volumio[1006]: info: camilladsp spawned new process with pid 2839, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2846, instance 1, run: true Mar 18 13:16:59 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2855, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2862, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2869, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2876, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2883, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2890, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2897, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2904, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2911, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2918, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2925, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2932, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2939, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2946, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2953, instance 1, run: true Mar 18 13:16:59 volumio volumio[1006]: info: camilladsp spawned new process with pid 2960, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 2967, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 2974, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 2981, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 2990, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 2997, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: MyVolumio token set successfully Mar 18 13:17:00 volumio volumio[1006]: info: MYVOLUMIO: Adding device Mar 18 13:17:00 volumio volumio[1006]: info: MYVOLUMIO: Evaluating Server Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 3004, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 3011, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 3018, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 3025, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: MyVolumio status changed Mar 18 13:17:00 volumio volumio[1006]: info: Streaming services startup Mar 18 13:17:00 volumio volumio[1006]: info: Starting Streaming Daemon Mar 18 13:17:00 volumio volumio[1006]: info: Removing browser output: myVolumio user plan is not superstar Mar 18 13:17:00 volumio volumio[1006]: info: Removing audio output: Mar 18 13:17:00 volumio volumio[1006]: info: Stoppping Tunnel 1 Mar 18 13:17:00 volumio sudo[3048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 18 13:17:00 volumio sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:17:00 volumio sudo[3050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 18 13:17:00 volumio sudo[3050]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 3053, instance 1, run: true Mar 18 13:17:00 volumio sudo[3048]: pam_unix(sudo:session): session closed for user root Mar 18 13:17:00 volumio volumio[1006]: error: Cannot start Volumio Streaming Daemon Mar 18 13:17:00 volumio volumio[1006]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 18 13:17:00 volumio volumio[1006]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 18 13:17:00 volumio sudo[3050]: pam_unix(sudo:session): session closed for user root Mar 18 13:17:00 volumio volumio[1006]: info: Remote SSH Stopped Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 3060, instance 1, run: true Mar 18 13:17:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 3067, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3074, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3081, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3088, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3095, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: Setting Geolocation for MyVolumio to eu6 Mar 18 13:17:01 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3102, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3109, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3116, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3123, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3130, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3137, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3144, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3151, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3158, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3165, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3172, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3179, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3186, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3193, instance 1, run: true Mar 18 13:17:01 volumio volumio[1006]: info: camilladsp spawned new process with pid 3200, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: AutoStart - Plugin is starting Mar 18 13:17:02 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:02 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:02 volumio volumio[1006]: info: AutoStart - start playing Mar 18 13:17:02 volumio volumio[1006]: info: AutoStart - start playing with no specific position Mar 18 13:17:02 volumio volumio[1006]: info: CoreCommandRouter::volumioPlay Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::play index 0 Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::stop Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::play index undefined Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 18 13:17:02 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::startPlaybackTimer Mar 18 13:17:02 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:02 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Mar 18 13:17:02 volumio volumio[1006]: info: sendMpdCommand stop took 3 milliseconds Mar 18 13:17:02 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: sendMpdCommand clear took 4 milliseconds Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3207, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: error: updateQueue error: null Mar 18 13:17:02 volumio volumio[1006]: error: updateQueue error: null Mar 18 13:17:02 volumio volumio[1006]: error: updateQueue error: null Mar 18 13:17:02 volumio volumio[1006]: error: updateQueue error: null Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 47ms Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 30ms Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 28ms Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 27ms Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3214, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3221, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: Updating MyVolumio device info Mar 18 13:17:02 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:02 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:02 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3228, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3235, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3242, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac" Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac" took 4 milliseconds Mar 18 13:17:02 volumio volumio[1006]: verbose: MPD COMMAND [object Object] Mar 18 13:17:02 volumio volumio[1006]: verbose: MPD COMMAND [object Object] Mar 18 13:17:02 volumio volumio[1006]: verbose: MPD COMMAND [object Object] Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 13ms Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 8ms Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 7ms Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 6ms Mar 18 13:17:02 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 18 13:17:02 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: Mar 18 13:17:02 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Mar 18 13:17:02 volumio volumio[1006]: info: Ignoring MPD Status Update Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3252, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 28ms Mar 18 13:17:02 volumio volumio[1006]: info: sendMpdCommand play took 26 milliseconds Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 25ms Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 23ms Mar 18 13:17:02 volumio volumio[1006]: info: ------------------------------ 22ms Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3259, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3266, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3273, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3280, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3287, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3294, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3301, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3308, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3315, instance 1, run: true Mar 18 13:17:02 volumio volumio[1006]: info: camilladsp spawned new process with pid 3322, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3329, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3336, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3343, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3350, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3357, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3364, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3371, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3378, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3385, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3392, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3399, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3406, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: Mar 18 13:17:03 volumio volumio[1006]: ---------------------------- MPD announces state update: player Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::getState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Mar 18 13:17:03 volumio volumio[1006]: info: Mar 18 13:17:03 volumio volumio[1006]: ---------------------------- MPD announces state update: player Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::getState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Mar 18 13:17:03 volumio volumio[1006]: info: Mar 18 13:17:03 volumio volumio[1006]: ---------------------------- MPD announces state update: player Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::getState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Mar 18 13:17:03 volumio volumio[1006]: info: Mar 18 13:17:03 volumio volumio[1006]: ---------------------------- MPD announces state update: player Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::getState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp spawned new process with pid 3416, instance 1, run: true Mar 18 13:17:03 volumio volumio[1006]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 18 13:17:03 volumio volumio[1006]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp stopping service pid 3416... Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp service terminated, instance 1 Mar 18 13:17:03 volumio volumio[1006]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 18 13:17:03 volumio volumio[1006]: info: camilladsp service started and running in background, instance 1 Mar 18 13:17:03 volumio volumio[1006]: info: Mar 18 13:17:03 volumio volumio[1006]: ---------------------------- MPD announces state update: player Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand status took 71 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::getState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand status took 70 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand status took 68 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand status took 65 milliseconds Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 13:17:03 volumio volumio[1006]: info: Mar 18 13:17:03 volumio volumio[1006]: ---------------------------- MPD announces state update: player Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::getState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Mar 18 13:17:03 volumio volumio[1006]: info: Mar 18 13:17:03 volumio volumio[1006]: ---------------------------- MPD announces state update: player Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::getState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Mar 18 13:17:03 volumio volumio[1006]: info: Mar 18 13:17:03 volumio volumio[1006]: ---------------------------- MPD announces state update: player Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::getState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand status took 21 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand status took 13 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand status took 12 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand status took 11 milliseconds Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseState Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:03 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mystic Traveller","artist":"Velvet Dreamer","album":"Last Kiss of Summer","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac","trackType":"flac"} Mar 18 13:17:03 volumio volumio[1006]: verbose: CURRENT POSITION 0 Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:03 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mystic Traveller","artist":"Velvet Dreamer","album":"Last Kiss of Summer","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac","trackType":"flac"} Mar 18 13:17:03 volumio volumio[1006]: verbose: CURRENT POSITION 0 Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Mar 18 13:17:03 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:03 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mystic Traveller","artist":"Velvet Dreamer","album":"Last Kiss of Summer","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac","trackType":"flac"} Mar 18 13:17:03 volumio volumio[1006]: verbose: CURRENT POSITION 0 Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Mar 18 13:17:03 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:03 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mystic Traveller","artist":"Velvet Dreamer","album":"Last Kiss of Summer","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac","trackType":"flac"} Mar 18 13:17:03 volumio volumio[1006]: verbose: CURRENT POSITION 0 Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Mar 18 13:17:03 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: ------------------------------ 143ms Mar 18 13:17:03 volumio volumio[1006]: info: ------------------------------ 162ms Mar 18 13:17:03 volumio volumio[1006]: info: ------------------------------ 161ms Mar 18 13:17:03 volumio volumio[1006]: info: ------------------------------ 158ms Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 83 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 78 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 18 13:17:03 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 78 milliseconds Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Mar 18 13:17:03 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:03 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mystic Traveller","artist":"Velvet Dreamer","album":"Last Kiss of Summer","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac","trackType":"flac"} Mar 18 13:17:03 volumio volumio[1006]: verbose: CURRENT POSITION 0 Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Mar 18 13:17:03 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:03 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mystic Traveller","artist":"Velvet Dreamer","album":"Last Kiss of Summer","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac","trackType":"flac"} Mar 18 13:17:03 volumio volumio[1006]: verbose: CURRENT POSITION 0 Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Mar 18 13:17:03 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:03 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mystic Traveller","artist":"Velvet Dreamer","album":"Last Kiss of Summer","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac","trackType":"flac"} Mar 18 13:17:03 volumio volumio[1006]: verbose: CURRENT POSITION 0 Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Mar 18 13:17:03 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: ControllerMpd::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Mar 18 13:17:03 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mystic Traveller","artist":"Velvet Dreamer","album":"Last Kiss of Summer","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-71.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De9af901b32dbef79c7d914719ce42e38950df72774a4622494432436f8c8e997%2Clid%3D178%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67e6ec8d%2F0%2F13243874%2F99cf5aa7.64370110.12.13225784%2Fflac&ext=.flac","trackType":"flac"} Mar 18 13:17:03 volumio volumio[1006]: verbose: CURRENT POSITION 0 Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Mar 18 13:17:03 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::pushState Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Mar 18 13:17:03 volumio volumio[1006]: info: ------------------------------ 176ms Mar 18 13:17:03 volumio volumio[1006]: info: ------------------------------ 165ms Mar 18 13:17:03 volumio volumio[1006]: info: ------------------------------ 165ms Mar 18 13:17:03 volumio volumio[1006]: info: ------------------------------ 164ms Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:03 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Mar 18 13:17:04 volumio volumio[1006]: info: CoreStateMachine::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: CorePlayQueue::getQueue Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3419, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3426, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3433, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3440, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3447, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3454, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3461, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3468, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3475, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3482, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3489, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3496, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3503, instance 1, run: true Mar 18 13:17:04 volumio volumio[1006]: info: camilladsp spawned new process with pid 3510, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3517, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3524, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3531, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3538, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3559, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3566, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3573, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3580, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3587, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3594, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3601, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3608, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3615, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3622, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: MYVOLUMIO: Adding device Mar 18 13:17:05 volumio volumio[1006]: info: MYVOLUMIO: Evaluating Server Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3629, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3636, instance 1, run: true Mar 18 13:17:05 volumio volumio[1006]: info: camilladsp spawned new process with pid 3643, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3650, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3657, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3664, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3686, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3693, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3700, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3707, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3714, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3721, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: Setting Geolocation for MyVolumio to eu10 Mar 18 13:17:06 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:06 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:06 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3728, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3735, instance 1, run: true Mar 18 13:17:06 volumio volumio[1006]: info: camilladsp spawned new process with pid 3742, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3749, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3756, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3763, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3770, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3777, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3784, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3791, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3798, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3805, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3812, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3819, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3826, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3833, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3840, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3847, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: Updating MyVolumio device info Mar 18 13:17:07 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:07 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3854, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3861, instance 1, run: true Mar 18 13:17:07 volumio volumio[1006]: info: camilladsp spawned new process with pid 3868, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3875, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3882, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3889, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3896, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3903, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3910, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3917, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3924, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3931, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3938, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3945, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3952, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3959, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3966, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3973, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3980, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3987, instance 1, run: true Mar 18 13:17:08 volumio volumio[1006]: info: camilladsp spawned new process with pid 3994, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4001, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4008, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4015, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4022, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4029, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4036, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4043, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4050, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4057, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4064, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4071, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4078, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4085, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4092, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 18 13:17:09 volumio volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4099, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4106, instance 1, run: true Mar 18 13:17:09 volumio volumio[1006]: info: camilladsp spawned new process with pid 4113, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4120, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4127, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4134, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4141, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4148, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4155, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4162, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4169, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4176, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4183, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4190, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4197, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4204, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4211, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4218, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4225, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4232, instance 1, run: true Mar 18 13:17:10 volumio volumio[1006]: info: camilladsp spawned new process with pid 4239, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4246, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4253, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4260, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4267, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4274, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4281, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4288, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4295, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4302, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4309, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4316, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4323, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4330, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4337, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4344, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4351, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4358, instance 1, run: true Mar 18 13:17:11 volumio volumio[1006]: info: camilladsp spawned new process with pid 4365, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4372, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 13:17:12 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:12 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 18 13:17:12 volumio volumio[1006]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4381, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4388, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4395, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4402, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4409, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4416, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4423, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4430, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4437, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4444, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4451, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4458, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4465, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4472, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4479, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4486, instance 1, run: true Mar 18 13:17:12 volumio volumio[1006]: info: camilladsp spawned new process with pid 4506, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4531, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4545, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4552, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4559, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4566, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4573, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4580, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4587, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4594, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4601, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4608, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4615, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4622, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4629, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4636, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4643, instance 1, run: true Mar 18 13:17:13 volumio volumio[1006]: info: camilladsp spawned new process with pid 4650, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4657, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4664, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4671, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4678, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4685, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4692, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4699, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4706, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4713, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4720, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4727, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4734, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4741, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4748, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4755, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:17:14 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:17:14 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 18 13:17:14 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 18 13:17:14 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 18 13:17:14 volumio volumio[1006]: info: CoreCommandRouter::volumioGetBrowseSources Mar 18 13:17:14 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4762, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4769, instance 1, run: true Mar 18 13:17:14 volumio volumio[1006]: info: camilladsp spawned new process with pid 4776, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4783, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4790, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4797, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4804, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4811, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4822, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4839, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4846, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4853, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4860, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4867, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4874, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4881, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4888, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4895, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4902, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4909, instance 1, run: true Mar 18 13:17:15 volumio volumio[1006]: info: camilladsp spawned new process with pid 4916, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4923, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4930, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4937, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4944, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4951, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4958, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4965, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4972, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4979, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 18 13:17:16 volumio volumio[1006]: info: Completed starting MyVolumio Plugin Mar 18 13:17:16 volumio volumio[1006]: [Metrics] CommandRouter: 45s 695.14ms Mar 18 13:17:16 volumio volumio[1006]: info: CoreCommandRouter::volumiosetStartupVolume Mar 18 13:17:16 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 18 13:17:16 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 18 13:17:16 volumio volumio[1006]: info: CoreCommandRouter::Close All Modals sent Mar 18 13:17:16 volumio volumio[1006]: info: CoreCommandRouter::Close All Modals sent Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4988, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 18 13:17:16 volumio volumio[1006]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 18 13:17:16 volumio volumio[1006]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 18 13:17:16 volumio volumio[1006]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 18 13:17:16 volumio volumio[1006]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 18 13:17:16 volumio volumio[1006]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 4996, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 5003, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 5010, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 5017, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 5024, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 5031, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 5038, instance 1, run: true Mar 18 13:17:16 volumio volumio[1006]: info: camilladsp spawned new process with pid 5045, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5052, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5059, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5066, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5073, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5080, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5087, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5094, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5101, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5108, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 18 13:17:17 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 18 13:17:17 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5117, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5124, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5131, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5138, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5145, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5152, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5159, instance 1, run: true Mar 18 13:17:17 volumio volumio[1006]: info: camilladsp spawned new process with pid 5166, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5173, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5180, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5187, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5194, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5201, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5208, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5215, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5225, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5232, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5239, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5246, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5253, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5260, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5267, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5274, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5281, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5288, instance 1, run: true Mar 18 13:17:18 volumio volumio[1006]: info: camilladsp spawned new process with pid 5295, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5302, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5309, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5316, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5323, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5330, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5337, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5344, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5351, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5358, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5365, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5372, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5379, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5386, instance 1, run: true Mar 18 13:17:19 volumio volumio-remote-updater[651]: No test mode Mar 18 13:17:19 volumio volumio-remote-updater[651]: No alpha test mode Mar 18 13:17:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5394, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5401, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5408, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: info: camilladsp spawned new process with pid 5415, instance 1, run: true Mar 18 13:17:19 volumio volumio[1006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 13:17:19 volumio volumio[1006]: Error: read ECONNRESET Mar 18 13:17:19 volumio volumio[1006]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Mar 18 13:17:19 volumio volumio[1006]: errno: -104, Mar 18 13:17:19 volumio volumio[1006]: code: 'ECONNRESET', Mar 18 13:17:19 volumio volumio[1006]: syscall: 'read' Mar 18 13:17:19 volumio volumio[1006]: } Mar 18 13:17:19 volumio volumio[1006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 18 13:17:20 volumio ntpd[825]: Soliciting pool server 162.159.200.1 Mar 18 13:17:20 volumio ntpd[825]: Soliciting pool server 92.241.18.100 Mar 18 13:17:20 volumio sudo[5431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 13:16 Mar 18 13:17:20 volumio sudo[5431]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"