-- Logs begin at Sat 2024-08-17 14:29:45 KST, end at Thu 2024-08-22 18:49:37 KST. -- Aug 22 18:48:03 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:08 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:14 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:20 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:25 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:27 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 18:48:27 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 18:48:27 volumio volumio[1062]: info: Discovery: Getting this device information Aug 22 18:48:27 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:27 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:27 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 18:48:27 volumio volumio[1062]: verbose: New Socket.io Connection to 192.168.219.182:3000 from 192.168.219.125 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 22 18:48:27 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:27 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:27 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 22 18:48:27 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 22 18:48:30 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 22 18:48:30 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 18:48:30 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Aug 22 18:48:30 volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 22 18:48:30 volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 18:48:30 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Aug 22 18:48:30 volumio volumio[1062]: verbose: New Socket.io Connection to 192.168.219.182 from 192.168.219.125 UA: Mozilla/5.0 (Linux; Android 14; SM-F946N Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 22 18:48:30 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:31 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 18:48:31 volumio volumio[1062]: info: Received Get System Info Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 18:48:31 volumio volumio[1062]: info: Discovery: Getting this device information Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:31 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:31 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:31 volumio volumio[1062]: info: Listing playlists Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 22 18:48:31 volumio sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 22 18:48:31 volumio sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 18:48:31 volumio sudo[1615]: pam_unix(sudo:session): session closed for user root Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 18:48:31 volumio sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 22 18:48:31 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 18:48:31 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Aug 22 18:48:31 volumio volumio[1062]: verbose: New Socket.io Connection to 192.168.219.182 from 192.168.219.125 UA: Mozilla/5.0 (Linux; Android 14; SM-F946N Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:31 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 18:48:31 volumio volumio[1062]: info: Received Get System Info Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 18:48:31 volumio volumio[1062]: info: Discovery: Getting this device information Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:31 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:31 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:31 volumio volumio[1062]: info: Listing playlists Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 22 18:48:31 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:31 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 18:48:32 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 22 18:48:33 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 18:48:33 volumio volumio[1062]: info: Received Get System Info Aug 22 18:48:33 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 18:48:33 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 18:48:33 volumio volumio[1062]: info: Discovery: Getting this device information Aug 22 18:48:33 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:33 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:33 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 18:48:33 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 22 18:48:36 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Aug 22 18:48:36 volumio volumio[1062]: info: CoreStateMachine::play index undefined Aug 22 18:48:36 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:48:36 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:36 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Aug 22 18:48:36 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:36 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:48:36 volumio volumio[1062]: info: sendMpdCommand stop took 3 milliseconds Aug 22 18:48:36 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 18:48:36 volumio volumio[1062]: info: sendMpdCommand clear took 1 milliseconds Aug 22 18:48:36 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "websbs/0" Aug 22 18:48:36 volumio volumio[1062]: info: Aug 22 18:48:36 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:36 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:36 volumio volumio[1062]: info: Aug 22 18:48:36 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:36 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:36 volumio volumio[1062]: info: Aug 22 18:48:36 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:36 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:36 volumio volumio[1062]: error: Upnp client error: Error: This socket has been ended by the other party Aug 22 18:48:36 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:48:36 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:48:36 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:48:36 volumio volumio[1062]: info: ------------------------------ 12ms Aug 22 18:48:36 volumio volumio[1062]: info: ------------------------------ 9ms Aug 22 18:48:36 volumio volumio[1062]: info: ------------------------------ 8ms Aug 22 18:48:37 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:37 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 18:48:37 volumio volumio[1062]: info: Received Get System Info Aug 22 18:48:37 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 18:48:37 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 18:48:37 volumio volumio[1062]: info: Discovery: Getting this device information Aug 22 18:48:37 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:37 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:37 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 18:48:41 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 22 18:48:42 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:45 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Aug 22 18:48:45 volumio volumio[1062]: info: CoreStateMachine::play index undefined Aug 22 18:48:45 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:48:45 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:45 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Aug 22 18:48:45 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:48:45 volumio volumio[1062]: info: sendMpdCommand stop took 1 milliseconds Aug 22 18:48:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 18:48:45 volumio volumio[1062]: info: sendMpdCommand clear took 1 milliseconds Aug 22 18:48:45 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "websbs/0" Aug 22 18:48:45 volumio volumio[1062]: info: Aug 22 18:48:45 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:45 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:45 volumio volumio[1062]: info: Aug 22 18:48:45 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:45 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:45 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:48:45 volumio volumio[1062]: info: ------------------------------ 3ms Aug 22 18:48:45 volumio volumio[1062]: info: Aug 22 18:48:45 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:45 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:45 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:48:45 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:48:45 volumio volumio[1062]: info: ------------------------------ 6ms Aug 22 18:48:45 volumio volumio[1062]: info: ------------------------------ 4ms Aug 22 18:48:47 volumio volumio[1062]: info: CoreCommandRouter::volumioGetQueue Aug 22 18:48:47 volumio volumio[1062]: info: CoreStateMachine::getQueue Aug 22 18:48:47 volumio volumio[1062]: info: CorePlayQueue::getQueue Aug 22 18:48:48 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:50 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 22 18:48:50 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:48:52 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 22 18:48:52 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 18:48:53 volumio volumio[1062]: info: Discovery: Getting this device information Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:53 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 22 18:48:53 volumio volumio[1062]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 22 18:48:53 volumio volumio[1062]: info: Received Get System Version Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 22 18:48:53 volumio volumio[1062]: info: Received Get System Info Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 18:48:53 volumio volumio[1062]: info: Discovery: Getting this device information Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:53 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:53 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 18:48:53 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:48:54 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:48:54 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:48:54 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Aug 22 18:48:54 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:48:54 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Aug 22 18:48:54 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Aug 22 18:48:54 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:48:54 volumio volumio[1062]: info: Adding Item to queue: websbs/1 Aug 22 18:48:54 volumio volumio[1062]: info: Exploding uri websbs/1 in service personal_radio Aug 22 18:48:54 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:48:54 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:48:54 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:48:54 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::play index 0 Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::play index undefined Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:48:54 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Aug 22 18:48:54 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:54 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:48:54 volumio volumio[1062]: info: sendMpdCommand stop took 4 milliseconds Aug 22 18:48:54 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 18:48:54 volumio volumio[1062]: info: Aug 22 18:48:54 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:54 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:54 volumio volumio[1062]: info: sendMpdCommand clear took 2 milliseconds Aug 22 18:48:54 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o" Aug 22 18:48:54 volumio volumio[1062]: info: Aug 22 18:48:54 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:54 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:54 volumio volumio[1062]: info: Aug 22 18:48:54 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:54 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:54 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:48:54 volumio volumio[1062]: info: Aug 22 18:48:54 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:54 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:54 volumio volumio[1062]: info: ------------------------------ 10ms Aug 22 18:48:54 volumio volumio[1062]: info: sendMpdCommand add "http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o" took 9 milliseconds Aug 22 18:48:54 volumio volumio[1062]: info: ------------------------------ 8ms Aug 22 18:48:54 volumio volumio[1062]: info: ------------------------------ 6ms Aug 22 18:48:54 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Aug 22 18:48:54 volumio volumio[1062]: info: Aug 22 18:48:54 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:54 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:54 volumio volumio[1062]: info: Aug 22 18:48:54 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:48:54 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:48:54 volumio volumio[1062]: info: ------------------------------ 16ms Aug 22 18:48:54 volumio volumio[1062]: info: sendMpdCommand play took 8 milliseconds Aug 22 18:48:54 volumio volumio[1062]: info: ------------------------------ 8ms Aug 22 18:48:54 volumio volumio[1062]: info: ------------------------------ 5ms Aug 22 18:48:54 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:48:54 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:48:54 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Aug 22 18:48:54 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:48:54 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:48:54 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 22 18:48:54 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:48:54 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:54 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","trackType":""} Aug 22 18:48:54 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Aug 22 18:48:54 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:48:55 volumio volumio[1062]: info: Aug 22 18:48:55 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:48:55 volumio volumio[1062]: info: Aug 22 18:48:55 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:48:55 volumio volumio[1062]: info: Aug 22 18:48:55 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:48:55 volumio volumio[1062]: info: Aug 22 18:48:55 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand status took 10 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand status took 9 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:48:55 volumio volumio[1062]: info: Aug 22 18:48:55 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:48:55 volumio volumio[1062]: info: Aug 22 18:48:55 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand status took 28 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand status took 28 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand status took 27 milliseconds Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:48:55 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:48:55 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:55 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:48:55 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:48:55 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:55 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:48:55 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:48:55 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:55 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:48:55 volumio volumio[1062]: info: ------------------------------ 63ms Aug 22 18:48:55 volumio volumio[1062]: info: ------------------------------ 61ms Aug 22 18:48:55 volumio volumio[1062]: info: ------------------------------ 58ms Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 18 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 22 18:48:55 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:48:55 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:48:55 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:48:55 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:55 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:48:55 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:48:55 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:55 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:48:55 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:48:55 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:48:55 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:55 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:48:55 volumio volumio[1062]: info: ------------------------------ 66ms Aug 22 18:48:55 volumio volumio[1062]: info: ------------------------------ 57ms Aug 22 18:48:55 volumio volumio[1062]: info: ------------------------------ 54ms Aug 22 18:48:55 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:55 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:55 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:48:55 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:48:59 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:49:05 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:49:10 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:49:15 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::serviceStop Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::stop Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Aug 22 18:49:15 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:15 volumio volumio[1062]: info: Adding Item to queue: websbs/0 Aug 22 18:49:15 volumio volumio[1062]: info: Exploding uri websbs/0 in service personal_radio Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand stop took 20 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 5 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 4 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","trackType":""} Aug 22 18:49:15 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: No code Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","trackType":""} Aug 22 18:49:15 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: No code Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","trackType":""} Aug 22 18:49:15 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: No code Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzMzQsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjBhNDIwYzhhLTJhMGMtNDM5Ni04YmZlLTVhYmU1MWU3Y2EzNyIsImlhdCI6MTcyNDMyMDEzNH0.zT4jVoJh1NVZmbI_eBvs7T0CzRwik0eZlXYxm5k_w3o","trackType":""} Aug 22 18:49:15 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: No code Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 91ms Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 88ms Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 87ms Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::play index 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::play index undefined Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand stop took 9 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:15 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand clear took 6 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://radiolive.sbs.co.kr/love/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4" Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:15 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:15 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:15 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:15 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 10ms Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand add "http://radiolive.sbs.co.kr/love/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4" took 8 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 8ms Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 7ms Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:15 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:15 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 15ms Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand play took 10 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 10ms Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 8ms Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/love/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","trackType":""} Aug 22 18:49:15 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Aug 22 18:49:15 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 11 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 4 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: Aug 22 18:49:15 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 13 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand status took 4 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 39ms Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 32ms Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 31ms Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 22 18:49:15 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:15 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:15 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:15 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:15 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 37ms Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 29ms Aug 22 18:49:15 volumio volumio[1062]: info: ------------------------------ 26ms Aug 22 18:49:16 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:49:17 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:17 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 18:49:17 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Aug 22 18:49:17 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:17 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:17 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Aug 22 18:49:17 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:17 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:17 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:17 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:17 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:17 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:17 volumio volumio[1062]: info: CoreStateMachine::serviceStop Aug 22 18:49:17 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:17 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Aug 22 18:49:17 volumio volumio[1062]: info: ControllerMpd::stop Aug 22 18:49:17 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:17 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Aug 22 18:49:17 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:17 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:17 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Aug 22 18:49:17 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Aug 22 18:49:17 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:17 volumio volumio[1062]: info: Adding Item to queue: websbs/1 Aug 22 18:49:17 volumio volumio[1062]: info: Exploding uri websbs/1 in service personal_radio Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand stop took 19 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 12 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 12 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 10 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 9 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/love/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","trackType":""} Aug 22 18:49:18 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: No code Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/love/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","trackType":""} Aug 22 18:49:18 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: No code Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/love/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","trackType":""} Aug 22 18:49:18 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: No code Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/love/lovefm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTUsInBhdGgiOiIvbG92ZWZtLnN0cmVhbSIsImR1cmF0aW9uIjotMSwidW5vIjoiZDllYzQ3YjgtMDE4Mi00NzZhLWFhN2YtYTM5YmY0Y2JiZTU2IiwiaWF0IjoxNzI0MzIwMTU1fQ.09j3qCc004Pi_ViT07qxk4--pGYzQ7slZMpuBiNa9t4","trackType":""} Aug 22 18:49:18 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: No code Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 92ms Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 90ms Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 89ms Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::play index 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::play index undefined Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand stop took 7 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:18 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand clear took 3 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA" Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:18 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:18 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:18 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:18 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 9ms Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand add "http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA" took 8 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 7ms Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 5ms Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:18 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:18 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 14ms Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand play took 9 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 7ms Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 5ms Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 3 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","trackType":""} Aug 22 18:49:18 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Aug 22 18:49:18 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 12 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 11 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: Aug 22 18:49:18 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 23 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 19 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 11 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 59ms Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 56ms Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 53ms Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 22 18:49:18 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:18 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:18 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:18 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:18 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 57ms Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 42ms Aug 22 18:49:18 volumio volumio[1062]: info: ------------------------------ 38ms Aug 22 18:49:21 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 22 18:49:21 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:21 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:49:22 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::serviceStop Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Aug 22 18:49:22 volumio volumio[1062]: info: ControllerMpd::stop Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Aug 22 18:49:22 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:22 volumio volumio[1062]: info: Adding Item to queue: webmbc/0 Aug 22 18:49:22 volumio volumio[1062]: info: Exploding uri webmbc/0 in service personal_radio Aug 22 18:49:22 volumio volumio[1062]: info: Aug 22 18:49:22 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand stop took 19 milliseconds Aug 22 18:49:22 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:22 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:22 volumio volumio[1062]: info: Aug 22 18:49:22 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:22 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:22 volumio volumio[1062]: info: Aug 22 18:49:22 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:22 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand status took 6 milliseconds Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand status took 7 milliseconds Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand status took 4 milliseconds Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand status took 3 milliseconds Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 18:49:22 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:22 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:22 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","trackType":""} Aug 22 18:49:22 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: No code Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","trackType":""} Aug 22 18:49:22 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: No code Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","trackType":""} Aug 22 18:49:22 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: No code Aug 22 18:49:22 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:22 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:22 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:22 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","artist":null,"album":null,"uri":"http://radiolive.sbs.co.kr/power/powerfm.stream/playlist.m3u8?token=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJleHAiOjE3MjQzNjMzNTgsInBhdGgiOiIvcG93ZXJmbS5zdHJlYW0iLCJkdXJhdGlvbiI6LTEsInVubyI6IjdlZjVkMjQ5LWEyZGMtNDVmNi05ZWI4LTRjNjY4ZDU5OGNhNSIsImlhdCI6MTcyNDMyMDE1OH0.0jaDGCbRsGm3KOfR1JkJyffMJbzAaYxq-ns3V7grMFA","trackType":""} Aug 22 18:49:23 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:23 volumio volumio[1062]: info: No code Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 109ms Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 108ms Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 107ms Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::play index 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::play index undefined Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand stop took 10 milliseconds Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:23 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand clear took 3 milliseconds Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:23 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:23 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:23 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:23 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 11ms Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" took 10 milliseconds Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 9ms Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 8ms Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:23 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:23 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 15ms Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand play took 10 milliseconds Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 9ms Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 5ms Aug 22 18:49:23 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand status took 5 milliseconds Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 22 18:49:23 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 18:49:23 volumio volumio[1062]: info: Discovery: Getting this device information Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioGetState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:23 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:23 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:23 volumio volumio[1062]: info: Aug 22 18:49:23 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:23 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand status took 9 milliseconds Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand status took 15 milliseconds Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand clearerror took 11 milliseconds Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand clearerror took 10 milliseconds Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:23 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:23 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 42ms Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 41ms Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand clearerror took 31 milliseconds Aug 22 18:49:23 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 22 18:49:23 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:23 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:23 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:23 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:23 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:23 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:23 volumio volumio[1062]: info: ------------------------------ 61ms Aug 22 18:49:25 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::serviceStop Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Aug 22 18:49:25 volumio volumio[1062]: info: ControllerMpd::stop Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Aug 22 18:49:25 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:25 volumio volumio[1062]: info: Adding Item to queue: webmbc/1 Aug 22 18:49:25 volumio volumio[1062]: info: Exploding uri webmbc/1 in service personal_radio Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand stop took 22 milliseconds Aug 22 18:49:25 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand status took 1 milliseconds Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 22 18:49:25 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:25 volumio volumio[1062]: info: No code Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:25 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::play index 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::play index undefined Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand stop took 3 milliseconds Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 18:49:25 volumio volumio[1062]: info: Aug 22 18:49:25 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:25 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand clear took 2 milliseconds Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8" Aug 22 18:49:25 volumio volumio[1062]: info: Aug 22 18:49:25 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:25 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:25 volumio volumio[1062]: info: Aug 22 18:49:25 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:25 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:25 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:49:25 volumio volumio[1062]: info: Aug 22 18:49:25 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:25 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:25 volumio volumio[1062]: info: ------------------------------ 12ms Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand add "http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8" took 10 milliseconds Aug 22 18:49:25 volumio volumio[1062]: info: ------------------------------ 10ms Aug 22 18:49:25 volumio volumio[1062]: info: ------------------------------ 9ms Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Aug 22 18:49:25 volumio volumio[1062]: info: Aug 22 18:49:25 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:25 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:25 volumio volumio[1062]: info: Aug 22 18:49:25 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:25 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:25 volumio volumio[1062]: info: ------------------------------ 16ms Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand play took 7 milliseconds Aug 22 18:49:25 volumio volumio[1062]: info: ------------------------------ 6ms Aug 22 18:49:25 volumio volumio[1062]: info: ------------------------------ 4ms Aug 22 18:49:25 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand status took 3 milliseconds Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:25 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 22 18:49:25 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:25 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:25 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 22 18:49:25 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Aug 22 18:49:25 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:26 volumio volumio[1062]: info: Aug 22 18:49:26 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:26 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:26 volumio volumio[1062]: info: Aug 22 18:49:26 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:26 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:26 volumio volumio[1062]: info: Aug 22 18:49:26 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:26 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand status took 8 milliseconds Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand status took 23 milliseconds Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand clearerror took 20 milliseconds Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand clearerror took 18 milliseconds Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:26 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:26 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:26 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:26 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:26 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:26 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:26 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:26 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:26 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:26 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:26 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:26 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:26 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:26 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:26 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:26 volumio volumio[1062]: info: ------------------------------ 50ms Aug 22 18:49:26 volumio volumio[1062]: info: ------------------------------ 49ms Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand clearerror took 30 milliseconds Aug 22 18:49:26 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 22 18:49:26 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:26 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:26 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:26 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:26 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:26 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:26 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:26 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:26 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:26 volumio volumio[1062]: info: ------------------------------ 68ms Aug 22 18:49:27 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:49:29 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::serviceStop Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Aug 22 18:49:29 volumio volumio[1062]: info: ControllerMpd::stop Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Aug 22 18:49:29 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:29 volumio volumio[1062]: info: Adding Item to queue: webmbc/0 Aug 22 18:49:29 volumio volumio[1062]: info: Exploding uri webmbc/0 in service personal_radio Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand stop took 17 milliseconds Aug 22 18:49:29 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand status took 10 milliseconds Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 22 18:49:29 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:29 volumio volumio[1062]: info: No code Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:29 volumio volumio[1062]: info: CoreCommandRouter::volumioPlay Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::play index 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::play index undefined Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::startPlaybackTimer Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand stop took 3 milliseconds Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clear Aug 22 18:49:29 volumio volumio[1062]: info: Aug 22 18:49:29 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:29 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand clear took 2 milliseconds Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" Aug 22 18:49:29 volumio volumio[1062]: info: Aug 22 18:49:29 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:29 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:29 volumio volumio[1062]: info: Aug 22 18:49:29 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:29 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:29 volumio volumio[1062]: error: updateQueue error: null Aug 22 18:49:29 volumio volumio[1062]: info: Aug 22 18:49:29 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:29 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:29 volumio volumio[1062]: info: ------------------------------ 7ms Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" took 7 milliseconds Aug 22 18:49:29 volumio volumio[1062]: info: ------------------------------ 6ms Aug 22 18:49:29 volumio volumio[1062]: info: ------------------------------ 4ms Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand play Aug 22 18:49:29 volumio volumio[1062]: info: Aug 22 18:49:29 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:29 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:29 volumio volumio[1062]: info: Aug 22 18:49:29 volumio volumio[1062]: ---------------------------- MPD announces system playlist update Aug 22 18:49:29 volumio volumio[1062]: info: Ignoring MPD Status Update Aug 22 18:49:29 volumio volumio[1062]: info: ------------------------------ 11ms Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand play took 7 milliseconds Aug 22 18:49:29 volumio volumio[1062]: info: ------------------------------ 5ms Aug 22 18:49:29 volumio volumio[1062]: info: ------------------------------ 4ms Aug 22 18:49:29 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand status took 3 milliseconds Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:29 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 22 18:49:29 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:29 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:29 volumio volumio[1062]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 22 18:49:29 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::syncState stateService play Aug 22 18:49:29 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:30 volumio volumio[1062]: info: Aug 22 18:49:30 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:30 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:30 volumio volumio[1062]: info: Aug 22 18:49:30 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:30 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:30 volumio volumio[1062]: info: Aug 22 18:49:30 volumio volumio[1062]: ---------------------------- MPD announces state update: player Aug 22 18:49:30 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand status took 15 milliseconds Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand status took 14 milliseconds Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand status took 22 milliseconds Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand clearerror took 13 milliseconds Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand clearerror took 12 milliseconds Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:30 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:30 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:30 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:30 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:30 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:30 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:30 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:30 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:30 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:30 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:30 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:30 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:30 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:30 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:30 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:30 volumio volumio[1062]: info: ------------------------------ 54ms Aug 22 18:49:30 volumio volumio[1062]: info: ------------------------------ 51ms Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand clearerror took 32 milliseconds Aug 22 18:49:30 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 22 18:49:30 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:30 volumio volumio[1062]: info: ControllerMpd::pushState Aug 22 18:49:30 volumio volumio[1062]: info: CoreCommandRouter::servicePushState Aug 22 18:49:30 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:30 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:30 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:30 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:30 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:30 volumio volumio[1062]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Aug 22 18:49:30 volumio volumio[1062]: info: ------------------------------ 69ms Aug 22 18:49:33 volumio wpa_supplicant[937]: wlan0: Failed to initiate sched scan Aug 22 18:49:33 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 22 18:49:33 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:35 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 22 18:49:35 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:36 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::ClearQueue Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::stop Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::stPlaybackTimer Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::updateTrackBlock Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::getTrackBlock Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::serviceStop Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::serviceStop Aug 22 18:49:36 volumio volumio[1062]: info: ControllerMpd::stop Aug 22 18:49:36 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand stop Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::clearPlayQueue Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::saveQueue Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::volumioPushQueue Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::addQueueItems Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::addQueueItems Aug 22 18:49:36 volumio volumio[1062]: info: Preload queue cleared Aug 22 18:49:36 volumio volumio[1062]: info: Adding Item to queue: webkbs/0 Aug 22 18:49:36 volumio volumio[1062]: info: Exploding uri webkbs/0 in service personal_radio Aug 22 18:49:36 volumio volumio[1062]: info: sendMpdCommand stop took 17 milliseconds Aug 22 18:49:36 volumio volumio[1062]: info: ControllerMpd::getState Aug 22 18:49:36 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand status Aug 22 18:49:36 volumio volumio[1062]: info: sendMpdCommand status took 1 milliseconds Aug 22 18:49:36 volumio volumio[1062]: verbose: ControllerMpd::parseState Aug 22 18:49:36 volumio volumio[1062]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 22 18:49:36 volumio volumio[1062]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 22 18:49:36 volumio volumio[1062]: verbose: ControllerMpd::parseTrackInfo Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:36 volumio volumio[1062]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Aug 22 18:49:36 volumio volumio[1062]: verbose: CURRENT POSITION 0 Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::syncState stateService stop Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::syncState currentStatus stop Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:36 volumio volumio[1062]: info: No code Aug 22 18:49:36 volumio volumio[1062]: info: CoreStateMachine::pushState Aug 22 18:49:36 volumio volumio[1062]: info: CorePlayQueue::getTrack 0 Aug 22 18:49:36 volumio volumio[1062]: info: CoreCommandRouter::volumioPushState Aug 22 18:49:36 volumio volumio[1062]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 18:49:36 volumio volumio[1062]: ReferenceError: self is not defined Aug 22 18:49:36 volumio volumio[1062]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Aug 22 18:49:36 volumio volumio[1062]: at /data/plugins/music_service/personal_radio/index.js:487:12 Aug 22 18:49:36 volumio volumio[1062]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:463:26) Aug 22 18:49:36 volumio volumio[1062]: at Request.self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Aug 22 18:49:36 volumio volumio[1062]: at Request.emit (events.js:315:20) Aug 22 18:49:36 volumio volumio[1062]: at Request. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:1044:10) Aug 22 18:49:36 volumio volumio[1062]: at Request.emit (events.js:315:20) Aug 22 18:49:36 volumio volumio[1062]: at IncomingMessage. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:965:12) Aug 22 18:49:36 volumio volumio[1062]: at IncomingMessage.emit (events.js:327:22) Aug 22 18:49:36 volumio volumio[1062]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 22 18:49:36 volumio volumio[1062]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 22 18:49:36 volumio volumio[1062]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 18:49:37 volumio sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-22 18:48 Aug 22 18:49:37 volumio sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"