-- Logs begin at Thu 2024-05-09 01:31:39 KST, end at Thu 2024-05-09 01:33:02 KST. -- May 09 01:32:00 volumio wireless.js[643]: trying... May 09 01:32:00 volumio sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 09 01:32:00 volumio sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:00 volumio sudo[988]: pam_unix(sudo:session): session closed for user root May 09 01:32:00 volumio wireless.js[643]: Connected to: ----banpo5G May 09 01:32:00 volumio wireless.js[643]: ---- May 09 01:32:00 volumio sudo[991]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:32:00 volumio sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:00 volumio sudo[991]: pam_unix(sudo:session): session closed for user root May 09 01:32:00 volumio wireless.js[643]: ... joined AP, wlan0 IPv4 is 192.168.0.98, ipV6 is undefined May 09 01:32:00 volumio wireless.js[643]: It's done! AP May 09 01:32:00 volumio systemd[1]: Started Wireless Services. May 09 01:32:00 volumio systemd[1]: Started Volumio Backend Module. May 09 01:32:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 09 01:32:00 volumio systemd[1]: Started Volumio Cpu Tweaker. May 09 01:32:00 volumio volumio-cpu-tweak[997]: Setting RT Priority for mpd May 09 01:32:00 volumio volumio-cpu-tweak[997]: pid 35's current scheduling policy: SCHED_OTHER May 09 01:32:00 volumio volumio-cpu-tweak[997]: pid 35's current scheduling priority: 0 May 09 01:32:00 volumio volumio-cpu-tweak[997]: Setting MPD Affinity May 09 01:32:00 volumio volumio-cpu-tweak[997]: pid 3's current affinity mask: f May 09 01:32:00 volumio volumio-cpu-tweak[997]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 09 01:32:00 volumio volumio-cpu-tweak[997]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative May 09 01:32:00 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 09 01:32:00 volumio volumio[996]: info: ------------------------------------------- May 09 01:32:00 volumio volumio[996]: info: ----- Volumio3 ---- May 09 01:32:00 volumio volumio[996]: info: ------------------------------------------- May 09 01:32:00 volumio volumio[996]: info: ----- System startup ---- May 09 01:32:00 volumio volumio[996]: info: ------------------------------------------- May 09 01:32:01 volumio ntpd[816]: Listen normally on 3 wlan0 192.168.0.98:123 May 09 01:32:01 volumio ntpd[816]: new interface(s) found: waking up resolver May 09 01:32:01 volumio volumio[996]: info: MYVOLUMIO Environment detected May 09 01:32:01 volumio volumio[996]: info: Plugin folders cleanup May 09 01:32:01 volumio volumio[996]: info: Scanning into folder /volumio/app/plugins/ May 09 01:32:01 volumio volumio[996]: info: Scanning category audio_interface May 09 01:32:01 volumio volumio[996]: info: Scanning category miscellanea May 09 01:32:01 volumio volumio[996]: info: Scanning category music_service May 09 01:32:01 volumio volumio[996]: info: Scanning category plugins.json May 09 01:32:01 volumio volumio[996]: info: Scanning category system_controller May 09 01:32:01 volumio volumio[996]: info: Scanning category user_interface May 09 01:32:01 volumio volumio[996]: info: Scanning into folder /data/plugins/ May 09 01:32:01 volumio volumio[996]: info: Scanning category audio_interface May 09 01:32:01 volumio volumio[996]: info: Scanning category music_service May 09 01:32:01 volumio volumio[996]: info: Scanning category system_controller May 09 01:32:01 volumio volumio[996]: info: Scanning category user_interface May 09 01:32:01 volumio volumio[996]: info: Plugin folders cleanup completed May 09 01:32:01 volumio volumio[996]: info: ------------------------------------------- May 09 01:32:01 volumio volumio[996]: info: ----- Core plugins startup ---- May 09 01:32:01 volumio volumio[996]: info: ------------------------------------------- May 09 01:32:01 volumio volumio[996]: info: Loading plugins from folder /volumio/app/plugins/ May 09 01:32:01 volumio volumio[996]: info: Adding plugin upnp to MyMusic Plugins May 09 01:32:01 volumio volumio[996]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 01:32:01 volumio volumio[996]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 01:32:01 volumio volumio[996]: info: Loading plugins from folder /data/plugins/ May 09 01:32:01 volumio volumio[996]: info: Loading plugin "system"... May 09 01:32:01 volumio volumio[996]: info: Loading plugin "appearance"... May 09 01:32:01 volumio volumio[996]: info: Loading plugin "network"... May 09 01:32:01 volumio volumio[996]: info: Refreshing Cached IP Addresses May 09 01:32:01 volumio sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:32:01 volumio sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:01 volumio volumio[996]: info: Loading plugin "services"... May 09 01:32:01 volumio sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:32:01 volumio sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:01 volumio sudo[1027]: pam_unix(sudo:session): session closed for user root May 09 01:32:01 volumio volumio[996]: info: Loading plugin "alsa_controller"... May 09 01:32:01 volumio sudo[1029]: pam_unix(sudo:session): session closed for user root May 09 01:32:01 volumio sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 09 01:32:01 volumio sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:01 volumio volumio[996]: warn: Unable to locate the audio output device HiFiBerry DAC Plus. Please configure a valid output device. May 09 01:32:01 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:32:01 volumio volumio[996]: info: Loading plugin "wizard"... May 09 01:32:01 volumio volumio[996]: info: Loading plugin "networkfs"... May 09 01:32:01 volumio volumio[996]: info: Starting Udev Watcher for removable devices May 09 01:32:01 volumio volumio[996]: info: Ignoring mount for partition: bootfs May 09 01:32:01 volumio volumio[996]: info: Ignoring mount for partition: rootfs May 09 01:32:01 volumio volumio[996]: info: Ignoring mount for partition: boot May 09 01:32:01 volumio volumio[996]: info: Ignoring mount for partition: volumio May 09 01:32:01 volumio volumio[996]: info: Ignoring mount for partition: volumio_data May 09 01:32:01 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:32:01 volumio volumio[996]: info: Loading plugin "volumio_command_line_client"... May 09 01:32:01 volumio volumio[996]: info: Plugin upnp is not enabled May 09 01:32:01 volumio volumio[996]: info: Loading plugin "my_music"... May 09 01:32:01 volumio volumio[996]: info: Loading plugin "mpd"... May 09 01:32:02 volumio volumio[996]: info: Plugin upnp_browser is not enabled May 09 01:32:02 volumio volumio[996]: info: Loading plugin "alarm-clock"... May 09 01:32:02 volumio volumio[996]: info: Plugin airplay_emulation is not enabled May 09 01:32:02 volumio volumio[996]: info: Loading plugin "last_100"... May 09 01:32:02 volumio volumio[996]: info: Loading plugin "webradio"... May 09 01:32:02 volumio volumio[996]: info: Loading plugin "i2s_dacs"... May 09 01:32:02 volumio volumio[996]: info: Loading plugin "volumiodiscovery"... May 09 01:32:02 volumio volumio[996]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:32:02 volumio node[996]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 01:32:02 volumio node[996]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:32:02 volumio volumio[996]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:32:02 volumio volumio[996]: *** WARNING *** For more information see May 09 01:32:02 volumio volumio[996]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:32:02 volumio volumio[996]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:32:02 volumio volumio[996]: *** WARNING *** For more information see May 09 01:32:02 volumio node[996]: *** WARNING *** For more information see May 09 01:32:02 volumio node[996]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 01:32:02 volumio node[996]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 01:32:02 volumio node[996]: *** WARNING *** For more information see May 09 01:32:02 volumio volumio[996]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 01:32:02 volumio volumio[996]: info: Discovery: Started advertising with name: Volumio May 09 01:32:02 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 01:32:02 volumio volumio[996]: info: Loading plugin "autostart"... May 09 01:32:02 volumio volumio[996]: info: Applying required configuration parameters for plugin autostart May 09 01:32:02 volumio volumio[996]: info: AutoStart - onVolumioStart - read config.json May 09 01:32:02 volumio volumio[996]: info: Loading plugin "outputs"... May 09 01:32:02 volumio volumio[996]: info: Loading plugin "albumart"... May 09 01:32:02 volumio volumio[996]: info: Plugin example_plugin is not enabled May 09 01:32:02 volumio volumio[996]: info: Loading plugin "inputs"... May 09 01:32:02 volumio volumio[996]: info: Loading plugin "updater_comm"... May 09 01:32:02 volumio volumio[996]: info: Plugin mpdemulation is not enabled May 09 01:32:02 volumio volumio[996]: info: Loading plugin "rest_api"... May 09 01:32:02 volumio volumio[996]: info: Loading plugin "websocket"... May 09 01:32:02 volumio volumio[996]: info: Loading plugin "fusiondsp"... May 09 01:32:02 volumio volumio[996]: Forking 3 albumart workers May 09 01:32:02 volumio volumio[996]: Starting albumart workers May 09 01:32:02 volumio volumio[996]: Starting albumart workers May 09 01:32:02 volumio volumio[996]: Starting albumart workers May 09 01:32:02 volumio volumio[996]: info: Applying required configuration parameters for plugin fusiondsp May 09 01:32:02 volumio volumio[996]: info: Loading plugin "personal_radio"... May 09 01:32:03 volumio volumio[996]: info: ControllerPersonalRadio::constructor May 09 01:32:03 volumio volumio[996]: info: Loading plugin "audiophonicsonoff"... May 09 01:32:03 volumio volumio[996]: info: Applying required configuration parameters for plugin audiophonicsonoff May 09 01:32:03 volumio volumio[996]: info: Audiophonics on/off initiated May 09 01:32:03 volumio volumio[996]: info: Loading plugin "backup_restore"... May 09 01:32:03 volumio volumio[996]: info: Applying required configuration parameters for plugin backup_restore May 09 01:32:03 volumio volumio[996]: info: Loading plugin "Systeminfo"... May 09 01:32:03 volumio volumio[996]: info: Loading i18n strings for locale ko May 09 01:32:03 volumio volumio[996]: Updating browse sources language May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::initPlayerControls May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 01:32:03 volumio volumio[996]: Express server listening on port 3000 May 09 01:32:03 volumio volumio[996]: [Metrics] WebUI: 3s 20.13ms May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::resetVolumioState May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::getcurrentVolume May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:32:03 volumio volumio[996]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:32:03 volumio volumio[996]: info: VolumeController:: Volume=undefined Mute =false May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:03 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::updateTrackBlock May 09 01:32:03 volumio volumio[996]: info: CorePlayQueue::getTrackBlock May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::volumioRetrievevolume May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:03 volumio volumio[996]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::setRepeat true single undefined May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:03 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::setRandom true May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:03 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:03 volumio volumio[996]: info: Setting Device type: Raspberry PI May 09 01:32:03 volumio volumio[996]: info: VolumeController:: Volume=undefined Mute =false May 09 01:32:03 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:03 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:03 volumio volumio[996]: info: Discovery: adding 1f8966a6-04ba-47db-aeca-a33edc5b5928 May 09 01:32:03 volumio volumio[996]: info: Discovery: Found device Volumio May 09 01:32:03 volumio volumio[996]: info: CoreCommandRouter::volumioGetState May 09 01:32:03 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:03 volumio nmbd[768]: [2024/05/09 01:32:03.888176, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 09 01:32:03 volumio nmbd[768]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 09 01:32:03 volumio systemd[1]: Started Samba NMB Daemon. May 09 01:32:03 volumio systemd[1]: Starting Samba Winbind Daemon... May 09 01:32:04 volumio winbindd[1113]: [2024/05/09 01:32:04.029367, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 09 01:32:04 volumio winbindd[1113]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 09 01:32:04 volumio winbindd[1113]: [2024/05/09 01:32:04.044315, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 09 01:32:04 volumio winbindd[1113]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 09 01:32:04 volumio systemd[1]: Started Samba Winbind Daemon. May 09 01:32:04 volumio systemd[1]: Starting Samba SMB Daemon... May 09 01:32:04 volumio smbd[1118]: [2024/05/09 01:32:04.236018, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 09 01:32:04 volumio smbd[1118]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 09 01:32:04 volumio systemd[1]: Started Samba SMB Daemon. May 09 01:32:04 volumio systemd[1]: Reached target Multi-User System. May 09 01:32:04 volumio systemd[1]: Reached target Graphical Interface. May 09 01:32:04 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 09 01:32:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 09 01:32:04 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 09 01:32:04 volumio systemd[1]: Startup finished in 7.001s (kernel) + 25.171s (userspace) = 32.173s. May 09 01:32:05 volumio volumio-remote-updater[644]: [2024-05-09 01:32:05] [connect] Successful connection May 09 01:32:05 volumio volumio-remote-updater[644]: [2024-05-09 01:32:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715185925 101 May 09 01:32:05 volumio volumio[996]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 09 01:32:05 volumio sudo[1032]: pam_unix(sudo:session): session closed for user root May 09 01:32:11 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 09 01:32:12 volumio sh[569]: timed out May 09 01:32:12 volumio dhcpcd[616]: timed out May 09 01:32:12 volumio sh[569]: dhcpcd exited May 09 01:32:12 volumio dhcpcd[616]: dhcpcd exited May 09 01:32:12 volumio sh[569]: ifup: failed to bring up eth0 May 09 01:32:12 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 09 01:32:12 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 09 01:32:13 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 09 01:32:25 volumio sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:32:25 volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:25 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root May 09 01:32:25 volumio sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:32:25 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:25 volumio sudo[1133]: pam_unix(sudo:session): session closed for user root May 09 01:32:25 volumio volumio[996]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.99 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/124.0.6367.111 Mobile/15E148 Safari/604.1 Total Clients: 2 May 09 01:32:25 volumio volumio[996]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:32:25 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:32:25 volumio volumio[996]: info: CoreCommandRouter::volumioGetState May 09 01:32:25 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:25 volumio volumio[996]: info: CoreCommandRouter::volumioGetQueue May 09 01:32:25 volumio volumio[996]: info: CoreStateMachine::getQueue May 09 01:32:25 volumio volumio[996]: info: CorePlayQueue::getQueue May 09 01:32:25 volumio volumio[996]: info: Listing playlists May 09 01:32:25 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 09 01:32:25 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 09 01:32:25 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 09 01:32:25 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:32:27 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 01:32:30 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 01:32:31 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 09 01:32:33 volumio volumio[996]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion May 09 01:32:33 volumio volumio[996]: info: Preparing to generate the ALSA configuration file May 09 01:32:33 volumio volumio[996]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 01:32:33 volumio volumio[996]: info: Reading ALSA contributions from plugins. May 09 01:32:33 volumio volumio[996]: info: Asound.conf file unchanged, so no further update is needed May 09 01:32:33 volumio volumio[996]: info: Output device has changed, restarting MPD May 09 01:32:33 volumio volumio[996]: info: ___________ START PLUGINS ___________ May 09 01:32:33 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 01:32:33 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:33 volumio volumio[996]: info: ControllerMpd::onStart: Initializing MPD May 09 01:32:33 volumio volumio[996]: info: Creating MPD Configuration file May 09 01:32:33 volumio sudo[1137]: pam_unix(sudo:session): session closed for user root May 09 01:32:33 volumio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 01:32:33 volumio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:33 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 01:32:33 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 01:32:33 volumio volumio[996]: info: [1715185953633] CoreMusicLibrary::Adding element Last_100 May 09 01:32:33 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 01:32:33 volumio volumio[996]: info: [1715185953636] CoreMusicLibrary::Adding element Webradio May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:32:33 volumio sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 01:32:33 volumio sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:33 volumio volumio[996]: info: AutoStart - onStart May 09 01:32:33 volumio volumio[996]: info: Loading i18n strings for locale ko May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: FusionDsp - mixtype--------------------- undefined May 09 01:32:33 volumio systemd[1]: Listening on mpd.socket. May 09 01:32:33 volumio systemd[1]: Starting Music Player Daemon... May 09 01:32:33 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 09 01:32:33 volumio systemd[1]: mpd.service: Succeeded. May 09 01:32:33 volumio systemd[1]: Stopped Music Player Daemon. May 09 01:32:33 volumio systemd[1]: Starting Music Player Daemon... May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 01:32:33 volumio volumio[996]: info: [1715185953677] CoreMusicLibrary::Adding element 개인 라디오 May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:32:33 volumio volumio[996]: Cannot find translation for source 개인 라디오 May 09 01:32:33 volumio volumio[996]: info: Configuring GPIO pins May 09 01:32:33 volumio volumio[996]: error: Plugin audiophonicsonoff failed to start! Error: EINVAL: invalid argument, write May 09 01:32:33 volumio volumio[996]: info: Volumio Calling Home May 09 01:32:33 volumio kernel: export_store: invalid GPIO 4 May 09 01:32:33 volumio volumio[996]: info: Preparing to generate the ALSA configuration file May 09 01:32:33 volumio volumio[996]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 01:32:33 volumio volumio[996]: info: Reading ALSA contributions from plugins. May 09 01:32:33 volumio volumio[996]: info: MPD Permissions set May 09 01:32:33 volumio volumio[996]: info: MPD Permissions set May 09 01:32:33 volumio volumio[996]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: Asound.conf file unchanged, so no further update is needed May 09 01:32:33 volumio volumio[996]: info: Output device has changed, restarting MPD May 09 01:32:33 volumio sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 01:32:33 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 01:32:33 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:33 volumio sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:33 volumio sudo[1163]: pam_unix(sudo:session): session closed for user root May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::volumioGetState May 09 01:32:33 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:33 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 09 01:32:33 volumio systemd[1]: mpd.service: Succeeded. May 09 01:32:33 volumio systemd[1]: Stopped Music Player Daemon. May 09 01:32:33 volumio volumio[996]: info: MPD Permissions set May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:33 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:33 volumio systemd[1]: Starting Music Player Daemon... May 09 01:32:34 volumio volumio[996]: (node:996) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 09 01:32:34 volumio volumio[996]: (Use `node --trace-deprecation ...` to show where the warning was created) May 09 01:32:34 volumio volumio[996]: (node:996) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. May 09 01:32:34 volumio volumio[996]: info: Volumio called home May 09 01:32:34 volumio mpd[1168]: May 09 01:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 09 01:32:34 volumio systemd[1]: Started Music Player Daemon. May 09 01:32:34 volumio sudo[1139]: pam_unix(sudo:session): session closed for user root May 09 01:32:34 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root May 09 01:32:34 volumio sudo[1146]: pam_unix(sudo:session): session closed for user root May 09 01:32:34 volumio volumio[996]: info: Completed starting Core Plugins May 09 01:32:34 volumio volumio[996]: info: ------------------------------------------- May 09 01:32:34 volumio volumio[996]: info: ----- MyVolumio plugins startup ---- May 09 01:32:34 volumio volumio[996]: info: ------------------------------------------- May 09 01:32:34 volumio volumio[996]: info: [MyVolumio PluginManager] Fetching plans data.... May 09 01:32:34 volumio volumio[996]: error: MPD error: The expression evaluated to a falsy value: May 09 01:32:34 volumio volumio[996]: assert.ok(self.idling) May 09 01:32:34 volumio volumio[996]: error: The expression evaluated to a falsy value: May 09 01:32:34 volumio volumio[996]: assert.ok(self.idling) May 09 01:32:34 volumio volumio[996]: error: MPD error: The expression evaluated to a falsy value: May 09 01:32:34 volumio volumio[996]: assert.ok(self.idling) May 09 01:32:34 volumio volumio[996]: error: The expression evaluated to a falsy value: May 09 01:32:34 volumio volumio[996]: assert.ok(self.idling) May 09 01:32:34 volumio volumio[996]: info: MPD running with PID1168 May 09 01:32:34 volumio volumio[996]: ,establishing connection May 09 01:32:34 volumio volumio[996]: error: updateQueue error: null May 09 01:32:34 volumio volumio[996]: error: updateQueue error: null May 09 01:32:35 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1183, instance 1, run: true May 09 01:32:35 volumio volumio[996]: info: camilladsp service started and running in background, instance 1 May 09 01:32:35 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 01:32:35 volumio volumio[996]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 09 01:32:35 volumio volumio[996]: cannot open device 'volumioHw': No such device May 09 01:32:35 volumio volumio[996]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 09 01:32:35 volumio volumio[996]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 09 01:32:35 volumio volumio[996]: cannot open device 'volumioHw': No such device May 09 01:32:35 volumio volumio[996]: info: FusionDsp loaded May 09 01:32:35 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 01:32:35 volumio sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 09 01:32:35 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:35 volumio volumio[996]: info: FusionDsp - Reporting Fusion DSP Enabled May 09 01:32:35 volumio volumio[996]: info: Adding Signal Path Element [object Object] May 09 01:32:35 volumio volumio[996]: info: Adding fusiondspeq DSP Signal Path Element May 09 01:32:35 volumio volumio[996]: info: FusionDsp - ---- installed callbackRead May 09 01:32:35 volumio volumio[996]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 01:32:35 volumio volumio[996]: info: Preload queue cleared May 09 01:32:35 volumio volumio[996]: error: FusionDsp - WebSocket error: [object Object] May 09 01:32:35 volumio volumio[996]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 May 09 01:32:35 volumio systemd[1]: Started FusionDsp Daemon. May 09 01:32:35 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1196, instance 1, run: true May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1203, instance 1, run: true May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1210, instance 1, run: true May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1217, instance 1, run: true May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1224, instance 1, run: true May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1231, instance 1, run: true May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1238, instance 1, run: true May 09 01:32:35 volumio volumio[996]: info: camilladsp spawned new process with pid 1245, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1252, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1259, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1266, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1273, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1280, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1287, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1294, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1301, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1308, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1315, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1322, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1329, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1336, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1343, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1350, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1357, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1364, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1371, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1378, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1385, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1392, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1399, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1406, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1413, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1420, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1427, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1434, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1441, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1448, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1455, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1462, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1469, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1476, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1483, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1490, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1497, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1504, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1511, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1518, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1525, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1532, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1539, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1546, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1553, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1560, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1567, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1574, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1581, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1588, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1595, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1602, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1609, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1616, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1623, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1630, instance 1, run: true May 09 01:32:36 volumio volumio[996]: info: camilladsp spawned new process with pid 1637, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1644, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1651, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1658, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1665, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1672, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1679, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1686, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1693, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1700, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1707, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1714, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1721, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1728, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1735, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1742, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1749, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1756, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1763, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1770, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1777, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1784, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1791, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1798, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1805, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1812, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1819, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1826, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1833, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1840, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1847, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1854, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1861, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1868, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1875, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1882, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1889, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1896, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1903, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1910, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1917, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1924, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: Preload queue cleared May 09 01:32:37 volumio volumio[996]: info: Preloading song: webkbs/0 May 09 01:32:37 volumio volumio[996]: info: Preloading song: webkbs/1 May 09 01:32:37 volumio volumio[996]: info: Preloading song: webkbs/2 May 09 01:32:37 volumio volumio[996]: info: Preloading song: webkbs/3 May 09 01:32:37 volumio volumio[996]: info: Preloading song: webkbs/4 May 09 01:32:37 volumio volumio[996]: info: Preloading song: webkbs/5 May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1931, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1938, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1945, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: Exploding uri webkbs/0 in service personal_radio May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1952, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1959, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1966, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: Exploding uri webkbs/1 in service personal_radio May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1973, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1980, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1987, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: Exploding uri webkbs/2 in service personal_radio May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 1994, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2001, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2008, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: Exploding uri webkbs/3 in service personal_radio May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2015, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2022, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2029, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: Exploding uri webkbs/4 in service personal_radio May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2036, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2043, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2050, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: Exploding uri webkbs/5 in service personal_radio May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2057, instance 1, run: true May 09 01:32:37 volumio volumio[996]: info: camilladsp spawned new process with pid 2064, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2071, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2078, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2085, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2092, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2099, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2106, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2113, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2120, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2127, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2134, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2141, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2148, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2155, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2162, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2169, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2176, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2183, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2190, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2197, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2204, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2211, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2218, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2225, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2232, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2239, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2246, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2253, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2260, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2267, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2274, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2281, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2288, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2295, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2302, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2309, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2316, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2323, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2330, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2337, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2344, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2351, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2358, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2365, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2372, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: Preload queue cleared May 09 01:32:38 volumio volumio[996]: info: CoreCommandRouter::volumioReplaceandPlayItems May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::ClearQueue May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::stop May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::clearPlayQueue May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::saveQueue May 09 01:32:38 volumio volumio[996]: info: CoreCommandRouter::volumioPushQueue May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::addQueueItems May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::addQueueItems May 09 01:32:38 volumio volumio[996]: info: Preload queue cleared May 09 01:32:38 volumio volumio[996]: info: Adding Item to queue: webkbs/0 May 09 01:32:38 volumio volumio[996]: info: Using cached record of: webkbs/0 May 09 01:32:38 volumio volumio[996]: info: Adding Item to queue: webkbs/1 May 09 01:32:38 volumio volumio[996]: info: Using cached record of: webkbs/1 May 09 01:32:38 volumio volumio[996]: info: Adding Item to queue: webkbs/2 May 09 01:32:38 volumio volumio[996]: info: Using cached record of: webkbs/2 May 09 01:32:38 volumio volumio[996]: info: Adding Item to queue: webkbs/3 May 09 01:32:38 volumio volumio[996]: info: Using cached record of: webkbs/3 May 09 01:32:38 volumio volumio[996]: info: Adding Item to queue: webkbs/4 May 09 01:32:38 volumio volumio[996]: info: Using cached record of: webkbs/4 May 09 01:32:38 volumio volumio[996]: info: Adding Item to queue: webkbs/5 May 09 01:32:38 volumio volumio[996]: info: Using cached record of: webkbs/5 May 09 01:32:38 volumio volumio[996]: info: CoreCommandRouter::volumioPushQueue May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::saveQueue May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::updateTrackBlock May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::getTrackBlock May 09 01:32:38 volumio volumio[996]: info: CoreCommandRouter::volumioPlay May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::play index 2 May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::stop May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::play index undefined May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::startPlaybackTimer May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:38 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand stop May 09 01:32:38 volumio volumio[996]: info: sendMpdCommand stop took 1 milliseconds May 09 01:32:38 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand clear May 09 01:32:38 volumio volumio[996]: info: May 09 01:32:38 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:38 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:38 volumio volumio[996]: info: sendMpdCommand clear took 2 milliseconds May 09 01:32:38 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand add "https://1radio.gscdn.kbs.co.kr/1radio_192_4.m3u8?Expires=1715358702&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFyYWRpb18xOTJfNC5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzAyfX19XX0_&Signature=OYxe7-mGTu~Mtl-Mc8Slflr5iEwsPIlGazJ8JPlxS9oRPsQUcYPBLOI2kYI8TfpxqTdm7yTZ47C5xiAuLQxenK8qWfG7BdT9buWMInHKcUHJkaTldU9faSRj3CFYKNLhBNeMyrMAWRJWaucbSejPDrp-wc0CczzVqgkp7MB9ByylNZuTgSvIxjrkpna01o2OohzxoTOYy7fINyvIBtIlCmoP90ztQG0XkfMrYG0JgEIOme7-~fS3B~HZU-JFEDfOGMoLRRcM5Ky07gMgnjtCQGxpvg9bDPlRcinCibHZ64mREtBZa06lrhokw-NPgxVV3R4pCvVcx~yqhzUQdRg7aw__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" May 09 01:32:38 volumio volumio[996]: info: May 09 01:32:38 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:38 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:38 volumio volumio[996]: info: May 09 01:32:38 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:38 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:38 volumio volumio[996]: info: May 09 01:32:38 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:38 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2379, instance 1, run: true May 09 01:32:38 volumio volumio[996]: error: updateQueue error: null May 09 01:32:38 volumio volumio[996]: info: May 09 01:32:38 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:38 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:38 volumio volumio[996]: info: ------------------------------ 10ms May 09 01:32:38 volumio volumio[996]: info: sendMpdCommand add "https://1radio.gscdn.kbs.co.kr/1radio_192_4.m3u8?Expires=1715358702&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFyYWRpb18xOTJfNC5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzAyfX19XX0_&Signature=OYxe7-mGTu~Mtl-Mc8Slflr5iEwsPIlGazJ8JPlxS9oRPsQUcYPBLOI2kYI8TfpxqTdm7yTZ47C5xiAuLQxenK8qWfG7BdT9buWMInHKcUHJkaTldU9faSRj3CFYKNLhBNeMyrMAWRJWaucbSejPDrp-wc0CczzVqgkp7MB9ByylNZuTgSvIxjrkpna01o2OohzxoTOYy7fINyvIBtIlCmoP90ztQG0XkfMrYG0JgEIOme7-~fS3B~HZU-JFEDfOGMoLRRcM5Ky07gMgnjtCQGxpvg9bDPlRcinCibHZ64mREtBZa06lrhokw-NPgxVV3R4pCvVcx~yqhzUQdRg7aw__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" took 9 milliseconds May 09 01:32:38 volumio volumio[996]: info: ------------------------------ 9ms May 09 01:32:38 volumio volumio[996]: info: ------------------------------ 8ms May 09 01:32:38 volumio volumio[996]: info: ------------------------------ 9ms May 09 01:32:38 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand play May 09 01:32:38 volumio volumio[996]: info: May 09 01:32:38 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:38 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:38 volumio volumio[996]: info: May 09 01:32:38 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:38 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:38 volumio volumio[996]: info: May 09 01:32:38 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:38 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:38 volumio volumio[996]: info: ------------------------------ 7ms May 09 01:32:38 volumio volumio[996]: info: sendMpdCommand play took 4 milliseconds May 09 01:32:38 volumio volumio[996]: info: ------------------------------ 4ms May 09 01:32:38 volumio volumio[996]: info: ------------------------------ 3ms May 09 01:32:38 volumio volumio[996]: info: ------------------------------ 4ms May 09 01:32:38 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:38 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:38 volumio volumio[996]: info: Pushing Favourites {"uri":"webkbs/2","favourite":false} May 09 01:32:38 volumio volumio[996]: info: sendMpdCommand status took 8 milliseconds May 09 01:32:38 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:38 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:38 volumio volumio[996]: info: sendMpdCommand playlistinfo took 1 milliseconds May 09 01:32:38 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:38 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:38 volumio volumio[996]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1radio_192_4.m3u8?Expires=1715358702&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFyYWRpb18xOTJfNC5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzAyfX19XX0_&Signature=OYxe7-mGTu~Mtl-Mc8Slflr5iEwsPIlGazJ8JPlxS9oRPsQUcYPBLOI2kYI8TfpxqTdm7yTZ47C5xiAuLQxenK8qWfG7BdT9buWMInHKcUHJkaTldU9faSRj3CFYKNLhBNeMyrMAWRJWaucbSejPDrp-wc0CczzVqgkp7MB9ByylNZuTgSvIxjrkpna01o2OohzxoTOYy7fINyvIBtIlCmoP90ztQG0XkfMrYG0JgEIOme7-~fS3B~HZU-JFEDfOGMoLRRcM5Ky07gMgnjtCQGxpvg9bDPlRcinCibHZ64mREtBZa06lrhokw-NPgxVV3R4pCvVcx~yqhzUQdRg7aw__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1radio.gscdn.kbs.co.kr/1radio_192_4.m3u8?Expires=1715358702&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFyYWRpb18xOTJfNC5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzAyfX19XX0_&Signature=OYxe7-mGTu~Mtl-Mc8Slflr5iEwsPIlGazJ8JPlxS9oRPsQUcYPBLOI2kYI8TfpxqTdm7yTZ47C5xiAuLQxenK8qWfG7BdT9buWMInHKcUHJkaTldU9faSRj3CFYKNLhBNeMyrMAWRJWaucbSejPDrp-wc0CczzVqgkp7MB9ByylNZuTgSvIxjrkpna01o2OohzxoTOYy7fINyvIBtIlCmoP90ztQG0XkfMrYG0JgEIOme7-~fS3B~HZU-JFEDfOGMoLRRcM5Ky07gMgnjtCQGxpvg9bDPlRcinCibHZ64mREtBZa06lrhokw-NPgxVV3R4pCvVcx~yqhzUQdRg7aw__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} May 09 01:32:38 volumio volumio[996]: verbose: CURRENT POSITION 2 May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::syncState stateService play May 09 01:32:38 volumio volumio[996]: info: CoreStateMachine::syncState currentStatus stop May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2389, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2396, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2403, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2410, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2417, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2424, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2431, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2438, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2445, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2452, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2459, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2466, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2473, instance 1, run: true May 09 01:32:38 volumio volumio[996]: info: camilladsp spawned new process with pid 2480, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2487, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2494, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2501, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2508, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2515, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2522, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2529, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2536, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2543, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2550, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2557, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2564, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2571, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2578, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2585, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2592, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2599, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2606, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2613, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2620, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2627, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2634, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2641, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2648, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2655, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2662, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2669, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2676, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2683, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2690, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2697, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2704, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2711, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2718, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2725, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2732, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: May 09 01:32:39 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:39 volumio volumio[996]: info: May 09 01:32:39 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:39 volumio volumio[996]: info: May 09 01:32:39 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:39 volumio volumio[996]: info: May 09 01:32:39 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2741, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 09 01:32:39 volumio volumio[996]: info: FusionDsp - ---- read samplerate from file: 192000 May 09 01:32:39 volumio volumio[996]: info: camilladsp stopping service pid 2741... May 09 01:32:39 volumio volumio[996]: info: camilladsp service terminated, instance 1 May 09 01:32:39 volumio volumio[996]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 01:32:39 volumio volumio[996]: info: camilladsp service started and running in background, instance 1 May 09 01:32:39 volumio volumio[996]: info: May 09 01:32:39 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand status took 27 milliseconds May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand status took 27 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand status took 26 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand status took 25 milliseconds May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:39 volumio volumio[996]: info: May 09 01:32:39 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:39 volumio volumio[996]: info: May 09 01:32:39 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:39 volumio volumio[996]: info: May 09 01:32:39 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand status took 5 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand playlistinfo took 3 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand status took 3 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand status took 2 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand status took 3 milliseconds May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:39 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:39 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:39 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:39 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:39 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:39 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:39 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:39 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:39 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:39 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:39 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:39 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:39 volumio volumio[996]: info: ------------------------------ 38ms May 09 01:32:39 volumio volumio[996]: info: ------------------------------ 37ms May 09 01:32:39 volumio volumio[996]: info: ------------------------------ 36ms May 09 01:32:39 volumio volumio[996]: info: ------------------------------ 36ms May 09 01:32:39 volumio volumio[996]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand playlistinfo took 26 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand playlistinfo took 25 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand playlistinfo took 25 milliseconds May 09 01:32:39 volumio volumio[996]: info: sendMpdCommand playlistinfo took 25 milliseconds May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:39 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:39 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:39 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:39 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:39 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:39 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:39 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:39 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:39 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:39 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:39 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:39 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:39 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:39 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:39 volumio volumio[996]: info: ------------------------------ 36ms May 09 01:32:39 volumio volumio[996]: info: ------------------------------ 33ms May 09 01:32:39 volumio volumio[996]: info: ------------------------------ 33ms May 09 01:32:39 volumio volumio[996]: info: ------------------------------ 32ms May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2746, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2753, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2760, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2767, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2774, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2781, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2788, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2795, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2802, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2809, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2816, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2823, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2830, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2837, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2844, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2851, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2858, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2865, instance 1, run: true May 09 01:32:39 volumio volumio[996]: info: camilladsp spawned new process with pid 2872, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2879, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2886, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2893, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2900, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2907, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2914, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2921, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2928, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2935, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2942, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2949, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2956, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2963, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2970, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2977, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2984, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2991, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 2998, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3005, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3012, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3019, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3026, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3033, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3040, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3047, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3054, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3061, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3068, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3075, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3082, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3089, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3096, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3103, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3110, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3117, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3124, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3131, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3138, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3145, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3152, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3159, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3166, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3173, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3180, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3187, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3194, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3201, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3208, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3215, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3222, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3229, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3236, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3243, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3250, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3257, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3264, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3271, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3278, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3285, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3292, instance 1, run: true May 09 01:32:40 volumio volumio[996]: info: camilladsp spawned new process with pid 3299, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3306, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3313, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3320, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3327, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3334, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3341, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3348, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3355, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3362, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3369, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3376, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3383, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3390, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3397, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3404, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3411, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3418, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3425, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3432, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3439, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3446, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3453, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3460, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3467, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3474, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3481, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3488, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3495, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3502, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3509, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3516, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3523, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3530, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3537, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3544, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3551, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3558, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3565, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3572, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3579, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3586, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3593, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3600, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3607, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3614, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3621, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3628, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3635, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3642, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3649, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3656, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3663, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3670, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3677, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3684, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3691, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3698, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3705, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3712, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3719, instance 1, run: true May 09 01:32:41 volumio volumio[996]: info: camilladsp spawned new process with pid 3726, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3733, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3740, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3747, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3754, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3761, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3768, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3775, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3782, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3789, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3796, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3816, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3844, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3855, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3862, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3869, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3876, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3883, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3890, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3897, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3904, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3911, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3918, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3925, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3932, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3939, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3946, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3953, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3960, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3967, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3974, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3981, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3988, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 3995, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4002, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4009, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4016, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4023, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4030, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4037, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4044, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4051, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4058, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4065, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4072, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4079, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4086, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4093, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4100, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4107, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4114, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4121, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4128, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4135, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4142, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4149, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4156, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4163, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4170, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4177, instance 1, run: true May 09 01:32:42 volumio volumio[996]: info: camilladsp spawned new process with pid 4184, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4191, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4198, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4205, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4212, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4219, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4226, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4233, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4240, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4247, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4254, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4261, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4268, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4275, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4282, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4289, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4296, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4303, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4310, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4317, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4324, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4331, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4338, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4345, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4352, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4359, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4366, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: camilladsp spawned new process with pid 4373, instance 1, run: true May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 09 01:32:43 volumio volumio[996]: info: Adding plugin bluetooth to MyMusic Plugins May 09 01:32:43 volumio volumio[996]: info: Adding plugin multiroom to MyMusic Plugins May 09 01:32:43 volumio volumio[996]: info: Adding plugin metavolumio to MyMusic Plugins May 09 01:32:43 volumio volumio[996]: info: Adding plugin cd_controller to MyMusic Plugins May 09 01:32:43 volumio volumio[996]: info: Adding plugin smart_inputs to MyMusic Plugins May 09 01:32:43 volumio volumio[996]: info: Adding plugin tidalconnect to MyMusic Plugins May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 09 01:32:43 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:43 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:43 volumio volumio[996]: info: Starting MyVolumio Remote Streaming Endpoints May 09 01:32:43 volumio volumio[996]: info: MyVolumio login type: Token May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 09 01:32:43 volumio volumio[996]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 09 01:32:44 volumio volumio[996]: info: Starting Streaming Service Transparent Proxy May 09 01:32:44 volumio volumio[996]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 09 01:32:44 volumio volumio[996]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 09 01:32:44 volumio volumio[996]: info: Streaming services startup May 09 01:32:44 volumio volumio[996]: info: Starting Streaming Daemon May 09 01:32:44 volumio sudo[4387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 01:32:44 volumio sudo[4387]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:44 volumio volumio[996]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 09 01:32:44 volumio sudo[4387]: pam_unix(sudo:session): session closed for user root May 09 01:32:44 volumio volumio[996]: error: Cannot start Volumio Streaming Daemon May 09 01:32:44 volumio volumio[996]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 01:32:44 volumio volumio[996]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4394, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4401, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4408, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4415, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4422, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4429, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4436, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4443, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4450, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4457, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4464, instance 1, run: true May 09 01:32:44 volumio volumio[996]: STREAMING PROXY: Starting server on port 3245 May 09 01:32:44 volumio volumio[996]: Node JS runtime: 14 May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4471, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4478, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4485, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4492, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4499, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4506, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4513, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4520, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4527, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4534, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4541, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4548, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4555, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4562, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4569, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4576, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4583, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4590, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4597, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4604, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4611, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4618, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4625, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4632, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4639, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4646, instance 1, run: true May 09 01:32:44 volumio volumio[996]: info: camilladsp spawned new process with pid 4653, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4660, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4667, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4674, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4681, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:32:45 volumio volumio[996]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 01:32:45 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 01:32:45 volumio volumio[996]: info: Received Get System Version May 09 01:32:45 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 01:32:45 volumio volumio[996]: info: Received Get System Info May 09 01:32:45 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 01:32:45 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 01:32:45 volumio volumio[996]: info: Discovery: Getting this device information May 09 01:32:45 volumio volumio[996]: info: CoreCommandRouter::volumioGetState May 09 01:32:45 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:45 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4688, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4695, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4702, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4709, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4716, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4723, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4730, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4737, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4744, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4751, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4758, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4765, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4772, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4779, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4786, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4793, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4800, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4807, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4814, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4821, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4828, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4835, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4842, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4849, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4856, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4863, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4870, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4877, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4884, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4891, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4898, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4905, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4914, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4921, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: MyVolumio token set successfully May 09 01:32:45 volumio volumio[996]: info: MYVOLUMIO: Adding device May 09 01:32:45 volumio volumio[996]: info: MYVOLUMIO: Evaluating Server May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4928, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4935, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4942, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4949, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4956, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4963, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4970, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4977, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4984, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4991, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 4998, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: MyVolumio status changed May 09 01:32:45 volumio volumio[996]: info: Streaming services startup May 09 01:32:45 volumio volumio[996]: info: Starting Streaming Daemon May 09 01:32:45 volumio volumio[996]: info: Removing browser output: myVolumio user plan is not superstar May 09 01:32:45 volumio volumio[996]: info: Removing audio output: May 09 01:32:45 volumio volumio[996]: info: Stoppping Tunnel 1 May 09 01:32:45 volumio sudo[5021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 01:32:45 volumio sudo[5021]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:45 volumio sudo[5024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 09 01:32:45 volumio sudo[5021]: pam_unix(sudo:session): session closed for user root May 09 01:32:45 volumio sudo[5024]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:45 volumio sudo[5024]: pam_unix(sudo:session): session closed for user root May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 5026, instance 1, run: true May 09 01:32:45 volumio volumio[996]: info: Remote SSH Stopped May 09 01:32:45 volumio volumio[996]: error: Cannot start Volumio Streaming Daemon May 09 01:32:45 volumio volumio[996]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 01:32:45 volumio volumio[996]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 01:32:45 volumio volumio[996]: info: camilladsp spawned new process with pid 5033, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5040, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5047, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5054, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5061, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5068, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5075, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5082, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5089, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5096, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5103, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5110, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5117, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5124, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: Setting Geolocation for MyVolumio to as1 May 09 01:32:46 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:46 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:46 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5131, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5138, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5145, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5152, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5159, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5166, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5173, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5180, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5187, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5194, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5201, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5208, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5215, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5222, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5229, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5236, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5243, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5250, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5257, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5264, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5271, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5278, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5285, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5292, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5299, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5306, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5313, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5320, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5327, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5334, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5341, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5348, instance 1, run: true May 09 01:32:46 volumio volumio[996]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5355, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5362, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5369, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5376, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5383, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5390, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5397, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5404, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5411, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5418, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5425, instance 1, run: true May 09 01:32:46 volumio volumio[996]: info: camilladsp spawned new process with pid 5432, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5439, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5446, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5453, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5460, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5467, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5474, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5481, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5488, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5495, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5502, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5509, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5516, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5523, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5530, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: Updating MyVolumio device info May 09 01:32:47 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:47 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:47 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5537, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5544, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5551, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5558, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5565, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5572, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5579, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5586, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5593, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5600, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5607, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5614, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5621, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5628, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5635, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5642, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5649, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5656, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5663, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5670, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5677, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5684, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5691, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5698, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5705, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5712, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5719, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5726, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5733, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5740, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5747, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5754, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5761, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5768, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5775, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5782, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5789, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5796, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5803, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5810, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5817, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5824, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5831, instance 1, run: true May 09 01:32:47 volumio volumio[996]: info: camilladsp spawned new process with pid 5838, instance 1, run: true May 09 01:32:47 volumio volumio[996]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5845, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5852, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5859, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5866, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5873, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5880, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5887, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5894, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5901, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5908, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5915, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5922, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5929, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5936, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5943, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5950, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5957, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5964, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5971, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5978, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5985, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5992, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 5999, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6006, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6013, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6020, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6027, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6034, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6041, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6048, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6055, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6062, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6069, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6076, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6083, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6090, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6097, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6104, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6111, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6118, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6125, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6132, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6139, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6146, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6153, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6160, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6167, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6174, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6181, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6188, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6195, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6202, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6209, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6216, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6223, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6230, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6237, instance 1, run: true May 09 01:32:48 volumio volumio[996]: info: camilladsp spawned new process with pid 6244, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6251, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6258, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6265, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6272, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6279, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6286, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6293, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6300, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6307, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6314, instance 1, run: true May 09 01:32:49 volumio ntpd[816]: Soliciting pool server 193.123.243.2 May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6321, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6328, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6335, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6342, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6349, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6356, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6363, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6370, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6377, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6384, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6391, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6398, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6405, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6412, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6419, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6426, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6433, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6440, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6447, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6454, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6461, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6468, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6475, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6482, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6489, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6496, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6503, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6510, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6517, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6524, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6531, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6538, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6545, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6552, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6559, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6566, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6573, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6580, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6587, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6594, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6601, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6608, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6615, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6622, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6629, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6636, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6643, instance 1, run: true May 09 01:32:49 volumio volumio[996]: info: camilladsp spawned new process with pid 6650, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6657, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6664, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6671, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6678, instance 1, run: true May 09 01:32:50 volumio ntpd[816]: Soliciting pool server 106.247.248.106 May 09 01:32:50 volumio ntpd[816]: Soliciting pool server 121.174.142.82 May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6685, instance 1, run: true May 09 01:32:50 volumio ntpd[816]: Soliciting pool server 175.195.167.194 May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6692, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6699, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6706, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6713, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6720, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6727, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6734, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6741, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6748, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6755, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6762, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6769, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6776, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6783, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6790, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6797, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6804, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6811, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6818, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6825, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6832, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6839, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6846, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6853, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6860, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6867, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6874, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6881, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6888, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6895, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6902, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6909, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6916, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6923, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6930, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6937, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6944, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6951, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6958, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6965, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: MYVOLUMIO: Adding device May 09 01:32:50 volumio volumio[996]: info: MYVOLUMIO: Evaluating Server May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6972, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6979, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6986, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 6993, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 7000, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 7007, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 7014, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 7021, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 7028, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 7035, instance 1, run: true May 09 01:32:50 volumio volumio[996]: info: camilladsp spawned new process with pid 7042, instance 1, run: true May 09 01:32:51 volumio ntpd[816]: Soliciting pool server 121.162.54.1 May 09 01:32:51 volumio ntpd[816]: Soliciting pool server 39.118.108.234 May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7064, instance 1, run: true May 09 01:32:51 volumio ntpd[816]: Soliciting pool server 39.118.108.234 May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7071, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7078, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7085, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7092, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7099, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7106, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7113, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7120, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7127, instance 1, run: true May 09 01:32:51 volumio sudo[7135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 01:32:51 volumio sudo[7135]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:51 volumio sudo[7135]: pam_unix(sudo:session): session closed for user root May 09 01:32:51 volumio sudo[7137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 01:32:51 volumio sudo[7137]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 01:32:51 volumio sudo[7137]: pam_unix(sudo:session): session closed for user root May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7139, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7147, instance 1, run: true May 09 01:32:51 volumio volumio[996]: verbose: New Socket.io Connection to 192.168.0.98 from 192.168.0.99 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/124.0.6367.111 Mobile/15E148 Safari/604.1 Total Clients: 4 May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7154, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7161, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7168, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: CoreCommandRouter::volumioGetVisibleSources May 09 01:32:51 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 01:32:51 volumio volumio[996]: info: CoreCommandRouter::volumioGetState May 09 01:32:51 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:51 volumio volumio[996]: info: CoreCommandRouter::volumioGetQueue May 09 01:32:51 volumio volumio[996]: info: CoreStateMachine::getQueue May 09 01:32:51 volumio volumio[996]: info: CorePlayQueue::getQueue May 09 01:32:51 volumio volumio[996]: info: Listing playlists May 09 01:32:51 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 09 01:32:51 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 09 01:32:51 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 09 01:32:51 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7175, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7182, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7189, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7196, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7203, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7210, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7217, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7224, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7231, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7238, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7245, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7252, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7259, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7266, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7273, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7280, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7287, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7294, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7301, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7308, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7315, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7322, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7329, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7336, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7343, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7350, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7357, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7364, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7371, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7378, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7385, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7392, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7399, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7406, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7413, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7420, instance 1, run: true May 09 01:32:51 volumio volumio[996]: info: camilladsp spawned new process with pid 7427, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7434, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7441, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7448, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7455, instance 1, run: true May 09 01:32:52 volumio ntpd[816]: Soliciting pool server 2001:678:8::123 May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7462, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7469, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7476, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7483, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7490, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7497, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7504, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7511, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7518, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7525, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7532, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7539, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7546, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7553, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7560, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7567, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7574, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7581, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7588, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7595, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7602, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7609, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7616, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7623, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7630, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7637, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7644, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7651, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7658, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7665, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7672, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7679, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7686, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7693, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7700, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7707, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7714, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7721, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7728, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7735, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7742, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7749, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7756, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7763, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7770, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7777, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7784, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7791, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7798, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7805, instance 1, run: true May 09 01:32:52 volumio volumio[996]: info: camilladsp spawned new process with pid 7812, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7819, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7826, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7833, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7840, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7847, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7854, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7861, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7868, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7875, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7882, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7889, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7896, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7903, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7910, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7917, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7924, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7931, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7938, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7945, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7952, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7959, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7966, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7973, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7980, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7987, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 7994, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8001, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8008, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8015, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8022, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8029, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8036, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8043, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8050, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8057, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: AutoStart - Plugin is starting May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioGetQueue May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::getQueue May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getQueue May 09 01:32:53 volumio volumio[996]: info: AutoStart - start playing May 09 01:32:53 volumio volumio[996]: info: AutoStart - start playing with no specific position May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPlay May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::play index 0 May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::stop May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::stPlaybackTimer May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::updateTrackBlock May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrackBlock May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::serviceStop May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::serviceStop May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::stop May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand stop May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8064, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand stop took 15 milliseconds May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand status took 2 milliseconds May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand status took 2 milliseconds May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand status took 1 milliseconds May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand status took 1 milliseconds May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand status took 0 milliseconds May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand playlistinfo took 1 milliseconds May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand playlistinfo took 1 milliseconds May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1radio_192_4.m3u8?Expires=1715358702&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFyYWRpb18xOTJfNC5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzAyfX19XX0_&Signature=OYxe7-mGTu~Mtl-Mc8Slflr5iEwsPIlGazJ8JPlxS9oRPsQUcYPBLOI2kYI8TfpxqTdm7yTZ47C5xiAuLQxenK8qWfG7BdT9buWMInHKcUHJkaTldU9faSRj3CFYKNLhBNeMyrMAWRJWaucbSejPDrp-wc0CczzVqgkp7MB9ByylNZuTgSvIxjrkpna01o2OohzxoTOYy7fINyvIBtIlCmoP90ztQG0XkfMrYG0JgEIOme7-~fS3B~HZU-JFEDfOGMoLRRcM5Ky07gMgnjtCQGxpvg9bDPlRcinCibHZ64mREtBZa06lrhokw-NPgxVV3R4pCvVcx~yqhzUQdRg7aw__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1radio.gscdn.kbs.co.kr/1radio_192_4.m3u8?Expires=1715358702&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFyYWRpb18xOTJfNC5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzAyfX19XX0_&Signature=OYxe7-mGTu~Mtl-Mc8Slflr5iEwsPIlGazJ8JPlxS9oRPsQUcYPBLOI2kYI8TfpxqTdm7yTZ47C5xiAuLQxenK8qWfG7BdT9buWMInHKcUHJkaTldU9faSRj3CFYKNLhBNeMyrMAWRJWaucbSejPDrp-wc0CczzVqgkp7MB9ByylNZuTgSvIxjrkpna01o2OohzxoTOYy7fINyvIBtIlCmoP90ztQG0XkfMrYG0JgEIOme7-~fS3B~HZU-JFEDfOGMoLRRcM5Ky07gMgnjtCQGxpvg9bDPlRcinCibHZ64mREtBZa06lrhokw-NPgxVV3R4pCvVcx~yqhzUQdRg7aw__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} May 09 01:32:53 volumio volumio[996]: verbose: CURRENT POSITION 2 May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::syncState stateService stop May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::syncState currentStatus stop May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:53 volumio volumio[996]: info: No code May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::pushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: CoreCommandRouter::volumioPushState May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 2 May 09 01:32:53 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 18ms May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::play index undefined May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 18ms May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 17ms May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 17ms May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::startPlaybackTimer May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand stop May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand stop took 1 milliseconds May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand clear May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:53 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand clear took 5 milliseconds May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1715358719&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzE5fX19XX0_&Signature=G8fmsLhm6icvUipcCJ6~20lIxrvR7B8HJ26MVqhjqFvlskGbohekGSWvGRWqi62z6QahQ3lOhlyiXWW-Jnb5Mv5djK5J4I1o1Wxg06RyD-9wvzYeSqlzxVZKI60AN5xGk90P00xs5RRdnB4oqAX1~FbSAWaktHK3amJT1jqBFwlG9swDuPYRjraI9e1J-DJTZiFV3iLRqiE16m9PBo1WeEw7XupmuY0eXoaw2WH1sx77yTJGdRoN5DIj~giDViEHVoFDqQ4H0r-Zy3J6Qb6yZvF4KDuP2q7CegrlJf2-wdyo6-jcRpn5oEswuzCSeMdHsw-Ml58FdusMZK4LPExYlQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:53 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:53 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:53 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8071, instance 1, run: true May 09 01:32:53 volumio volumio[996]: error: updateQueue error: null May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:53 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 10ms May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1715358719&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzE5fX19XX0_&Signature=G8fmsLhm6icvUipcCJ6~20lIxrvR7B8HJ26MVqhjqFvlskGbohekGSWvGRWqi62z6QahQ3lOhlyiXWW-Jnb5Mv5djK5J4I1o1Wxg06RyD-9wvzYeSqlzxVZKI60AN5xGk90P00xs5RRdnB4oqAX1~FbSAWaktHK3amJT1jqBFwlG9swDuPYRjraI9e1J-DJTZiFV3iLRqiE16m9PBo1WeEw7XupmuY0eXoaw2WH1sx77yTJGdRoN5DIj~giDViEHVoFDqQ4H0r-Zy3J6Qb6yZvF4KDuP2q7CegrlJf2-wdyo6-jcRpn5oEswuzCSeMdHsw-Ml58FdusMZK4LPExYlQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" took 10 milliseconds May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 10ms May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 9ms May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 9ms May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand play May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:53 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:53 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:53 volumio volumio[996]: info: May 09 01:32:53 volumio volumio[996]: ---------------------------- MPD announces system playlist update May 09 01:32:53 volumio volumio[996]: info: Ignoring MPD Status Update May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 4ms May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand play took 3 milliseconds May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 2ms May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 2ms May 09 01:32:53 volumio volumio[996]: info: ------------------------------ 1ms May 09 01:32:53 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand status took 1 milliseconds May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:53 volumio volumio[996]: info: Pushing Favourites {"uri":"webkbs/0","favourite":false} May 09 01:32:53 volumio volumio[996]: info: sendMpdCommand playlistinfo took 2 milliseconds May 09 01:32:53 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:53 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:53 volumio volumio[996]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1715358719&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzE5fX19XX0_&Signature=G8fmsLhm6icvUipcCJ6~20lIxrvR7B8HJ26MVqhjqFvlskGbohekGSWvGRWqi62z6QahQ3lOhlyiXWW-Jnb5Mv5djK5J4I1o1Wxg06RyD-9wvzYeSqlzxVZKI60AN5xGk90P00xs5RRdnB4oqAX1~FbSAWaktHK3amJT1jqBFwlG9swDuPYRjraI9e1J-DJTZiFV3iLRqiE16m9PBo1WeEw7XupmuY0eXoaw2WH1sx77yTJGdRoN5DIj~giDViEHVoFDqQ4H0r-Zy3J6Qb6yZvF4KDuP2q7CegrlJf2-wdyo6-jcRpn5oEswuzCSeMdHsw-Ml58FdusMZK4LPExYlQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1715358719&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1MzU4NzE5fX19XX0_&Signature=G8fmsLhm6icvUipcCJ6~20lIxrvR7B8HJ26MVqhjqFvlskGbohekGSWvGRWqi62z6QahQ3lOhlyiXWW-Jnb5Mv5djK5J4I1o1Wxg06RyD-9wvzYeSqlzxVZKI60AN5xGk90P00xs5RRdnB4oqAX1~FbSAWaktHK3amJT1jqBFwlG9swDuPYRjraI9e1J-DJTZiFV3iLRqiE16m9PBo1WeEw7XupmuY0eXoaw2WH1sx77yTJGdRoN5DIj~giDViEHVoFDqQ4H0r-Zy3J6Qb6yZvF4KDuP2q7CegrlJf2-wdyo6-jcRpn5oEswuzCSeMdHsw-Ml58FdusMZK4LPExYlQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} May 09 01:32:53 volumio volumio[996]: verbose: CURRENT POSITION 0 May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::syncState stateService play May 09 01:32:53 volumio volumio[996]: info: CoreStateMachine::syncState currentStatus stop May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8079, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8086, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8093, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8100, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8107, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8114, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8121, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8128, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8135, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8142, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8149, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8156, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8163, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8170, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8177, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8184, instance 1, run: true May 09 01:32:53 volumio volumio[996]: info: camilladsp spawned new process with pid 8191, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8198, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8205, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8212, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8219, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8226, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8233, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8240, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8247, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8254, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8261, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8268, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8275, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8282, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8289, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8296, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8303, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8310, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8317, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8324, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8331, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8338, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: May 09 01:32:54 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:54 volumio volumio[996]: info: May 09 01:32:54 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:54 volumio volumio[996]: info: May 09 01:32:54 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:54 volumio volumio[996]: info: May 09 01:32:54 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:54 volumio volumio[996]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 09 01:32:54 volumio volumio[996]: info: FusionDsp - ---- read samplerate from file: 192000 May 09 01:32:54 volumio volumio[996]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 01:32:54 volumio volumio[996]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 09 01:32:54 volumio volumio[996]: info: FusionDsp - ---- read samplerate from file: 192000 May 09 01:32:54 volumio volumio[996]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 01:32:54 volumio volumio[996]: info: May 09 01:32:54 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand status took 17 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand status took 16 milliseconds May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand status took 16 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand status took 15 milliseconds May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:54 volumio volumio[996]: info: May 09 01:32:54 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:54 volumio volumio[996]: info: May 09 01:32:54 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:54 volumio volumio[996]: info: May 09 01:32:54 volumio volumio[996]: ---------------------------- MPD announces state update: player May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::getState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand status May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand status took 5 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand status took 3 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand status took 2 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand status took 2 milliseconds May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseState May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:54 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:54 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:54 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:54 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:54 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:54 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:54 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:54 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:54 volumio volumio[996]: info: ------------------------------ 29ms May 09 01:32:54 volumio volumio[996]: info: ------------------------------ 27ms May 09 01:32:54 volumio volumio[996]: info: ------------------------------ 26ms May 09 01:32:54 volumio volumio[996]: info: ------------------------------ 25ms May 09 01:32:54 volumio volumio[996]: error: FusionDsp - WebSocket error: [object Object] May 09 01:32:54 volumio volumio[996]: error: FusionDsp - WebSocket error: [object Object] May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8346, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand playlistinfo took 13 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand playlistinfo took 12 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand playlistinfo took 12 milliseconds May 09 01:32:54 volumio volumio[996]: info: sendMpdCommand playlistinfo took 11 milliseconds May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:54 volumio volumio[996]: verbose: ControllerMpd::parseTrackInfo May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:54 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:54 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:54 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:54 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:54 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:54 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:54 volumio volumio[996]: info: ControllerMpd::pushState May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::servicePushState May 09 01:32:54 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:54 volumio volumio[996]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd May 09 01:32:54 volumio volumio[996]: info: ------------------------------ 22ms May 09 01:32:54 volumio volumio[996]: info: ------------------------------ 19ms May 09 01:32:54 volumio volumio[996]: info: ------------------------------ 19ms May 09 01:32:54 volumio volumio[996]: info: ------------------------------ 18ms May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8353, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8360, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8367, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8374, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8381, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8388, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8395, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8402, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8409, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8416, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8423, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8430, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8437, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8444, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8451, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8458, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8465, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8472, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8479, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8486, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8493, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8500, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8507, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8514, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8521, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8528, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8535, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8542, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8549, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8556, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8563, instance 1, run: true May 09 01:32:54 volumio volumio[996]: info: camilladsp spawned new process with pid 8570, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8577, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8584, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8591, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8598, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8605, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8612, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8619, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8626, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8633, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8640, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8647, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8654, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8661, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8668, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8675, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8682, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8689, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8696, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8703, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8710, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8717, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8724, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8731, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8738, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8745, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8752, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8759, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8766, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8773, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8780, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8787, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8794, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8801, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8808, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8815, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8822, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8829, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8836, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8843, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8850, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8857, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8864, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8871, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8878, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8885, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8892, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8899, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8906, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8913, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8920, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8927, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8934, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8941, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8948, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8955, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8962, instance 1, run: true May 09 01:32:55 volumio volumio[996]: info: camilladsp spawned new process with pid 8969, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 8976, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 8983, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 8990, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 8997, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9004, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9011, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9018, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9025, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9032, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9039, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9046, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9053, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9060, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9067, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9074, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9081, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9088, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9095, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9102, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: Setting Geolocation for MyVolumio to as1 May 09 01:32:56 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:56 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:56 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9109, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9116, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9123, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9130, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9137, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9144, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9151, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9158, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9165, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9172, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9179, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9186, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9193, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9200, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9207, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9214, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9221, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9228, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9235, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9242, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9249, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9256, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9263, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9270, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9277, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9284, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9291, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9298, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9305, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9312, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9319, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9326, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9333, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9340, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9347, instance 1, run: true May 09 01:32:56 volumio volumio[996]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9354, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9361, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9368, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9375, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9382, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9389, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9396, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9403, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9410, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9417, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9425, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9432, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9439, instance 1, run: true May 09 01:32:56 volumio volumio[996]: info: camilladsp spawned new process with pid 9446, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9453, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9460, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9467, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9474, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9481, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9488, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9495, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9502, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: Updating MyVolumio device info May 09 01:32:57 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:57 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:57 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9509, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9516, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9523, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9530, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9537, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9544, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9551, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9558, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9565, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9572, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9579, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9586, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9593, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9600, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9607, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9614, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9621, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9628, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9635, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9642, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9649, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9656, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9663, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9670, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9677, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9684, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9691, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9698, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9705, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9712, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9719, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9726, instance 1, run: true May 09 01:32:57 volumio volumio[996]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9733, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9740, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9747, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9754, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9761, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9768, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9775, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9782, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9789, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9796, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9803, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9810, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9817, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9824, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9831, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9838, instance 1, run: true May 09 01:32:57 volumio volumio[996]: info: camilladsp spawned new process with pid 9845, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9852, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9859, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9866, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9873, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9880, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9887, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9894, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9901, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9908, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9915, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9922, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9929, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9936, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9943, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9950, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9957, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9964, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9971, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9978, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9985, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9992, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 9999, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10006, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10013, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10020, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10027, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10034, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10041, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10048, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10055, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10062, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10069, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10076, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10083, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10090, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10097, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10104, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10111, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10118, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 01:32:58 volumio volumio[996]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10125, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: CoreCommandRouter::volumioGetState May 09 01:32:58 volumio volumio[996]: info: CorePlayQueue::getTrack 0 May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10132, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10139, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10146, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10153, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10160, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10167, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10174, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10181, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10188, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10195, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10202, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10209, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10216, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10223, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10230, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10237, instance 1, run: true May 09 01:32:58 volumio volumio[996]: info: camilladsp spawned new process with pid 10244, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10251, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10258, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10265, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10272, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10279, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10286, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10293, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10300, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10307, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10314, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10321, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10328, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10335, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10342, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10349, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10356, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10363, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10370, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10377, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10384, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10391, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10398, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10405, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10412, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10419, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10426, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10433, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10440, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10447, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10454, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10461, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10468, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10475, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10482, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10489, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10496, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10503, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10510, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10517, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10524, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10531, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10538, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10545, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10552, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10559, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10566, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10573, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10580, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10587, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10594, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10601, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10608, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10615, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10622, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10629, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10636, instance 1, run: true May 09 01:32:59 volumio volumio[996]: info: camilladsp spawned new process with pid 10643, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10650, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10657, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10664, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10671, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10678, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10685, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10692, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10699, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10706, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10713, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10720, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10727, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10734, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10741, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10748, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10755, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10762, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10769, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10776, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10783, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10790, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10797, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10804, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10811, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10818, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10825, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10832, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10839, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10846, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10853, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10860, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10867, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10874, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10881, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10888, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10895, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10902, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10909, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10916, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10923, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10930, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10937, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10944, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10951, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10958, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10965, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10972, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10979, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10986, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 10993, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11000, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11007, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11014, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11021, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11028, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11035, instance 1, run: true May 09 01:33:00 volumio volumio[996]: info: camilladsp spawned new process with pid 11042, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11049, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11056, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11063, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11070, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11077, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11084, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11091, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11098, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11105, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11112, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11119, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11126, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11133, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11140, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11147, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11154, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11161, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11168, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11175, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11182, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11189, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11196, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11203, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11210, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11217, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11224, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11231, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11238, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11245, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11252, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11259, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11266, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11273, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11280, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11287, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11294, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11301, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11308, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11315, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11322, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11329, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11336, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11343, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11350, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11357, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11364, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11371, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11378, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11385, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11392, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11399, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11406, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11413, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11420, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11427, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11434, instance 1, run: true May 09 01:33:01 volumio volumio[996]: info: camilladsp spawned new process with pid 11441, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11448, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11455, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11462, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11469, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11476, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11483, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11490, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11497, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11504, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11511, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11518, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11525, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11532, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11539, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11546, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11553, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11560, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11567, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11574, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11581, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11588, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: camilladsp spawned new process with pid 11595, instance 1, run: true May 09 01:33:02 volumio volumio[996]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ May 09 01:33:02 volumio volumio[996]: info: PLUGIN onShutdown : networkfs May 09 01:33:02 volumio volumio[996]: info: PLUGIN onShutdown : audiophonicsonoff May 09 01:33:02 volumio volumio[996]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:33:02 volumio volumio[996]: TypeError: Cannot read property 'writeSync' of undefined May 09 01:33:02 volumio volumio[996]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) May 09 01:33:02 volumio volumio[996]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) May 09 01:33:02 volumio volumio[996]: at HashMap. (/volumio/app/pluginmanager.js:621:31) May 09 01:33:02 volumio volumio[996]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 09 01:33:02 volumio volumio[996]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 09 01:33:02 volumio volumio[996]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) May 09 01:33:02 volumio volumio[996]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1313:24) May 09 01:33:02 volumio volumio[996]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:831:33) May 09 01:33:02 volumio volumio[996]: at Socket.emit (events.js:315:20) May 09 01:33:02 volumio volumio[996]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 09 01:33:02 volumio volumio[996]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 01:33:02 volumio sudo[11611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 01:32 May 09 01:33:02 volumio sudo[11611]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"