May 10 02:36:08 volumio ntpd[892]: CLOCK: time stepped by 132.871214 May 10 02:36:08 volumio ntpd[892]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 10 02:36:08 volumio volumio[1116]: info: Plugin peppymeterbasic is not enabled May 10 02:36:08 volumio volumio[1116]: info: Loading i18n strings for locale en May 10 02:36:08 volumio volumio[1116]: Updating browse sources language May 10 02:36:08 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 02:36:09 volumio volumio[1310]: Starting albumart workers May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::initPlayerControls May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 02:36:09 volumio ntpd[892]: IO: Listen normally on 5 wlan0 169.254.139.4:123 May 10 02:36:09 volumio ntpd[892]: IO: new interface(s) found: waking up resolver May 10 02:36:09 volumio ntpd[892]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 10 02:36:09 volumio volumio[1116]: Express server listening on port 3000 May 10 02:36:09 volumio volumio[1116]: [Metrics] WebUI: 9s 222.78ms May 10 02:36:09 volumio ntpd[892]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 10 02:36:09 volumio ntpd[892]: DNS: Pool skipping: 185.156.110.183 May 10 02:36:09 volumio ntpd[892]: DNS: Pool skipping: 185.103.216.7 May 10 02:36:09 volumio ntpd[892]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 10 02:36:09 volumio volumio[1116]: info: CoreStateMachine::resetVolumioState May 10 02:36:09 volumio volumio[1116]: info: CoreStateMachine::getcurrentVolume May 10 02:36:09 volumio volumio[1116]: info: CoreCommandRouter::volumioRetrievevolume May 10 02:36:09 volumio volumio[1116]: info: Volumio Network Manager: Network status updated: 1 May 10 02:36:09 volumio volumio[1311]: Starting albumart workers May 10 02:36:09 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 10 02:36:09 volumio volumio[1116]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:10 volumio volumio[1116]: info: Reloading queue from file May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 10 02:36:10 volumio volumio[1116]: info: VolumeController:: Volume=undefined Mute =false May 10 02:36:10 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:10 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:10 volumio volumio[1116]: info: CoreStateMachine::updateTrackBlock May 10 02:36:10 volumio volumio[1116]: info: CorePlayQueue::getTrackBlock May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::volumioRetrievevolume May 10 02:36:10 volumio volumio[1116]: info: CoreStateMachine::setRepeat null single undefined May 10 02:36:10 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:10 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:10 volumio volumio[1116]: info: CoreStateMachine::setRandom null May 10 02:36:10 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:10 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:10 volumio volumio[1116]: info: Setting Device type: Raspberry PI May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 10 02:36:10 volumio volumio[1116]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 10 02:36:10 volumio volumio[1116]: info: Completed loading Core Plugins May 10 02:36:10 volumio volumio[1116]: info: Preparing to generate the ALSA configuration file May 10 02:36:10 volumio volumio[1116]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 10 02:36:10 volumio volumio[1116]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 10 02:36:10 volumio volumio[1116]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 10 02:36:10 volumio ntpd[892]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 10 02:36:10 volumio ntpd[892]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 10 02:36:10 volumio ntpd[892]: DNS: Pool skipping: 161.53.131.231 May 10 02:36:10 volumio ntpd[892]: DNS: Pool skipping: 161.53.128.17 May 10 02:36:10 volumio ntpd[892]: DNS: Pool skipping: 2001:1700:a00:24::1 May 10 02:36:10 volumio ntpd[892]: DNS: Pool skipping: 2a0f:85c0::50 May 10 02:36:10 volumio ntpd[892]: DNS: Pool skipping: 2a01:4f8:a0:7143::2 May 10 02:36:10 volumio ntpd[892]: DNS: Pool skipping: 2a01:238:4216:400:2719:9f12:f159:ba3f May 10 02:36:10 volumio ntpd[892]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 10 02:36:10 volumio volumio[1116]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 10 02:36:10 volumio volumio[1116]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 10 02:36:10 volumio volumio[1116]: info: Reading ALSA contributions from plugins. May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 10 02:36:10 volumio volumio[1116]: info: VolumeController:: Volume=undefined Mute =false May 10 02:36:10 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:10 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 May 10 02:36:10 volumio volumio[1116]: info: Discovery: adding 356738dd-e89f-476e-86f7-26d8f5727c4f May 10 02:36:10 volumio volumio[1116]: info: Discovery: Found device Volumio May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:10 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 May 10 02:36:10 volumio volumio[1116]: info: Discovery: this is already registered, 356738dd-e89f-476e-86f7-26d8f5727c4f May 10 02:36:10 volumio volumio[1116]: info: Discovery: Found device Volumio May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:10 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 May 10 02:36:10 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 May 10 02:36:10 volumio volumio[1116]: info: Asound.conf file unchanged, so no further update is needed May 10 02:36:10 volumio volumio[1116]: info: Output device has changed, restarting MPD May 10 02:36:10 volumio volumio[1116]: info: Output device has changed, restarting Shairport Sync May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:10 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:10 volumio sudo[1395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 02:36:10 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:10 volumio sudo[1397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 02:36:10 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:11 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root May 10 02:36:11 volumio volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 02:36:11 volumio volumio[1116]: info: ___________ START PLUGINS ___________ May 10 02:36:11 volumio volumio[1116]: info: ControllerMpd::onStart: Initializing MPD May 10 02:36:11 volumio volumio[1116]: info: Creating MPD Configuration file May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 02:36:11 volumio volumio[1116]: info: [1746837371092] CoreMusicLibrary::Adding element Media Servers May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 02:36:11 volumio sudo[1408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 02:36:11 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 10 02:36:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 10 02:36:11 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:11 volumio sudo[1409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 02:36:11 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:11 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root May 10 02:36:11 volumio systemd[1]: mpd.service: Deactivated successfully. May 10 02:36:11 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 10 02:36:11 volumio systemd[1]: mpd.socket: Deactivated successfully. May 10 02:36:11 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 10 02:36:11 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:11 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 10 02:36:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 10 02:36:11 volumio volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:11 volumio sudo[1421]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 02:36:11 volumio sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 10 02:36:11 volumio sudo[1428]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 10 02:36:11 volumio sudo[1421]: pam_unix(sudo:session): session closed for user root May 10 02:36:11 volumio volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 02:36:11 volumio volumio[1116]: info: [1746837371406] CoreMusicLibrary::Adding element Last_100 May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 02:36:11 volumio volumio[1116]: info: [1746837371408] CoreMusicLibrary::Adding element Webradio May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 02:36:11 volumio volumio[1116]: info: Initializing BBC Radios May 10 02:36:11 volumio ntpd[892]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 10 02:36:11 volumio ntpd[892]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 10 02:36:11 volumio ntpd[892]: DNS: Pool skipping: 185.103.216.7 May 10 02:36:11 volumio ntpd[892]: DNS: Pool skipping: 161.53.131.232 May 10 02:36:11 volumio ntpd[892]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:11 volumio volumio[1116]: info: AutoStart - onStart May 10 02:36:11 volumio volumio[1116]: info: [now-playing] ConfigUpdater: config is up to date. May 10 02:36:11 volumio volumio[1116]: info: Loading i18n strings for locale en May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:11 volumio volumio[1116]: info: FusionDsp - mixtype--------------------- undefined May 10 02:36:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 10 02:36:11 volumio volumio[1116]: info: Volumio Calling Home May 10 02:36:11 volumio sudo[1448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh May 10 02:36:11 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:11 volumio sudo[1450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service May 10 02:36:11 volumio sudo[1450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:12 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root May 10 02:36:12 volumio volumio[1116]: info: Preparing to generate the ALSA configuration file May 10 02:36:12 volumio ntpd[892]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 10 02:36:12 volumio ntpd[892]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 10 02:36:12 volumio ntpd[892]: DNS: Pool skipping: 161.53.78.71 May 10 02:36:12 volumio ntpd[892]: DNS: Pool skipping: 185.156.110.183 May 10 02:36:12 volumio ntpd[892]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 10 02:36:12 volumio volumio[1116]: info: [now-playing] App is listening on port 4004. May 10 02:36:12 volumio volumio[1116]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds May 10 02:36:13 volumio volumio[1116]: info: Discovery: this is already registered, 356738dd-e89f-476e-86f7-26d8f5727c4f May 10 02:36:13 volumio volumio[1116]: info: Discovery: Found device Volumio May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:13 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:13 volumio volumio[1116]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 10 02:36:13 volumio volumio[1116]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 10 02:36:13 volumio volumio[1116]: info: Reading ALSA contributions from plugins. May 10 02:36:13 volumio sudo[1471]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 02:36:13 volumio sudo[1473]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 02:36:13 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:13 volumio sudo[1473]: pam_unix(sudo:session): session closed for user root May 10 02:36:13 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 May 10 02:36:13 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 May 10 02:36:13 volumio volumio[1116]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh May 10 02:36:13 volumio volumio[1116]: info: MPD Permissions set May 10 02:36:13 volumio volumio[1116]: info: MPD Permissions set May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 May 10 02:36:13 volumio volumio[1116]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 May 10 02:36:13 volumio volumio[1116]: info: Volumio called home May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:13 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:13 volumio volumio[1116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 29 May 10 02:36:14 volumio volumio[1116]: info: camilladsp service started and running in background, instance 1 May 10 02:36:14 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:14 volumio volumio[1116]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA May 10 02:36:14 volumio volumio[1116]: info: FusionDsp loaded May 10 02:36:14 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 02:36:14 volumio volumio[1116]: error: FusionDsp - Socket connection not established May 10 02:36:14 volumio volumio[1116]: info: FusionDsp - Reporting Fusion DSP Enabled May 10 02:36:14 volumio volumio[1116]: info: Adding Signal Path Element [object Object] May 10 02:36:14 volumio volumio[1116]: info: Adding fusiondspeq DSP Signal Path Element May 10 02:36:14 volumio volumio[1116]: info: FusionDsp - ---- installed callbackRead May 10 02:36:14 volumio volumio[1116]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 10 02:36:14 volumio sudo[1502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 10 02:36:14 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:14 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. May 10 02:36:14 volumio sudo[1502]: pam_unix(sudo:session): session closed for user root May 10 02:36:14 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 30 May 10 02:36:14 volumio volumio[1116]: error: FusionDsp - Reload WebSocket error: [object Object] May 10 02:36:14 volumio volumio[1116]: info: Starting Shairport Sync May 10 02:36:14 volumio sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 02:36:14 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:14 volumio volumio[1116]: info: Starting Shairport Sync May 10 02:36:14 volumio volumio[1116]: info: Starting Shairport Sync May 10 02:36:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 10 02:36:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 10 02:36:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:14 volumio systemd[1]: shairport-sync.service: Consumed 1.551s CPU time. May 10 02:36:15 volumio sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 02:36:15 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:15 volumio volumio[1116]: verbose: New Socket.io Connection to 192.168.0.212 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 May 10 02:36:15 volumio sudo[1515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 02:36:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:15 volumio sudo[1515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:15 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root May 10 02:36:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 10 02:36:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 10 02:36:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:15 volumio sudo[1533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 10 02:36:15 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:15 volumio sudo[1515]: pam_unix(sudo:session): session closed for user root May 10 02:36:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 10 02:36:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 10 02:36:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:15 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root May 10 02:36:15 volumio volumio[1116]: info: Asound.conf file unchanged, so no further update is needed May 10 02:36:15 volumio volumio[1116]: info: Output device has changed, restarting MPD May 10 02:36:15 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 10 02:36:15 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root May 10 02:36:15 volumio sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 02:36:15 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:15 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root May 10 02:36:15 volumio volumio[1116]: info: Output device has changed, restarting Shairport Sync May 10 02:36:15 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:15 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:15 volumio sudo[1556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 02:36:15 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:15 volumio volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 02:36:15 volumio systemd[1]: mpd.service: Deactivated successfully. May 10 02:36:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 10 02:36:15 volumio systemd[1]: mpd.service: Consumed 2.857s CPU time. May 10 02:36:15 volumio systemd[1]: mpd.socket: Deactivated successfully. May 10 02:36:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 10 02:36:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 10 02:36:15 volumio volumio[1116]: info: Preparing to generate the ALSA configuration file May 10 02:36:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 10 02:36:16 volumio volumio[1116]: info: Shairport-Sync Started May 10 02:36:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 10 02:36:16 volumio volumio[1116]: Error adding Membership: Error: addMembership EINVAL May 10 02:36:16 volumio volumio[1116]: info: Shairport-Sync Started May 10 02:36:16 volumio volumio[1116]: info: Shairport-Sync Started May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:16 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:16 volumio volumio[1116]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 10 02:36:16 volumio volumio[1116]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf May 10 02:36:16 volumio volumio[1116]: info: Reading ALSA contributions from plugins. May 10 02:36:16 volumio volumio[1116]: info: MPD Permissions set May 10 02:36:16 volumio volumio[1116]: info: Upmpdcli Daemon Started May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 10 02:36:16 volumio volumio[1116]: info: Listing playlists May 10 02:36:16 volumio sudo[1573]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 02:36:16 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 10 02:36:16 volumio sudo[1576]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 10 02:36:16 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:16 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:16 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:17 volumio volumio[1116]: info: Starting Shairport Sync May 10 02:36:17 volumio sudo[1585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 02:36:17 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:17 volumio volumio[1116]: info: Asound.conf file unchanged, so no further update is needed May 10 02:36:17 volumio volumio[1116]: info: Output device has changed, restarting MPD May 10 02:36:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 10 02:36:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 10 02:36:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:17 volumio systemd[1]: shairport-sync.service: Consumed 1.122s CPU time. May 10 02:36:17 volumio volumio[1116]: info: Output device has changed, restarting Shairport Sync May 10 02:36:17 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:17 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:17 volumio sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 02:36:17 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:17 volumio mpd[1577]: 2025-05-10T02:36:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 10 02:36:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:17 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root May 10 02:36:17 volumio sudo[1591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 02:36:17 volumio sudo[1591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 10 02:36:17 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root May 10 02:36:17 volumio systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. May 10 02:36:17 volumio sudo[1450]: pam_unix(sudo:session): session closed for user root May 10 02:36:17 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root May 10 02:36:17 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root May 10 02:36:17 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root May 10 02:36:17 volumio systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... May 10 02:36:17 volumio systemd[1]: mpd_oled_plugin.service: Deactivated successfully. May 10 02:36:17 volumio systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. May 10 02:36:17 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 10 02:36:18 volumio volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 02:36:18 volumio volumio[1116]: info: MPD Permissions set May 10 02:36:18 volumio volumio[1116]: info: Shairport-Sync Started May 10 02:36:18 volumio volumio[1116]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service May 10 02:36:18 volumio volumio[1116]: info: Completed starting Core Plugins May 10 02:36:18 volumio volumio[1116]: info: ------------------------------------------- May 10 02:36:18 volumio volumio[1116]: info: ----- MyVolumio plugins startup ---- May 10 02:36:18 volumio volumio[1116]: info: ------------------------------------------- May 10 02:36:18 volumio volumio[1116]: info: [MyVolumio PluginManager] Fetching plans data.... May 10 02:36:18 volumio systemd[1]: mpd.service: Deactivated successfully. May 10 02:36:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 10 02:36:18 volumio systemd[1]: mpd.service: Consumed 1.761s CPU time. May 10 02:36:18 volumio systemd[1]: mpd.socket: Deactivated successfully. May 10 02:36:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 10 02:36:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 10 02:36:18 volumio volumio[1116]: error: MPD error: Error: connect ECONNRESET /run/mpd/socket May 10 02:36:18 volumio volumio[1116]: error: MPD error: Error: connect ECONNRESET /run/mpd/socket May 10 02:36:18 volumio volumio[1116]: error: MPD error: Error: connect ECONNRESET /run/mpd/socket May 10 02:36:18 volumio volumio[1116]: info: MPD running with PID1577 May 10 02:36:18 volumio volumio[1116]: ,establishing connection May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio volumio-remote-updater[654]: [2025-05-10 02:36:18] [connect] Successful connection May 10 02:36:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 10 02:36:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:18 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:18 volumio sudo[1624]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 02:36:18 volumio volumio-remote-updater[654]: [2025-05-10 02:36:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746837378 101 May 10 02:36:18 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 10 02:36:18 volumio volumio[1116]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 32 May 10 02:36:18 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root May 10 02:36:18 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 10 02:36:18 volumio volumio[1116]: info: Starting Shairport Sync May 10 02:36:18 volumio sudo[1628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 02:36:19 volumio sudo[1628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 10 02:36:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 10 02:36:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:19 volumio systemd[1]: shairport-sync.service: Consumed 1.044s CPU time. May 10 02:36:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 10 02:36:19 volumio sudo[1628]: pam_unix(sudo:session): session closed for user root May 10 02:36:19 volumio volumio[1116]: info: Shairport-Sync Started May 10 02:36:19 volumio mpd[1626]: 2025-05-10T02:36:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 10 02:36:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 10 02:36:20 volumio volumio[1116]: error: updateQueue error: null May 10 02:36:20 volumio sudo[1591]: pam_unix(sudo:session): session closed for user root May 10 02:36:20 volumio systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. May 10 02:36:20 volumio volumio[1116]: error: updateQueue error: null May 10 02:36:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:20 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:20 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:20 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:20 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:20 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:20 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:21 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 10 02:36:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:21 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:21 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:21 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:21 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:21 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:21 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:22 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:22 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:22 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:22 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:22 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:22 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:23 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:23 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:23 volumio volumio[1116]: error: MPD error: The expression evaluated to a falsy value: May 10 02:36:23 volumio volumio[1116]: assert.ok(self.idling) May 10 02:36:23 volumio volumio[1116]: error: The expression evaluated to a falsy value: May 10 02:36:23 volumio volumio[1116]: assert.ok(self.idling) May 10 02:36:23 volumio volumio[1116]: error: MPD error: The expression evaluated to a falsy value: May 10 02:36:23 volumio volumio[1116]: assert.ok(self.idling) May 10 02:36:23 volumio volumio[1116]: error: The expression evaluated to a falsy value: May 10 02:36:23 volumio volumio[1116]: assert.ok(self.idling) May 10 02:36:23 volumio volumio[1116]: error: updateQueue error: null May 10 02:36:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:23 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:23 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:23 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:23 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:24 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:24 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:24 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:24 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:25 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:25 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:25 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:25 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:25 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:25 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:26 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:26 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:26 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:26 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 10 02:36:26 volumio volumio[1116]: info: Adding plugin bluetooth to MyMusic Plugins May 10 02:36:26 volumio volumio[1116]: info: Adding plugin multiroom to MyMusic Plugins May 10 02:36:26 volumio volumio[1116]: info: Adding plugin metavolumio to MyMusic Plugins May 10 02:36:26 volumio volumio[1116]: info: Adding plugin cd_controller to MyMusic Plugins May 10 02:36:26 volumio volumio[1116]: info: Adding plugin smart_inputs to MyMusic Plugins May 10 02:36:26 volumio volumio[1116]: info: Adding plugin tidalconnect to MyMusic Plugins May 10 02:36:26 volumio volumio[1116]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 10 02:36:28 volumio volumio[1116]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 10 02:36:28 volumio volumio[1116]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 10 02:36:28 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:28 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:28 volumio volumio[1116]: info: Starting MyVolumio Remote Streaming Endpoints May 10 02:36:28 volumio volumio[1116]: info: MyVolumio login type: Token May 10 02:36:29 volumio volumio[1116]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 10 02:36:29 volumio volumio[1116]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 10 02:36:30 volumio volumio[1116]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 10 02:36:30 volumio volumio[1116]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 10 02:36:30 volumio volumio[1116]: info: Streaming services startup May 10 02:36:30 volumio volumio[1116]: info: Starting Streaming Daemon May 10 02:36:30 volumio sudo[1674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 10 02:36:30 volumio sudo[1674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:30 volumio volumio[1116]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 10 02:36:31 volumio sudo[1674]: pam_unix(sudo:session): session closed for user root May 10 02:36:31 volumio volumio[1116]: error: Cannot start Volumio Streaming Daemon May 10 02:36:31 volumio volumio[1116]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 10 02:36:31 volumio volumio[1116]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 10 02:36:31 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:31 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:31 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:31 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:31 volumio volumio[1116]: info: AutoStart - Plugin is starting May 10 02:36:31 volumio volumio[1116]: info: CoreCommandRouter::volumioGetQueue May 10 02:36:31 volumio volumio[1116]: info: CoreStateMachine::getQueue May 10 02:36:31 volumio volumio[1116]: info: CorePlayQueue::getQueue May 10 02:36:31 volumio volumio[1116]: info: AutoStart - start playing May 10 02:36:31 volumio volumio[1116]: info: AutoStart - start playing with no specific position May 10 02:36:31 volumio volumio[1116]: info: CoreCommandRouter::volumioPlay May 10 02:36:31 volumio volumio[1116]: info: CoreStateMachine::play index 0 May 10 02:36:31 volumio volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 02:36:31 volumio volumio[1116]: info: CoreStateMachine::stop May 10 02:36:31 volumio volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 02:36:31 volumio volumio[1116]: info: CoreStateMachine::play index undefined May 10 02:36:31 volumio volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 02:36:31 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:31 volumio volumio[1116]: info: CoreStateMachine::startPlaybackTimer May 10 02:36:31 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:31 volumio volumio[1116]: info: [1746837391694] ControllerWebradio::clearAddPlayTrack May 10 02:36:31 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand stop May 10 02:36:31 volumio volumio[1116]: info: sendMpdCommand stop took 59 milliseconds May 10 02:36:31 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand clear May 10 02:36:31 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:31 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:31 volumio volumio[1116]: info: May 10 02:36:31 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:31 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:31 volumio volumio[1116]: info: sendMpdCommand clear took 17 milliseconds May 10 02:36:31 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand load "https://audio.social3.hr/listen/top_radio_256_mp3/" May 10 02:36:31 volumio volumio[1116]: info: May 10 02:36:31 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:31 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:31 volumio volumio[1116]: info: May 10 02:36:31 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:31 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:31 volumio volumio[1116]: info: May 10 02:36:31 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:31 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:31 volumio volumio[1116]: info: May 10 02:36:31 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:31 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:31 volumio volumio[1116]: error: updateQueue error: null May 10 02:36:31 volumio volumio[1116]: info: ------------------------------ 31ms May 10 02:36:31 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:31 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:32 volumio volumio[1116]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 10 02:36:32 volumio volumio[1116]: error: updateQueue error: null May 10 02:36:32 volumio volumio[1116]: error: updateQueue error: null May 10 02:36:32 volumio volumio[1116]: error: updateQueue error: null May 10 02:36:32 volumio volumio[1116]: error: updateQueue error: null May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand add "https://audio.social3.hr/listen/top_radio_256_mp3/" May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 760ms May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 759ms May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 758ms May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 755ms May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:32 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:32 volumio volumio[1116]: info: sendMpdCommand add "https://audio.social3.hr/listen/top_radio_256_mp3/" took 19 milliseconds May 10 02:36:32 volumio volumio[1116]: info: CoreStateMachine::setConsumeUpdateService mpd May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand play May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:32 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:32 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:32 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:32 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 47ms May 10 02:36:32 volumio volumio[1116]: info: sendMpdCommand play took 29 milliseconds May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 30ms May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 29ms May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 28ms May 10 02:36:32 volumio volumio[1116]: info: ------------------------------ 28ms May 10 02:36:32 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:32 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:32 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:32 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:32 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:32 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:32 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:32 volumio volumio[1116]: info: May 10 02:36:32 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:32 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:32 volumio volumio[1116]: info: sendMpdCommand status took 24 milliseconds May 10 02:36:32 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:32 volumio volumio[1116]: info: sendMpdCommand status took 7 milliseconds May 10 02:36:32 volumio volumio[1116]: info: sendMpdCommand status took 6 milliseconds May 10 02:36:32 volumio volumio[1116]: info: sendMpdCommand status took 4 milliseconds May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:32 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:32 volumio volumio[1116]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 10 02:36:32 volumio volumio[1116]: info: FusionDsp - ---- read samplerate from file: 44100 May 10 02:36:32 volumio volumio[1116]: info: camilladsp stopping service pid 1495... May 10 02:36:33 volumio volumio[1116]: grep: /proc/1495/cmdline: binary file matches May 10 02:36:33 volumio volumio[1116]: grep: /proc/1495/cmdline: binary file matches May 10 02:36:33 volumio volumio[1116]: info: camilladsp service terminated, instance 1 May 10 02:36:33 volumio volumio[1116]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 10 02:36:33 volumio volumio[1116]: info: camilladsp service started and running in background, instance 1 May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 519 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 521ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 519 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 487 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 487 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 487 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 483 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 26ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 26 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 25ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 17 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 16ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 16 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 16ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 8 milliseconds May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio upmpdcli[1700]: writing RSA key May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus stop May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 701ms May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 762ms May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 744ms May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 744ms May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:33 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:33 volumio volumio[1116]: info: May 10 02:36:33 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 279ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 276 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 273 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 242 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 240 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 239 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 239 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 239 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 54ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 13 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 12ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 11 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 6ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 6 milliseconds May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 5ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand status took 4 milliseconds May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1340,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1340,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 1047ms May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 1055ms May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 561ms May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 560ms May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 552ms May 10 02:36:33 volumio volumio[1116]: info: ------------------------------ 551ms May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 375 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 375 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 341 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 341 milliseconds May 10 02:36:33 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 341 milliseconds May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:33 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:33 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:33 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:33 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:33 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:33 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:34 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:34 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:34 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:34 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:34 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:34 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:34 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:34 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:34 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:34 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:34 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:34 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:34 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:34 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:34 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:34 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:34 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"CUBISMO - BAILANDO CHA-CHA-CHA","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:34 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:34 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:34 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:34 volumio volumio[1116]: info: ------------------------------ 782ms May 10 02:36:34 volumio volumio[1116]: info: ------------------------------ 561ms May 10 02:36:34 volumio volumio[1116]: info: ------------------------------ 528ms May 10 02:36:34 volumio volumio[1116]: info: ------------------------------ 523ms May 10 02:36:34 volumio volumio[1116]: info: ------------------------------ 521ms May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:34 volumio volumio[1116]: info: MyVolumio token set successfully May 10 02:36:34 volumio volumio[1116]: info: MYVOLUMIO: Adding device May 10 02:36:34 volumio volumio[1116]: info: MYVOLUMIO: Evaluating Server May 10 02:36:34 volumio volumio[1116]: info: MyVolumio status changed May 10 02:36:34 volumio volumio[1116]: info: Streaming services startup May 10 02:36:34 volumio volumio[1116]: info: Starting Streaming Daemon May 10 02:36:34 volumio volumio[1116]: info: Removing browser output: myVolumio user plan is not superstar May 10 02:36:34 volumio volumio[1116]: info: Removing audio output: May 10 02:36:34 volumio volumio[1116]: info: Stoppping Tunnel 1 May 10 02:36:34 volumio sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 10 02:36:34 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:34 volumio sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 10 02:36:34 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 10 02:36:34 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:34 volumio volumio[1116]: error: Cannot start Volumio Streaming Daemon May 10 02:36:34 volumio volumio[1116]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 10 02:36:34 volumio volumio[1116]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 10 02:36:34 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 10 02:36:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 10 02:36:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 10 02:36:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 10 02:36:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 10 02:36:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 10 02:36:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 10 02:36:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 10 02:36:34 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root May 10 02:36:34 volumio volumio[1116]: info: Remote SSH Stopped May 10 02:36:35 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:35 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:35 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:35 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:35 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:35 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:36 volumio volumio[1116]: info: Setting Geolocation for MyVolumio to eu7 May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:36 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:37 volumio volumio[1116]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:37 volumio volumio[1116]: info: Updating MyVolumio device info May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:37 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:38 volumio volumio[1116]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 10 02:36:38 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:38 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:38 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:38 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:38 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:38 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:39 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:39 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:39 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:39 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:39 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:39 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:40 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:40 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:40 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:40 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:40 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:40 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:41 volumio volumio[1116]: info: MYVOLUMIO: Adding device May 10 02:36:41 volumio volumio[1116]: info: MYVOLUMIO: Evaluating Server May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:41 volumio volumio-remote-updater[654]: No test mode May 10 02:36:41 volumio volumio-remote-updater[654]: No alpha test mode May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 10 02:36:41 volumio volumio[1116]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 10 02:36:41 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 10 02:36:42 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:42 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:42 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:42 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:42 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:42 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:43 volumio volumio[1116]: info: Setting Geolocation for MyVolumio to eu6 May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:43 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 10 02:36:44 volumio volumio[1116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:44 volumio volumio[1116]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 10 02:36:44 volumio volumio[1116]: info: Updating MyVolumio device info May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:44 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:45 volumio volumio[1116]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 10 02:36:45 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:45 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:45 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:45 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:45 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:45 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:46 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:46 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:46 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:46 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:46 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:46 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:47 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:47 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:47 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:47 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:47 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:47 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::volumioGetBrowseSources May 10 02:36:48 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 02:36:49 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:49 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:49 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:49 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:49 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:49 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:50 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:50 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:50 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:50 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:50 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:50 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:50 volumio volumio[1116]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 10 02:36:50 volumio volumio[1116]: info: Completed starting MyVolumio Plugin May 10 02:36:50 volumio volumio[1116]: [Metrics] CommandRouter: 50s 216.18ms May 10 02:36:50 volumio volumio[1116]: info: CoreCommandRouter::volumiosetStartupVolume May 10 02:36:50 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 02:36:50 volumio volumio[1116]: info: VolumeController:: Setting startup Volume 75 May 10 02:36:50 volumio volumio[1116]: info: VolumeController::SetAlsaVolume75 May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::Close All Modals sent May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::Close All Modals sent May 10 02:36:51 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:51 volumio volumio[1116]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 10 02:36:51 volumio volumio[1116]: info: FusionDsp - ---- read samplerate from file: 44100 May 10 02:36:51 volumio volumio[1116]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 10 02:36:51 volumio volumio[1116]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:51 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:52 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:53 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:53 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:53 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:53 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:53 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:53 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:54 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:54 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:54 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:54 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:54 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:54 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:55 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:55 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:55 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:55 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:55 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:55 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:56 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:56 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:56 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:56 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:56 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:56 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:57 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:57 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:57 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:57 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces system playlist update May 10 02:36:57 volumio volumio[1116]: info: Ignoring MPD Status Update May 10 02:36:57 volumio volumio[1116]: info: May 10 02:36:57 volumio volumio[1116]: ---------------------------- MPD announces state update: player May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::getState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand status May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 17ms May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand status took 17 milliseconds May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 17ms May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand status took 16 milliseconds May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 16ms May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand status took 14 milliseconds May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 3ms May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand status took 2 milliseconds May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 10ms May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand status took 9 milliseconds May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 7 milliseconds May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 7 milliseconds May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 6 milliseconds May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 4 milliseconds May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseState May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:57 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:57 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"VIDEOSEX - TKO JE ZGAZIO GOSPODJU MJESEC","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:57 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:57 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:57 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:57 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"VIDEOSEX - TKO JE ZGAZIO GOSPODJU MJESEC","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:57 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:57 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:57 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:57 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"VIDEOSEX - TKO JE ZGAZIO GOSPODJU MJESEC","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:57 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:57 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:57 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:57 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"VIDEOSEX - TKO JE ZGAZIO GOSPODJU MJESEC","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:57 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:57 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 177ms May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 176ms May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 174ms May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 164ms May 10 02:36:57 volumio volumio[1116]: info: sendMpdCommand playlistinfo took 144 milliseconds May 10 02:36:57 volumio volumio[1116]: verbose: ControllerMpd::parseTrackInfo May 10 02:36:57 volumio volumio[1116]: info: ControllerMpd::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::servicePushState May 10 02:36:57 volumio volumio[1116]: info: CorePlayQueue::getTrack 0 May 10 02:36:57 volumio volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27483,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"VIDEOSEX - TKO JE ZGAZIO GOSPODJU MJESEC","artist":"Top radio 256 MP3","album":null,"uri":"https://audio.social3.hr/listen/top_radio_256_mp3/","trackType":""} May 10 02:36:57 volumio volumio[1116]: verbose: CURRENT POSITION 0 May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState stateService play May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::syncState currentStatus play May 10 02:36:57 volumio volumio[1116]: info: Received an update from plugin. extracting info from payload May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: CoreStateMachine::pushState May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioPushState May 10 02:36:57 volumio volumio[1116]: info: ------------------------------ 190ms May 10 02:36:57 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:58 volumio volumio[1116]: info: BOOT COMPLETED May 10 02:36:58 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:58 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:58 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:58 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:58 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:58 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:59 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:59 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:59 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:36:59 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:00 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:00 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:00 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:00 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:00 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:00 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:01 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:01 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:01 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:01 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:01 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:01 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:02 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:02 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:02 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:02 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:02 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:02 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:03 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:03 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:03 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:03 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:03 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:03 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:04 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:04 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:04 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:04 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:04 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:04 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:05 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:05 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:05 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:05 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:05 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:05 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:06 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:06 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:06 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:06 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:06 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:06 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:07 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:07 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:07 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:07 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:07 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:07 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:08 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:09 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:09 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:09 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:09 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:09 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:09 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:10 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:10 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:10 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:10 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:10 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:10 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:11 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:11 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:11 volumio volumio[1116]: info: CoreCommandRouter::volumioGetVisibleSources May 10 02:37:11 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 02:37:11 volumio volumio[1116]: info: CoreCommandRouter::volumioGetQueue May 10 02:37:11 volumio volumio[1116]: info: CoreStateMachine::getQueue May 10 02:37:11 volumio volumio[1116]: info: CorePlayQueue::getQueue May 10 02:37:11 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:11 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:12 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:12 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:12 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:12 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:12 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:12 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:13 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:13 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:13 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:13 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:13 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:13 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:14 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:14 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:14 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:14 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:14 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 10 02:37:14 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:14 volumio volumio[1116]: info: Preload queue cleared May 10 02:37:14 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:15 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:15 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:15 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:15 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:16 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:16 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:16 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:16 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:16 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:17 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:17 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:17 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:17 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 10 02:37:17 volumio volumio[1116]: info: Preload queue cleared May 10 02:37:17 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:17 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:18 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:18 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:18 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:18 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:18 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:18 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:19 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:19 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:19 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:19 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:19 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:19 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:20 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:21 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:22 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:23 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 10 02:37:23 volumio volumio[1116]: info: TuneIn handleBrowseUri: tunein/local May 10 02:37:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:23 volumio volumio[1116]: info: Preload queue cleared May 10 02:37:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:23 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:24 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:25 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:26 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:27 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:27 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:27 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:27 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:27 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:27 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:28 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:28 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:28 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:28 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:28 volumio volumio[1116]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 10 02:37:28 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:28 volumio volumio[1116]: info: CoreCommandRouter::volumioGetState May 10 02:37:29 volumio volumio[1116]: info: READ May 10 02:37:29 volumio volumio[1116]: Use XMLElement.setAttribute instead May 10 02:37:29 volumio volumio[1116]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 02:37:29 volumio volumio[1116]: TypeError: children[i].attr(...).value is not a function May 10 02:37:29 volumio volumio[1116]: at /volumio/app/plugins/music_service/webradio/index.js:286:47 May 10 02:37:29 volumio volumio[1116]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 10 02:37:29 volumio volumio[1116]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 02:37:30 volumio sudo[1997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-10 02:36' May 10 02:37:30 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="5431d94b4ec10a9432d7232ad6f9406bf7be8de2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1e197db88ef99f5883323701bf35f98f4d21ec68" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Apr 26 16:11:36 UTC 2025" VOLUMIO_VERSION="0.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="86d3a07c7fcdc4825f235495fe259558"