-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-04-19 21:12:56 CEST. -- Apr 19 21:11:19 volumio systemd-timedated[1050]: Changed local time to Sat Apr 19 21:11:19 2025 Apr 19 21:11:19 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root Apr 19 21:11:19 volumio volumio-time-update[622]: volumio-time-update-util: System time updated successfully. Apr 19 21:11:19 volumio volumio[883]: Forking 3 albumart workers Apr 19 21:11:19 volumio systemd[1]: Starting Daily apt download activities... Apr 19 21:11:19 volumio systemd[1]: Starting Daily man-db regeneration... Apr 19 21:11:19 volumio systemd[1]: Started Volumio Time Update Utility. Apr 19 21:11:19 volumio ntpd[1008]: Soliciting pool server 213.172.105.106 Apr 19 21:11:19 volumio volumio[883]: info: Plugin mpdemulation is not enabled Apr 19 21:11:19 volumio volumio[883]: info: Loading plugin "rest_api"... Apr 19 21:11:19 volumio volumio[883]: info: Loading plugin "websocket"... Apr 19 21:11:19 volumio systemd[1]: man-db.service: Succeeded. Apr 19 21:11:19 volumio systemd[1]: Started Daily man-db regeneration. Apr 19 21:11:19 volumio volumio[883]: info: Starting Socket.io Server version 2.3.0 Apr 19 21:11:19 volumio volumio[883]: info: Loading plugin "calmradio"... Apr 19 21:11:20 volumio ntpd[1008]: Soliciting pool server 217.144.138.234 Apr 19 21:11:20 volumio ntpd[1008]: Soliciting pool server 195.201.20.16 Apr 19 21:11:20 volumio nmbd[810]: [2025/04/19 21:11:20.269573, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 19 21:11:20 volumio nmbd[810]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 19 21:11:20 volumio systemd[1]: Started Samba NMB Daemon. Apr 19 21:11:20 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 19 21:11:20 volumio nmbd[810]: [2025/04/19 21:11:20.349029, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 19 21:11:20 volumio nmbd[810]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.40 for name WORKGROUP<1d>. Apr 19 21:11:20 volumio nmbd[810]: This response was from IP 192.168.178.81, reporting an IP address of 192.168.178.81. Apr 19 21:11:20 volumio winbindd[1096]: [2025/04/19 21:11:20.855530, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 19 21:11:20 volumio winbindd[1096]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 19 21:11:20 volumio winbindd[1096]: [2025/04/19 21:11:20.972847, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 19 21:11:20 volumio systemd[1]: Started Samba Winbind Daemon. Apr 19 21:11:20 volumio winbindd[1096]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 19 21:11:21 volumio systemd[1]: Starting Samba SMB Daemon... Apr 19 21:11:21 volumio ntpd[1008]: Soliciting pool server 178.63.52.50 Apr 19 21:11:21 volumio ntpd[1008]: Soliciting pool server 185.255.121.15 Apr 19 21:11:21 volumio ntpd[1008]: Soliciting pool server 78.46.204.247 Apr 19 21:11:21 volumio systemd[1]: Started Samba SMB Daemon. Apr 19 21:11:21 volumio systemd[1]: Reached target Multi-User System. Apr 19 21:11:21 volumio smbd[1101]: [2025/04/19 21:11:21.965878, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 19 21:11:21 volumio systemd[1]: Reached target Graphical Interface. Apr 19 21:11:21 volumio smbd[1101]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 19 21:11:22 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 19 21:11:22 volumio ntpd[1008]: Soliciting pool server 85.215.93.134 Apr 19 21:11:22 volumio ntpd[1008]: Soliciting pool server 188.34.165.100 Apr 19 21:11:22 volumio ntpd[1008]: Soliciting pool server 5.189.151.39 Apr 19 21:11:22 volumio ntpd[1008]: Soliciting pool server 80.153.195.191 Apr 19 21:11:22 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 19 21:11:22 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 19 21:11:22 volumio volumio[883]: Starting albumart workers Apr 19 21:11:22 volumio volumio[883]: Starting albumart workers Apr 19 21:11:22 volumio volumio[883]: Starting albumart workers Apr 19 21:11:23 volumio systemd[1]: apt-daily.service: Succeeded. Apr 19 21:11:23 volumio systemd[1]: Started Daily apt download activities. Apr 19 21:11:23 volumio ntpd[1008]: Soliciting pool server 78.47.94.77 Apr 19 21:11:23 volumio ntpd[1008]: Soliciting pool server 85.214.38.116 Apr 19 21:11:23 volumio ntpd[1008]: Soliciting pool server 85.215.166.214 Apr 19 21:11:23 volumio ntpd[1008]: Soliciting pool server 217.160.19.219 Apr 19 21:11:23 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Apr 19 21:11:24 volumio ntpd[1008]: Soliciting pool server 5.75.181.179 Apr 19 21:11:24 volumio ntpd[1008]: Soliciting pool server 62.154.155.69 Apr 19 21:11:24 volumio ntpd[1008]: Soliciting pool server 178.63.52.31 Apr 19 21:11:24 volumio ntpd[1008]: Soliciting pool server 134.34.3.18 Apr 19 21:11:25 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 19 21:11:25 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 19 21:11:25 volumio systemd[1]: Startup finished in 11.962s (kernel) + 20.993s (userspace) = 32.955s. Apr 19 21:11:25 volumio volumio[883]: info: Loading plugin "motherearthradio"... Apr 19 21:11:25 volumio volumio[883]: info: Applying required configuration parameters for plugin motherearthradio Apr 19 21:11:25 volumio volumio[883]: info: [1745089885561] [MotherEarth] API delay: 5 Apr 19 21:11:25 volumio volumio[883]: info: Loading plugin "personal_radio"... Apr 19 21:11:25 volumio volumio-remote-updater[631]: [2025-04-19 21:11:25] [connect] Successful connection Apr 19 21:11:26 volumio volumio[883]: info: ControllerPersonalRadio::constructor Apr 19 21:11:26 volumio volumio[883]: info: Loading plugin "radio_paradise"... Apr 19 21:11:26 volumio volumio[883]: info: Applying required configuration parameters for plugin radio_paradise Apr 19 21:11:26 volumio volumio[883]: info: [1745089886425] [RadioParadise] API delay: 5 Apr 19 21:11:26 volumio volumio[883]: info: Loading plugin "audiophonicsonoff"... Apr 19 21:11:26 volumio volumio[883]: info: Applying required configuration parameters for plugin audiophonicsonoff Apr 19 21:11:26 volumio volumio[883]: info: Audiophonics on/off initiated Apr 19 21:11:26 volumio volumio[883]: info: Loading plugin "audiophonics_evo_sabre"... Apr 19 21:11:26 volumio volumio[883]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Apr 19 21:11:26 volumio volumio[883]: info: Loading plugin "music_services_shield"... Apr 19 21:11:27 volumio volumio[883]: info: Applying required configuration parameters for plugin music_services_shield Apr 19 21:11:27 volumio volumio[883]: info: Loading plugin "Systeminfo"... Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15f27cda does not match aorg 0000000000.00000000 from server@5.189.151.39 xmt 0xebae73e0.0b04382b Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15f524c5 does not match aorg 0000000000.00000000 from server@195.201.20.16 xmt 0xebae73e0.0aa2127a Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15ecf910 does not match aorg 0000000000.00000000 from server@5.75.181.179 xmt 0xebae73e0.0ab06c14 Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15e83832 does not match aorg 0000000000.00000000 from server@62.154.155.69 xmt 0xebae73e0.0a9753da Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15ebe5fe does not match aorg 0000000000.00000000 from server@178.63.52.31 xmt 0xebae73e0.0ace8f92 Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15ef664e does not match aorg 0000000000.00000000 from server@78.47.94.77 xmt 0xebae73e0.0adadc56 Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15f36022 does not match aorg 0000000000.00000000 from server@78.46.204.247 xmt 0xebae73e0.0af5b911 Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15f06b14 does not match aorg 0000000000.00000000 from server@80.153.195.191 xmt 0xebae73e0.0bc963e4 Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15f18036 does not match aorg 0000000000.00000000 from server@85.215.93.134 xmt 0xebae73e0.0b53eb60 Apr 19 21:11:28 volumio ntpd[1008]: receive: Unexpected origin timestamp 0xebae73df.15ede2c9 does not match aorg 0000000000.00000000 from server@217.160.19.219 xmt 0xebae73e0.0b8a2815 Apr 19 21:11:28 volumio volumio[883]: info: Loading plugin "lastfm"... Apr 19 21:11:28 volumio volumio[883]: info: Loading plugin "randomizer"... Apr 19 21:11:29 volumio volumio[883]: info: Applying required configuration parameters for plugin randomizer Apr 19 21:11:29 volumio volumio[883]: info: Loading i18n strings for locale de Apr 19 21:11:29 volumio volumio[883]: Updating browse sources language Apr 19 21:11:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 21:11:29 volumio volumio[883]: info: CoreCommandRouter::initPlayerControls Apr 19 21:11:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:11:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 21:11:29 volumio volumio[883]: Express server listening on port 3000 Apr 19 21:11:29 volumio volumio[883]: [Metrics] WebUI: 15s 752.11ms Apr 19 21:11:29 volumio volumio[883]: info: CoreStateMachine::resetVolumioState Apr 19 21:11:29 volumio volumio[883]: info: CoreStateMachine::getcurrentVolume Apr 19 21:11:29 volumio volumio[883]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 21:11:29 volumio sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 21:11:29 volumio sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:11:29 volumio sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 21:11:29 volumio sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:11:29 volumio sudo[1235]: pam_unix(sudo:session): session closed for user root Apr 19 21:11:29 volumio sudo[1236]: pam_unix(sudo:session): session closed for user root Apr 19 21:11:29 volumio volumio[883]: info: Volumio Network Manager: Network status updated: 0 Apr 19 21:11:29 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.40 from 192.168.178.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 1 Apr 19 21:11:29 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.40 from 192.168.178.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 2 Apr 19 21:11:29 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.40 from 192.168.178.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 3 Apr 19 21:11:29 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.40 from 192.168.178.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 4 Apr 19 21:11:29 volumio volumio-remote-updater[631]: [2025-04-19 21:11:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745089885 101 Apr 19 21:11:30 volumio volumio[883]: 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: 5 Apr 19 21:11:30 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.178.40 from 192.168.178.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 6 Apr 19 21:11:30 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 19 21:11:30 volumio volumio[883]: info: VolumeController:: Volume=85 Mute =false Apr 19 21:11:30 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:11:30 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrackBlock Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:11:30 volumio volumio[883]: info: Reloading queue from file Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 21:11:30 volumio volumio[883]: info: CoreStateMachine::setRepeat true single undefined Apr 19 21:11:30 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:11:30 volumio volumio[883]: info: CoreStateMachine::setRandom false Apr 19 21:11:30 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:11:30 volumio volumio[883]: info: Received Get System Info Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 21:11:30 volumio volumio[883]: info: Discovery: Getting this device information Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:11:30 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:11:30 volumio volumio[883]: info: Listing playlists Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 21:11:30 volumio volumio[883]: info: Setting Device type: Raspberry PI Apr 19 21:11:30 volumio volumio[883]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 19 21:11:30 volumio volumio[883]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Apr 19 21:11:30 volumio volumio[883]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 19 21:11:30 volumio volumio[883]: info: VolumeController:: Volume=85 Mute =false Apr 19 21:11:30 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:11:30 volumio sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 19 21:11:30 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:11:30 volumio volumio[883]: info: Discovery: adding 40cc1dcc-700a-42c5-a28a-9115ec5dc113 Apr 19 21:11:30 volumio volumio[883]: info: Discovery: Found device Volumio Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:30 volumio volumio[883]: info: Discovery: A device disappeared from network Apr 19 21:11:30 volumio volumio[883]: info: Discovery: Device volumio disappeared from network Apr 19 21:11:30 volumio volumio[883]: info: Discovery: adding 40cc1dcc-700a-42c5-a28a-9115ec5dc113 Apr 19 21:11:30 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 19 21:11:30 volumio volumio[883]: info: Discovery: Found device Volumio Apr 19 21:11:30 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:11:30 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:30 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root Apr 19 21:11:30 volumio volumio[883]: info: Upmpdcli Daemon Started Apr 19 21:11:30 volumio volumio[1250]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 19 21:11:31 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 19 21:11:36 volumio volumio[883]: info: Volumio Network Manager: Network status updated: 1 Apr 19 21:11:38 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 19 21:11:38 volumio volumio[1250]: ..................................................................................++++ Apr 19 21:11:40 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 19 21:11:49 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 19 21:11:51 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Apr 19 21:11:51 volumio volumio[883]: info: CoreStateMachine::play index undefined Apr 19 21:11:51 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:11:51 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:51 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:11:51 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:51 volumio volumio[883]: info: [1745089911463] ControllerWebradio::clearAddPlayTrack Apr 19 21:11:54 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Apr 19 21:11:54 volumio volumio[883]: info: CoreStateMachine::play index undefined Apr 19 21:11:54 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:11:54 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:54 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:11:54 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:54 volumio volumio[883]: info: [1745089914567] ControllerWebradio::clearAddPlayTrack Apr 19 21:11:58 volumio volumio[1250]: ..............................................................................................................................................................................................................................++++ Apr 19 21:11:58 volumio volumio[1250]: e is 65537 (0x010001) Apr 19 21:11:58 volumio volumio[1250]: writing RSA key Apr 19 21:11:58 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Apr 19 21:11:58 volumio volumio[883]: info: CoreStateMachine::play index undefined Apr 19 21:11:58 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:11:58 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:58 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:11:58 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:58 volumio volumio[883]: info: [1745089918555] ControllerWebradio::clearAddPlayTrack Apr 19 21:11:59 volumio volumio[883]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion Apr 19 21:11:59 volumio volumio[883]: info: Preparing to generate the ALSA configuration file Apr 19 21:11:59 volumio volumio[883]: info: Asound.conf file unchanged, so no further update is needed Apr 19 21:11:59 volumio volumio[883]: info: Output device has changed, restarting MPD Apr 19 21:11:59 volumio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 21:11:59 volumio volumio[883]: info: Output device has changed, restarting Shairport Sync Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:11:59 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:11:59 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Apr 19 21:11:59 volumio sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 21:11:59 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:11:59 volumio volumio[883]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 21:11:59 volumio volumio[883]: info: ___________ START PLUGINS ___________ Apr 19 21:11:59 volumio volumio[883]: info: ControllerMpd::onStart: Initializing MPD Apr 19 21:11:59 volumio systemd[1]: musicservicesshield.service: Succeeded. Apr 19 21:11:59 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 19 21:11:59 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 19 21:11:59 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 21:11:59 volumio volumio[883]: info: Creating MPD Configuration file Apr 19 21:11:59 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:11:59 volumio volumio[883]: info: [1745089919429] CoreMusicLibrary::Adding element Medienserver Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:59 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:11:59 volumio sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 21:11:59 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Apr 19 21:11:59 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:11:59 volumio systemd[1]: mpd.service: Succeeded. Apr 19 21:11:59 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 21:11:59 volumio systemd[1]: Starting Music Player Daemon... Apr 19 21:11:59 volumio volumio[883]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:11:59 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 21:11:59 volumio systemd[1]: mpd.service: Succeeded. Apr 19 21:11:59 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 21:11:59 volumio systemd[1]: Starting Music Player Daemon... Apr 19 21:11:59 volumio volumio[883]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:11:59 volumio volumio[883]: info: [1745089919631] CoreMusicLibrary::Adding element Last_100 Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:11:59 volumio volumio[883]: info: [1745089919634] CoreMusicLibrary::Adding element Webradio Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 21:11:59 volumio volumio[883]: info: Initializing BBC Radios Apr 19 21:11:59 volumio sudo[1355]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 21:11:59 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:11:59 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:11:59 volumio volumio[883]: info: [1745089919759] CoreMusicLibrary::Adding element SoundCloud Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source SoundCloud Apr 19 21:11:59 volumio volumio[883]: info: [now-playing-config] Config is up to date. Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 21:11:59 volumio volumio[883]: info: Discovery: Getting this device information Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:11:59 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 21:11:59 volumio volumio[883]: info: Adding Calm Radio to Browse Sources Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:11:59 volumio volumio[883]: info: [1745089919815] CoreMusicLibrary::Adding element Calm Radio Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source SoundCloud Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Calm Radio Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:11:59 volumio volumio[883]: info: [1745089919825] CoreMusicLibrary::Adding element Mother Earth Radio Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source SoundCloud Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Calm Radio Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Mother Earth Radio Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:11:59 volumio volumio[883]: info: [1745089919922] CoreMusicLibrary::Adding element Personal Radio Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source SoundCloud Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Calm Radio Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Mother Earth Radio Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Personal Radio Apr 19 21:11:59 volumio kernel: export_store: invalid GPIO 4 Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:11:59 volumio volumio[883]: info: [1745089919930] CoreMusicLibrary::Adding element Radio Paradise Apr 19 21:11:59 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source SoundCloud Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Calm Radio Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Mother Earth Radio Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Personal Radio Apr 19 21:11:59 volumio volumio[883]: Cannot find translation for source Radio Paradise Apr 19 21:11:59 volumio volumio[883]: info: Configuring GPIO pins Apr 19 21:11:59 volumio volumio[883]: error: Plugin audiophonicsonoff failed to start! Error: EINVAL: invalid argument, write Apr 19 21:11:59 volumio volumio[883]: info: EVO SABRE : Starting Plugin Apr 19 21:12:00 volumio sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 19 21:12:00 volumio sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:00 volumio systemd[1]: Reloading. Apr 19 21:12:00 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 21:12:00 volumio volumio[883]: info: [1745089920460] CoreMusicLibrary::Adding element LastFM Apr 19 21:12:00 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 21:12:00 volumio volumio[883]: Cannot find translation for source SoundCloud Apr 19 21:12:00 volumio volumio[883]: Cannot find translation for source Calm Radio Apr 19 21:12:00 volumio volumio[883]: Cannot find translation for source Mother Earth Radio Apr 19 21:12:00 volumio volumio[883]: Cannot find translation for source Personal Radio Apr 19 21:12:00 volumio volumio[883]: Cannot find translation for source Radio Paradise Apr 19 21:12:00 volumio volumio[883]: Cannot find translation for source LastFM Apr 19 21:12:00 volumio volumio[883]: info: [LastFM] scrobbler initiated! Apr 19 21:12:00 volumio volumio[883]: info: [LastFM] extended logging: false Apr 19 21:12:00 volumio volumio[883]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 21:12:00 volumio volumio[883]: info: [LastFM] Left init routine Apr 19 21:12:00 volumio volumio[883]: info: [LastFM] Socket already connected: true Apr 19 21:12:00 volumio volumio[883]: info: Volumio Calling Home Apr 19 21:12:00 volumio volumio[883]: info: [now-playing-app] App is listening on port 4004. Apr 19 21:12:01 volumio volumio[883]: info: MPD Permissions set Apr 19 21:12:01 volumio volumio[883]: info: MPD Permissions set Apr 19 21:12:01 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 19 21:12:01 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Apr 19 21:12:01 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 19 21:12:01 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:01 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 19 21:12:01 volumio volumio[883]: info: Volumio called home Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio mpd[1362]: Apr 19 21:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:01 volumio systemd[1]: Started Music Player Daemon. Apr 19 21:12:01 volumio sudo[1371]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:01 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 19 21:12:01 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:01 volumio sudo[1340]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:02 volumio volumio[883]: info: [LastFM] finished init: Authenticated successfully! Apr 19 21:12:02 volumio volumio[883]: info: EVO SABRE : systemd cmd daemon-reload : success Apr 19 21:12:02 volumio sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Apr 19 21:12:02 volumio volumio[883]: info: MPD running with PID1362 Apr 19 21:12:02 volumio volumio[883]: ,establishing connection Apr 19 21:12:02 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:02 volumio sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Apr 19 21:12:02 volumio volumio[1438]: cset: --> shielding system active with Apr 19 21:12:02 volumio sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:02 volumio volumio[883]: info: Starting Shairport Sync Apr 19 21:12:02 volumio volumio[1438]: cset: "system" cpuset of CPUSPEC(3) with 130 tasks running Apr 19 21:12:02 volumio volumio[1438]: cset: "user" cpuset of CPUSPEC(0-2) with 3 tasks running Apr 19 21:12:02 volumio volumio[883]: info: Starting Shairport Sync Apr 19 21:12:02 volumio systemd[1]: Started OLED Display Service for EVO SABRE. Apr 19 21:12:02 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 19 21:12:02 volumio sudo[1474]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/app ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/app/index.js volumio Apr 19 21:12:02 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:02 volumio lircd[848]: lircd-0.10.1[848]: Notice: caught signal Apr 19 21:12:02 volumio lircd-0.10.1[848]: Notice: caught signal Apr 19 21:12:02 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:02 volumio systemd[1]: Stopping Flexible IR remote input/output application support... Apr 19 21:12:02 volumio systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Apr 19 21:12:02 volumio systemd[1]: lircd.service: Succeeded. Apr 19 21:12:02 volumio systemd[1]: Stopped Flexible IR remote input/output application support. Apr 19 21:12:02 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 21:12:02 volumio systemd[1]: Starting lircd(8) initialization helper tool... Apr 19 21:12:02 volumio volumio[883]: info: Starting Shairport Sync Apr 19 21:12:02 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:02 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:02 volumio volumio[883]: (node:883) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Apr 19 21:12:02 volumio volumio[883]: (Use `node --trace-deprecation ...` to show where the warning was created) Apr 19 21:12:02 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 21:12:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 21:12:02 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 19 21:12:02 volumio volumio[883]: (node:883) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Apr 19 21:12:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 21:12:02 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 21:12:02 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 21:12:02 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:02 volumio lircd-setup[1483]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Apr 19 21:12:02 volumio lircd-setup[1483]: parser = configparser.SafeConfigParser() Apr 19 21:12:02 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:02 volumio systemd[1]: lircd-setup.service: Succeeded. Apr 19 21:12:02 volumio systemd[1]: Started lircd(8) initialization helper tool. Apr 19 21:12:03 volumio systemd[1]: Starting Flexible IR remote input/output application support... Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Info: lircd: Opening log, level: Info Apr 19 21:12:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 21:12:03 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 19 21:12:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Version: lircd 0.10.1 Apr 19 21:12:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: System info: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux Apr 19 21:12:03 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:03 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Info: Initial device: /dev/lirc0 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Info: [lirc] protocol is enabled Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Info: Initial device: /dev/lirc0 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Info: [lirc] protocol is enabled Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: driver: default Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: output: /var/run/lirc/lircd Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: nodaemon: 1 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: logfile: syslog Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: immediate-init: 0 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: permission: 666 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: driver-options: Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: listen: 0 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: connect: (null) Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: userelease: 0 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: effective_user: (null) Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: release_suffix: _EVUP Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: allow_simulate: 0 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: repeat_max: 600 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: configfile: /etc/lirc/lircd.conf Apr 19 21:12:03 volumio lircd[1498]: lircd-0.10.1[1498]: Info: lircd: Opening log, level: Info Apr 19 21:12:03 volumio lircd[1498]: lircd-0.10.1[1498]: Notice: Using systemd fd Apr 19 21:12:03 volumio lircd[1498]: lircd-0.10.1[1498]: Warning: Running as root Apr 19 21:12:03 volumio lircd[1498]: lircd-0.10.1[1498]: Info: Using remote: ApEvo. Apr 19 21:12:03 volumio lircd[1498]: lircd-0.10.1[1498]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Options: dynamic_codes: (null) Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Current driver: default Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Driver API version: 3 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Driver version: 0.10.0 Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Info: lircd: Opening log, level: Info Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: Using systemd fd Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Warning: Running as root Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Info: Using remote: ApEvo. Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: lircd(default) ready, using /var/run/lirc/lircd Apr 19 21:12:03 volumio systemd[1]: Started Flexible IR remote input/output application support. Apr 19 21:12:03 volumio sudo[1463]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:03 volumio volumio[883]: error: MPD error: The expression evaluated to a falsy value: Apr 19 21:12:03 volumio volumio[883]: assert.ok(self.idling) Apr 19 21:12:03 volumio volumio[883]: error: The expression evaluated to a falsy value: Apr 19 21:12:03 volumio volumio[883]: assert.ok(self.idling) Apr 19 21:12:03 volumio volumio[883]: error: MPD error: The expression evaluated to a falsy value: Apr 19 21:12:03 volumio volumio[883]: assert.ok(self.idling) Apr 19 21:12:03 volumio volumio[883]: error: The expression evaluated to a falsy value: Apr 19 21:12:03 volumio volumio[883]: assert.ok(self.idling) Apr 19 21:12:03 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:03 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:03 volumio volumio[883]: info: Shairport-Sync Started Apr 19 21:12:03 volumio volumio[883]: Error adding Membership: Error: addMembership EINVAL Apr 19 21:12:03 volumio volumio[883]: info: Shairport-Sync Started Apr 19 21:12:03 volumio volumio[883]: info: Shairport-Sync Started Apr 19 21:12:03 volumio volumio[883]: info: EVO SABRE : systemd cmd restart lircd.service : success Apr 19 21:12:03 volumio volumio[883]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Apr 19 21:12:03 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Apr 19 21:12:03 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:03 volumio volumio[883]: info: Completed starting Core Plugins Apr 19 21:12:03 volumio volumio[883]: info: ------------------------------------------- Apr 19 21:12:03 volumio volumio[883]: info: ----- MyVolumio plugins startup ---- Apr 19 21:12:03 volumio volumio[883]: info: ------------------------------------------- Apr 19 21:12:03 volumio volumio[883]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 21:12:03 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Apr 19 21:12:03 volumio lircd[1498]: lircd-0.10.1[1498]: Notice: accepted new client on /var/run/lirc/lircd Apr 19 21:12:03 volumio lircd[1498]: lircd-0.10.1[1498]: Info: [lirc] protocol is enabled Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Notice: accepted new client on /var/run/lirc/lircd Apr 19 21:12:03 volumio lircd-0.10.1[1498]: Info: [lirc] protocol is enabled Apr 19 21:12:03 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:03 volumio sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Apr 19 21:12:03 volumio sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:03 volumio volumio[883]: error: updateQueue error: null Apr 19 21:12:03 volumio volumio[883]: info: EVO SABRE : systemd cmd restart irexec.service : success Apr 19 21:12:03 volumio systemd[1]: Reloading. Apr 19 21:12:04 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 19 21:12:04 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 19 21:12:04 volumio sudo[1512]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:04 volumio volumio[883]: info: succeeded Apr 19 21:12:07 volumio volumio[883]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 19 21:12:09 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 19 21:12:09 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:09 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:09 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:09 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:09 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:09 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:09 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:10 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:10 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:10 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:10 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:10 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:11 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:11 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:11 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:11 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:11 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 19 21:12:11 volumio volumio[883]: info: Adding plugin bluetooth to MyMusic Plugins Apr 19 21:12:11 volumio volumio[883]: info: Adding plugin multiroom to MyMusic Plugins Apr 19 21:12:11 volumio volumio[883]: info: Adding plugin metavolumio to MyMusic Plugins Apr 19 21:12:11 volumio volumio[883]: info: Adding plugin cd_controller to MyMusic Plugins Apr 19 21:12:11 volumio volumio[883]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 19 21:12:11 volumio volumio[883]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 19 21:12:11 volumio volumio[883]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 19 21:12:13 volumio volumio[883]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 19 21:12:13 volumio volumio[883]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 19 21:12:13 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:13 volumio volumio[883]: info: Starting MyVolumio Remote Streaming Endpoints Apr 19 21:12:13 volumio volumio[883]: info: MyVolumio login type: Token Apr 19 21:12:13 volumio volumio[883]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 19 21:12:13 volumio volumio[883]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 19 21:12:14 volumio volumio[883]: info: Starting Streaming Service Transparent Proxy Apr 19 21:12:14 volumio volumio[883]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 19 21:12:14 volumio volumio[883]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 19 21:12:14 volumio volumio[883]: info: Streaming services startup Apr 19 21:12:14 volumio volumio[883]: info: Starting Streaming Daemon Apr 19 21:12:14 volumio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 21:12:15 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:15 volumio volumio[883]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 19 21:12:15 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:15 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:15 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:15 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Apr 19 21:12:15 volumio volumio[883]: info: CoreStateMachine::play index undefined Apr 19 21:12:15 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:12:15 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:15 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:12:15 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:15 volumio volumio[883]: info: [1745089935281] ControllerWebradio::clearAddPlayTrack Apr 19 21:12:15 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:12:15 volumio volumio[883]: error: Cannot start Volumio Streaming Daemon Apr 19 21:12:15 volumio volumio[883]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 19 21:12:15 volumio volumio[883]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 19 21:12:15 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:15 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:15 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:15 volumio volumio[883]: info: sendMpdCommand stop took 36 milliseconds Apr 19 21:12:15 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:12:15 volumio volumio[883]: info: Apr 19 21:12:15 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:15 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:15 volumio volumio[883]: info: sendMpdCommand clear took 24 milliseconds Apr 19 21:12:15 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1809057" Apr 19 21:12:15 volumio volumio[883]: info: Apr 19 21:12:15 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:15 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:15 volumio volumio[883]: info: Apr 19 21:12:15 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:15 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:15 volumio volumio[883]: error: updateQueue error: null Apr 19 21:12:15 volumio volumio[883]: info: ------------------------------ 13ms Apr 19 21:12:15 volumio volumio[883]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:16 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1809057" took 1112 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 1112ms Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 1111ms Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:16 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:16 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 63ms Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand play took 92 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 91ms Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 90ms Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand status took 20 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand status took 13 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:16 volumio volumio[883]: info: Apr 19 21:12:16 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand status took 41 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand playlistinfo took 39 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:16 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:16 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:16 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:16 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:16 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 151ms Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 162ms Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 156ms Apr 19 21:12:16 volumio volumio[883]: info: MyVolumio token set successfully Apr 19 21:12:16 volumio volumio[883]: info: MYVOLUMIO: Adding device Apr 19 21:12:16 volumio volumio[883]: info: MYVOLUMIO: Evaluating Server Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand playlistinfo took 130 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand playlistinfo took 133 milliseconds Apr 19 21:12:16 volumio volumio[883]: info: sendMpdCommand playlistinfo took 134 milliseconds Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:16 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:16 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:16 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:16 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:16 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:16 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:16 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:16 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 275ms Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 260ms Apr 19 21:12:16 volumio volumio[883]: info: ------------------------------ 269ms Apr 19 21:12:16 volumio volumio[883]: info: [LastFM] Current track has sufficient metadata: title (Costa Del Mar (Original)) and artist ((#1 - 9/2000) Costa Del Mar (Original)) passed on explicitly Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: STREAMING PROXY: Starting server on port 3245 Apr 19 21:12:17 volumio volumio[883]: Node JS runtime: 14 Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:17 volumio volumio[883]: info: MyVolumio status changed Apr 19 21:12:17 volumio volumio[883]: info: Streaming services startup Apr 19 21:12:17 volumio volumio[883]: info: Starting Streaming Daemon Apr 19 21:12:17 volumio volumio[883]: info: Removing browser output: myVolumio user plan is not superstar Apr 19 21:12:17 volumio volumio[883]: info: Removing audio output: Apr 19 21:12:17 volumio volumio[883]: info: Stoppping Tunnel 1 Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:17 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:17 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:17 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:17 volumio sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 21:12:17 volumio sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:17 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:17 volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:17 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:17 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:17 volumio volumio[883]: info: ------------------------------ 90ms Apr 19 21:12:17 volumio volumio[883]: info: sendMpdCommand status took 89 milliseconds Apr 19 21:12:17 volumio volumio[883]: info: ------------------------------ 101ms Apr 19 21:12:17 volumio volumio[883]: info: sendMpdCommand status took 101 milliseconds Apr 19 21:12:17 volumio volumio[883]: info: ------------------------------ 101ms Apr 19 21:12:17 volumio volumio[883]: info: sendMpdCommand status took 101 milliseconds Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:17 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:17 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:17 volumio volumio[883]: info: Apr 19 21:12:17 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:17 volumio volumio[883]: info: ------------------------------ 38ms Apr 19 21:12:17 volumio volumio[883]: info: sendMpdCommand status took 38 milliseconds Apr 19 21:12:17 volumio volumio[883]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 19 21:12:17 volumio volumio[883]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 19 21:12:17 volumio volumio[883]: info: ------------------------------ 19ms Apr 19 21:12:17 volumio volumio[883]: info: sendMpdCommand status took 11 milliseconds Apr 19 21:12:17 volumio volumio[883]: info: ------------------------------ 10ms Apr 19 21:12:17 volumio volumio[883]: info: sendMpdCommand status took 10 milliseconds Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:17 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:17 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1688,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:17 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:17 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:17 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1688,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:17 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:17 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:17 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:17 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:17 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1688,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:17 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:17 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:17 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:17 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:17 volumio volumio[883]: info: ------------------------------ 175ms Apr 19 21:12:18 volumio volumio[883]: info: ------------------------------ 186ms Apr 19 21:12:18 volumio volumio[883]: info: ------------------------------ 186ms Apr 19 21:12:18 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:18 volumio volumio[883]: error: Cannot start Volumio Streaming Daemon Apr 19 21:12:18 volumio volumio[883]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 19 21:12:18 volumio volumio[883]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 19 21:12:18 volumio volumio[883]: info: sendMpdCommand playlistinfo took 80 milliseconds Apr 19 21:12:18 volumio volumio[883]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 19 21:12:18 volumio volumio[883]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 19 21:12:18 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:18 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:18 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:18 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:18 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1779,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:18 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:18 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:18 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:18 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1779,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:18 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:18 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:18 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:18 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:18 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1779,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:18 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:18 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:18 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:18 volumio volumio[883]: info: ------------------------------ 172ms Apr 19 21:12:18 volumio volumio[883]: info: ------------------------------ 148ms Apr 19 21:12:18 volumio volumio[883]: info: ------------------------------ 141ms Apr 19 21:12:18 volumio volumio[883]: info: [LastFM] Split composite title into artist: Lunar Twin and title: Waves (CDM Edit) Apr 19 21:12:18 volumio volumio[883]: info: Setting Geolocation for MyVolumio to eu7 Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:18 volumio volumio[883]: info: Remote SSH Stopped Apr 19 21:12:18 volumio volumio[883]: error: [LastFM] track info request failed with error: Track not found Apr 19 21:12:18 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:18 volumio volumio[883]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Waves (CDM Edit)","url":"https://www.last.fm/music/Lunar+Twin/_/Waves+(CDM+Edit)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"28","playcount":"113","artist":{"name":"Lunar Twin","url":"https://www.last.fm/music/Lunar+Twin"},"userplaycount":"10","userloved":"0","toptags":""}} Apr 19 21:12:18 volumio volumio[883]: info: [LastFM] Updated missing track duration: 0 Apr 19 21:12:18 volumio volumio[883]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 19 21:12:19 volumio volumio[883]: info: Updating MyVolumio device info Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioStop Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::stop Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrackBlock Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::serviceStop Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::serviceStop Apr 19 21:12:19 volumio volumio[883]: info: [1745089939345] ControllerWebradio::stop Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:12:19 volumio volumio[883]: info: Apr 19 21:12:19 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:19 volumio volumio[883]: info: sendMpdCommand stop took 6 milliseconds Apr 19 21:12:19 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:19 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:19 volumio volumio[883]: info: Apr 19 21:12:19 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:19 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:19 volumio volumio[883]: info: Apr 19 21:12:19 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:19 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:19 volumio volumio[883]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 21:12:19 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:19 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:19 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:19 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:19 volumio volumio[883]: info: ------------------------------ 31ms Apr 19 21:12:19 volumio volumio[883]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 19 21:12:19 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:19 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:19 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:19 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:19 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:19 volumio volumio[883]: info: ------------------------------ 44ms Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:19 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:19 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:19 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:19 volumio volumio[883]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::play index undefined Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: info: [1745089940502] ControllerWebradio::clearAddPlayTrack Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand stop took 1 milliseconds Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:20 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand clear took 1 milliseconds Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1809057" Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:20 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:20 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:20 volumio volumio[883]: error: updateQueue error: null Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 3ms Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:20 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1809057" took 106 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 106ms Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 105ms Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:20 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:20 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 4ms Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand play took 3 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 2ms Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 1ms Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand status took 3 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:20 volumio volumio[883]: info: Apr 19 21:12:20 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand status took 4 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:20 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:20 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:20 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:20 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:20 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 37ms Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 48ms Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 49ms Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand playlistinfo took 43 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 19 21:12:20 volumio volumio[883]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 19 21:12:20 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:20 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:20 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:20 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:20 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:20 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:20 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 99ms Apr 19 21:12:20 volumio volumio[883]: info: ------------------------------ 96ms Apr 19 21:12:20 volumio volumio[883]: info: [LastFM] Current track has sufficient metadata: title (Costa Del Mar (Original)) and artist ((#1 - 9/2000) Costa Del Mar (Original)) passed on explicitly Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:20 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:20 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:20 volumio volumio[883]: error: [LastFM] track info request failed with error: Track not found Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:21 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:21 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:21 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:21 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 5ms Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand status took 4 milliseconds Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 3ms Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 3ms Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:21 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:21 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:21 volumio volumio[883]: info: Apr 19 21:12:21 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 10ms Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand status took 9 milliseconds Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 4ms Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand status took 3 milliseconds Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 3ms Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:21 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:21 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:21 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:21 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:21 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:21 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:21 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:21 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:21 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 71ms Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 76ms Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 76ms Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand playlistinfo took 72 milliseconds Apr 19 21:12:21 volumio volumio[883]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 19 21:12:21 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:21 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:21 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:21 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:21 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:21 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:21 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:21 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:21 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:21 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 126ms Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 120ms Apr 19 21:12:21 volumio volumio[883]: info: ------------------------------ 119ms Apr 19 21:12:21 volumio volumio[883]: info: [LastFM] Split composite title into artist: Lunar Twin and title: Waves (CDM Edit) Apr 19 21:12:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:21 volumio volumio[883]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Waves (CDM Edit)","url":"https://www.last.fm/music/Lunar+Twin/_/Waves+(CDM+Edit)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"28","playcount":"113","artist":{"name":"Lunar Twin","url":"https://www.last.fm/music/Lunar+Twin"},"userplaycount":"10","userloved":"0","toptags":""}} Apr 19 21:12:21 volumio volumio[883]: info: [LastFM] Updated missing track duration: 0 Apr 19 21:12:22 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:22 volumio volumio[883]: info: MYVOLUMIO: Adding device Apr 19 21:12:22 volumio volumio[883]: info: MYVOLUMIO: Evaluating Server Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioStop Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::stop Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrackBlock Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::serviceStop Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::serviceStop Apr 19 21:12:23 volumio volumio[883]: info: [1745089943258] ControllerWebradio::stop Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: Apr 19 21:12:23 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:23 volumio volumio[883]: info: sendMpdCommand stop took 24 milliseconds Apr 19 21:12:23 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:23 volumio volumio[883]: info: Apr 19 21:12:23 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:23 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:23 volumio volumio[883]: info: Apr 19 21:12:23 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:23 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:23 volumio volumio[883]: info: sendMpdCommand status took 4 milliseconds Apr 19 21:12:23 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:23 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:23 volumio volumio[883]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 19 21:12:23 volumio volumio[883]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 21:12:23 volumio volumio[883]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 19 21:12:23 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:23 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:23 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:23 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:23 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:23 volumio volumio[883]: info: ------------------------------ 42ms Apr 19 21:12:23 volumio volumio[883]: info: ------------------------------ 41ms Apr 19 21:12:23 volumio volumio[883]: info: ------------------------------ 40ms Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:23 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:23 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:23 volumio volumio[883]: info: Setting Geolocation for MyVolumio to eu7 Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:23 volumio volumio[883]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::play index undefined Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: info: [1745089944520] ControllerWebradio::clearAddPlayTrack Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand stop took 0 milliseconds Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:24 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand clear took 0 milliseconds Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1809057" Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:24 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:24 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:24 volumio volumio[883]: error: updateQueue error: null Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 2ms Apr 19 21:12:24 volumio volumio[883]: info: Updating MyVolumio device info Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1809057" took 94 milliseconds Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:24 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 97ms Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 97ms Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand play took 2 milliseconds Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:24 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:24 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 5ms Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 3ms Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 2ms Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:24 volumio volumio[883]: info: Apr 19 21:12:24 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand status took 4 milliseconds Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand status took 18 milliseconds Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:24 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:24 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:24 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:24 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:24 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 65ms Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 71ms Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 71ms Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand playlistinfo took 49 milliseconds Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand playlistinfo took 52 milliseconds Apr 19 21:12:24 volumio volumio[883]: info: sendMpdCommand playlistinfo took 53 milliseconds Apr 19 21:12:24 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:24 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:24 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:24 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:24 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:24 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:24 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 114ms Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 113ms Apr 19 21:12:24 volumio volumio[883]: info: ------------------------------ 113ms Apr 19 21:12:24 volumio volumio[883]: info: [LastFM] Current track has sufficient metadata: title (Costa Del Mar (Original)) and artist ((#1 - 9/2000) Costa Del Mar (Original)) passed on explicitly Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:24 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:24 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:24 volumio volumio[883]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 19 21:12:25 volumio volumio[883]: error: [LastFM] track info request failed with error: Track not found Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:25 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:25 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:25 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:25 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 25ms Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand status took 23 milliseconds Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 22ms Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand status took 3 milliseconds Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 3ms Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:25 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:25 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:25 volumio volumio[883]: info: Apr 19 21:12:25 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 6ms Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand status took 5 milliseconds Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 4ms Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand status took 3 milliseconds Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 3ms Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:25 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:25 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:25 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:25 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:25 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:25 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:25 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:25 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:25 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:25 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:25 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:25 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 104ms Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 102ms Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 82ms Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand playlistinfo took 75 milliseconds Apr 19 21:12:25 volumio volumio[883]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 19 21:12:25 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:25 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:25 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:25 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:25 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:25 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:25 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:25 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:25 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:25 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:25 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:25 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:25 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 163ms Apr 19 21:12:25 volumio volumio[883]: info: ------------------------------ 159ms Apr 19 21:12:25 volumio volumio[883]: info: [LastFM] Split composite title into artist: Lunar Twin and title: Waves (CDM Edit) Apr 19 21:12:25 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:25 volumio volumio[883]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Waves (CDM Edit)","url":"https://www.last.fm/music/Lunar+Twin/_/Waves+(CDM+Edit)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"28","playcount":"113","artist":{"name":"Lunar Twin","url":"https://www.last.fm/music/Lunar+Twin"},"userplaycount":"10","userloved":"0","toptags":""}} Apr 19 21:12:25 volumio volumio[883]: info: [LastFM] Updated missing track duration: 0 Apr 19 21:12:26 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:27 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:28 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:28 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 21:12:28 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 19 21:12:28 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:29 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 21:12:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 21:12:29 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 19 21:12:29 volumio volumio-remote-updater[631]: No test mode Apr 19 21:12:29 volumio volumio-remote-updater[631]: No alpha test mode Apr 19 21:12:30 volumio volumio[883]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES


NEW ADDITIONS

 

 

","title":"Update v3.799","updateavailable":true} Apr 19 21:12:30 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 19 21:12:30 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:31 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:32 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:33 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 19 21:12:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 19 21:12:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 19 21:12:33 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 21:12:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 21:12:34 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:35 volumio volumio[883]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 19 21:12:35 volumio volumio[883]: info: Completed starting MyVolumio Plugin Apr 19 21:12:35 volumio volumio[883]: [Metrics] CommandRouter: 80s 723.03ms Apr 19 21:12:35 volumio volumio[883]: info: CoreCommandRouter::volumiosetStartupVolume Apr 19 21:12:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 21:12:35 volumio volumio[883]: info: VolumeController:: Setting startup Volume 85 Apr 19 21:12:35 volumio volumio[883]: info: VolumeController::SetAlsaVolume85 Apr 19 21:12:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 21:12:35 volumio volumio[883]: info: CoreCommandRouter::Close All Modals sent Apr 19 21:12:35 volumio volumio[883]: info: CoreCommandRouter::Close All Modals sent Apr 19 21:12:35 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:35 volumio volumio[883]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 19 21:12:35 volumio volumio[883]: aplay: main:828: audio open error: Device or resource busy Apr 19 21:12:35 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioStop Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::stop Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrackBlock Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::serviceStop Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::serviceStop Apr 19 21:12:36 volumio volumio[883]: info: [1745089956711] ControllerWebradio::stop Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:36 volumio volumio[883]: info: Apr 19 21:12:36 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:36 volumio volumio[883]: info: sendMpdCommand stop took 26 milliseconds Apr 19 21:12:36 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:36 volumio volumio[883]: info: Apr 19 21:12:36 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:36 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:36 volumio volumio[883]: info: Apr 19 21:12:36 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:36 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:36 volumio volumio[883]: info: sendMpdCommand status took 12 milliseconds Apr 19 21:12:36 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:36 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:36 volumio volumio[883]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 19 21:12:36 volumio volumio[883]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 19 21:12:36 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:36 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:36 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:36 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:36 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:36 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:36 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 19 21:12:36 volumio volumio[883]: info: ------------------------------ 84ms Apr 19 21:12:36 volumio volumio[883]: info: ------------------------------ 83ms Apr 19 21:12:36 volumio volumio[883]: info: ------------------------------ 72ms Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:36 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:36 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:36 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:37 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:37 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:37 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:37 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:37 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:38 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:38 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:38 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:38 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:38 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:39 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:39 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:39 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:39 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:39 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:40 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:40 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:40 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:40 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:40 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:41 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:41 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:41 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:41 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:41 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:42 volumio volumio[883]: info: BOOT COMPLETED Apr 19 21:12:42 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:42 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:42 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:42 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:42 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:43 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:43 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:43 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:43 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:43 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:44 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:44 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:44 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:44 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:44 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:45 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:45 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:45 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:45 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:45 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:46 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:46 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:46 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:46 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:46 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:47 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:47 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:47 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:47 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:47 volumio volumio[883]: info: CoreCommandRouter::volumioPlay Apr 19 21:12:47 volumio volumio[883]: info: CoreStateMachine::play index undefined Apr 19 21:12:47 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 21:12:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:47 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer Apr 19 21:12:47 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:47 volumio volumio[883]: info: [1745089967850] ControllerWebradio::clearAddPlayTrack Apr 19 21:12:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 21:12:47 volumio volumio[883]: info: sendMpdCommand stop took 1 milliseconds Apr 19 21:12:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 21:12:47 volumio volumio[883]: info: Apr 19 21:12:47 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:47 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:47 volumio volumio[883]: info: sendMpdCommand clear took 1 milliseconds Apr 19 21:12:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1809057" Apr 19 21:12:47 volumio volumio[883]: info: Apr 19 21:12:47 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:47 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:47 volumio volumio[883]: info: Apr 19 21:12:47 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:47 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:47 volumio volumio[883]: error: updateQueue error: null Apr 19 21:12:47 volumio volumio[883]: info: ------------------------------ 2ms Apr 19 21:12:47 volumio volumio[883]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1809057" took 97 milliseconds Apr 19 21:12:47 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 21:12:47 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play Apr 19 21:12:47 volumio volumio[883]: info: Apr 19 21:12:47 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:47 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:47 volumio volumio[883]: info: ------------------------------ 98ms Apr 19 21:12:47 volumio volumio[883]: info: ------------------------------ 97ms Apr 19 21:12:47 volumio volumio[883]: info: sendMpdCommand play took 1 milliseconds Apr 19 21:12:47 volumio volumio[883]: info: Apr 19 21:12:47 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:47 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:47 volumio volumio[883]: info: Apr 19 21:12:47 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:47 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:47 volumio volumio[883]: info: ------------------------------ 2ms Apr 19 21:12:47 volumio volumio[883]: info: ------------------------------ 1ms Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 3 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 3 milliseconds Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 11 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 11 milliseconds Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus stop Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 53ms Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 74ms Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 73ms Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 45 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 43 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 1 milliseconds Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 101ms Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 93ms Apr 19 21:12:48 volumio volumio[883]: info: [LastFM] Current track has sufficient metadata: title (Costa Del Mar (Original)) and artist ((#1 - 9/2000) Costa Del Mar (Original)) passed on explicitly Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Costa-Del-Mar-Original","artist":"(#1 - 9/2000) Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 78ms Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: error: [LastFM] track info request failed with error: Track not found Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::getQueue Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getQueue Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:48 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:48 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:48 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:48 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 14ms Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 12 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 11ms Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 3 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 2ms Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:48 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces system playlist update Apr 19 21:12:48 volumio volumio[883]: info: Ignoring MPD Status Update Apr 19 21:12:48 volumio volumio[883]: info: Apr 19 21:12:48 volumio volumio[883]: ---------------------------- MPD announces state update: player Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::getState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 39ms Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 38 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 33ms Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 22 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 21ms Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand status took 2 milliseconds Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseState Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 107ms Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 106ms Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 98ms Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 58 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 57 milliseconds Apr 19 21:12:48 volumio volumio[883]: info: sendMpdCommand playlistinfo took 58 milliseconds Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ControllerMpd::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::servicePushState Apr 19 21:12:48 volumio volumio[883]: info: CorePlayQueue::getTrack 0 Apr 19 21:12:48 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Lunar Twin - Waves (CDM Edit)","artist":"Costa Del Mar (Original)","album":null,"uri":"http://54.38.43.201:8057/Costa-Del-Mar-Original","trackType":""} Apr 19 21:12:48 volumio volumio[883]: verbose: CURRENT POSITION 0 Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState stateService play Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::syncState currentStatus play Apr 19 21:12:48 volumio volumio[883]: info: Received an update from plugin. extracting info from payload Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: CoreStateMachine::pushState Apr 19 21:12:48 volumio volumio[883]: info: CoreCommandRouter::volumioPushState Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 175ms Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 169ms Apr 19 21:12:48 volumio volumio[883]: info: ------------------------------ 145ms Apr 19 21:12:48 volumio volumio[883]: info: [LastFM] Split composite title into artist: Lunar Twin and title: Waves (CDM Edit) Apr 19 21:12:49 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:50 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:51 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:52 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:53 volumio volumio[883]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Waves (CDM Edit)","url":"https://www.last.fm/music/Lunar+Twin/_/Waves+(CDM+Edit)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"28","playcount":"113","artist":{"name":"Lunar Twin","url":"https://www.last.fm/music/Lunar+Twin"},"userplaycount":"10","userloved":"0","toptags":""}} Apr 19 21:12:53 volumio volumio[883]: info: [LastFM] Updated missing track duration: 0 Apr 19 21:12:53 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:54 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:55 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Apr 19 21:12:55 volumio volumio[883]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Apr 19 21:12:55 volumio volumio[883]: info: PLUGIN onReboot : networkfs Apr 19 21:12:55 volumio sudo[1722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Multimedia Apr 19 21:12:55 volumio sudo[1722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 21:12:55 volumio sudo[1722]: pam_unix(sudo:session): session closed for user root Apr 19 21:12:55 volumio volumio[883]: umount: /mnt/NAS/Multimedia: not mounted. Apr 19 21:12:55 volumio volumio[883]: error: Cannot umount share Multimedia : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/Multimedia Apr 19 21:12:55 volumio volumio[883]: umount: /mnt/NAS/Multimedia: not mounted. Apr 19 21:12:55 volumio volumio[883]: info: PLUGIN onReboot : audiophonicsonoff Apr 19 21:12:55 volumio volumio[883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 21:12:55 volumio volumio[883]: TypeError: Cannot read property 'writeSync' of undefined Apr 19 21:12:55 volumio volumio[883]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Apr 19 21:12:55 volumio volumio[883]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Apr 19 21:12:55 volumio volumio[883]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Apr 19 21:12:55 volumio volumio[883]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 19 21:12:55 volumio volumio[883]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 19 21:12:55 volumio volumio[883]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Apr 19 21:12:55 volumio volumio[883]: at CoreCommandRouter.reboot (/volumio/app/index.js:1344:22) Apr 19 21:12:55 volumio volumio[883]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Apr 19 21:12:55 volumio volumio[883]: at Socket.emit (events.js:315:20) Apr 19 21:12:55 volumio volumio[883]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 19 21:12:55 volumio volumio[883]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 19 21:12:55 volumio volumio[883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 21:12:56 volumio sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 21:11 Apr 19 21:12:56 volumio sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"