-- Logs begin at Thu 2019-02-14 18:12:06 CST, end at Sun 2024-05-12 16:26:18 CST. -- May 12 16:25:00 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 12 16:25:09 volumio sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 16:25:09 volumio sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 16:25:09 volumio sudo[2094]: pam_unix(sudo:session): session closed for user root May 12 16:25:09 volumio sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 16:25:09 volumio sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 16:25:09 volumio sudo[2097]: pam_unix(sudo:session): session closed for user root May 12 16:25:09 volumio volumio[953]: verbose: New Socket.io Connection to 192.168.31.244 from 192.168.31.58 UA: okhttp/4.9.2 Total Clients: 6 May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::volumioGetVisibleSources May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:09 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 16:25:09 volumio volumio[953]: info: Received Get System Info May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:25:09 volumio volumio[953]: info: Discovery: Getting this device information May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:09 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:09 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:09 volumio volumio[953]: info: Listing playlists May 12 16:25:09 volumio volumio[953]: info: Received Get System Info May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:25:09 volumio volumio[953]: info: Discovery: Getting this device information May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:09 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:09 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 12 16:25:16 volumio ntpd[688]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 12 16:25:24 volumio nmbd[643]: [2024/05/12 16:25:24.433413, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 12 16:25:24 volumio nmbd[643]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.31.244 for name WORKGROUP<1d>. May 12 16:25:24 volumio nmbd[643]: This response was from IP 192.168.31.216, reporting an IP address of 192.168.31.216. May 12 16:25:33 volumio sudo[2101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 16:25:33 volumio sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 16:25:33 volumio sudo[2101]: pam_unix(sudo:session): session closed for user root May 12 16:25:33 volumio sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 16:25:33 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 16:25:33 volumio sudo[2104]: pam_unix(sudo:session): session closed for user root May 12 16:25:33 volumio volumio[953]: verbose: New Socket.io Connection to 192.168.31.244 from 192.168.31.58 UA: okhttp/4.9.2 Total Clients: 6 May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::volumioGetVisibleSources May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:33 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 16:25:33 volumio volumio[953]: info: Received Get System Info May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:25:33 volumio volumio[953]: info: Discovery: Getting this device information May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:33 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:33 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:33 volumio volumio[953]: info: Listing playlists May 12 16:25:33 volumio volumio[953]: info: Received Get System Info May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:25:33 volumio volumio[953]: info: Discovery: Getting this device information May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:33 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:33 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:33 volumio volumio[953]: info: Received Get System Info May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 16:25:33 volumio volumio[953]: info: Discovery: Getting this device information May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:33 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:33 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 12 16:25:51 volumio volumio[953]: info: Clearing queue after UPNP request May 12 16:25:51 volumio volumio[953]: info: CoreStateMachine::ClearQueue May 12 16:25:51 volumio volumio[953]: info: CoreStateMachine::stop May 12 16:25:51 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 16:25:51 volumio volumio[953]: info: CorePlayQueue::clearPlayQueue May 12 16:25:51 volumio volumio[953]: info: CorePlayQueue::saveQueue May 12 16:25:51 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:25:51 volumio volumio[953]: info: CoreCommandRouter::volumioPushQueue May 12 16:25:52 volumio volumio[953]: error: Upnp client error: Error: This socket has been ended by the other party May 12 16:25:53 volumio volumio[953]: info: Starting UPNP Playback May 12 16:25:53 volumio volumio[953]: info: Preparing playback through UPNP May 12 16:25:53 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:25:53 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:25:53 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService mpd May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 3ms May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 1ms May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 3ms May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 1ms May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 3ms May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 1ms May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:25:54 volumio volumio[953]: info: ControllerMpd::getState May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:25:54 volumio volumio[953]: info: ControllerMpd::getState May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:25:54 volumio volumio[953]: info: sendMpdCommand status took 30 milliseconds May 12 16:25:54 volumio volumio[953]: info: sendMpdCommand status took 29 milliseconds May 12 16:25:54 volumio volumio[953]: info: ControllerMpd::getState May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:25:54 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:25:54 volumio volumio[953]: info: May 12 16:25:54 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:25:54 volumio volumio[953]: info: ControllerMpd::getState May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 6ms May 12 16:25:54 volumio volumio[953]: info: sendMpdCommand status took 4 milliseconds May 12 16:25:54 volumio volumio[953]: info: sendMpdCommand playlistinfo took 4 milliseconds May 12 16:25:54 volumio volumio[953]: info: sendMpdCommand playlistinfo took 4 milliseconds May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 3ms May 12 16:25:54 volumio volumio[953]: info: sendMpdCommand status took 3 milliseconds May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:25:54 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:25:54 volumio volumio[953]: verbose: In UPNP mode May 12 16:25:54 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":146,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"this is what slow dancing feels like","artist":"JVKE","album":"this is what slow dancing feels like","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500004dxbVO1allxi.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=FBD85F8292C3FD7CD68A2B97E1C1103E9CEAB7A244015AD18AC960C374AF82138054162A5684A00395D8CD1FE7C99B85A0C9154E57DEC301&uin=289399252&redirect=1&fromtag=111042","trackType":""} May 12 16:25:54 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop May 12 16:25:54 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:25:54 volumio volumio[953]: verbose: In UPNP mode May 12 16:25:54 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":146,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"this is what slow dancing feels like","artist":"JVKE","album":"this is what slow dancing feels like","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500004dxbVO1allxi.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=FBD85F8292C3FD7CD68A2B97E1C1103E9CEAB7A244015AD18AC960C374AF82138054162A5684A00395D8CD1FE7C99B85A0C9154E57DEC301&uin=289399252&redirect=1&fromtag=111042","trackType":""} May 12 16:25:54 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:25:54 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 52ms May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 59ms May 12 16:25:54 volumio volumio[953]: info: sendMpdCommand playlistinfo took 23 milliseconds May 12 16:25:54 volumio volumio[953]: info: sendMpdCommand playlistinfo took 22 milliseconds May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:25:54 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:25:54 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:25:54 volumio volumio[953]: verbose: In UPNP mode May 12 16:25:54 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":146,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"this is what slow dancing feels like","artist":"JVKE","album":"this is what slow dancing feels like","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500004dxbVO1allxi.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=FBD85F8292C3FD7CD68A2B97E1C1103E9CEAB7A244015AD18AC960C374AF82138054162A5684A00395D8CD1FE7C99B85A0C9154E57DEC301&uin=289399252&redirect=1&fromtag=111042","trackType":""} May 12 16:25:54 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:25:54 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:25:54 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:25:54 volumio volumio[953]: verbose: In UPNP mode May 12 16:25:54 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":146,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"this is what slow dancing feels like","artist":"JVKE","album":"this is what slow dancing feels like","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500004dxbVO1allxi.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=FBD85F8292C3FD7CD68A2B97E1C1103E9CEAB7A244015AD18AC960C374AF82138054162A5684A00395D8CD1FE7C99B85A0C9154E57DEC301&uin=289399252&redirect=1&fromtag=111042","trackType":""} May 12 16:25:54 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:25:54 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 60ms May 12 16:25:54 volumio volumio[953]: info: ------------------------------ 57ms May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:25:54 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:25:54 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:25:54 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:25:54 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:25:54 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:25:54 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:25:54 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:25:54 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:08 volumio volumio[953]: info: May 12 16:26:08 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:26:08 volumio volumio[953]: info: ControllerMpd::getState May 12 16:26:08 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:26:08 volumio volumio[953]: info: May 12 16:26:08 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:26:08 volumio volumio[953]: info: ControllerMpd::getState May 12 16:26:08 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:26:08 volumio volumio[953]: info: sendMpdCommand status took 2 milliseconds May 12 16:26:08 volumio volumio[953]: info: sendMpdCommand status took 1 milliseconds May 12 16:26:08 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:26:08 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:26:08 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:26:08 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:26:08 volumio volumio[953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 12 16:26:08 volumio volumio[953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 12 16:26:08 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:26:08 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:26:08 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:08 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:26:08 volumio volumio[953]: 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":"this is what slow dancing feels like","artist":"JVKE","album":"this is what slow dancing feels like","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500004dxbVO1allxi.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=FBD85F8292C3FD7CD68A2B97E1C1103E9CEAB7A244015AD18AC960C374AF82138054162A5684A00395D8CD1FE7C99B85A0C9154E57DEC301&uin=289399252&redirect=1&fromtag=111042","trackType":""} May 12 16:26:08 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::stPlaybackTimer May 12 16:26:08 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:08 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:26:08 volumio volumio[953]: 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":"this is what slow dancing feels like","artist":"JVKE","album":"this is what slow dancing feels like","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500004dxbVO1allxi.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=FBD85F8292C3FD7CD68A2B97E1C1103E9CEAB7A244015AD18AC960C374AF82138054162A5684A00395D8CD1FE7C99B85A0C9154E57DEC301&uin=289399252&redirect=1&fromtag=111042","trackType":""} May 12 16:26:08 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:08 volumio volumio[953]: info: No code May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:08 volumio volumio[953]: info: ------------------------------ 30ms May 12 16:26:08 volumio volumio[953]: info: ------------------------------ 30ms May 12 16:26:08 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:08 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:08 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:09 volumio volumio[953]: info: Clearing queue after UPNP request May 12 16:26:09 volumio volumio[953]: info: CoreStateMachine::ClearQueue May 12 16:26:09 volumio volumio[953]: info: CoreStateMachine::stop May 12 16:26:09 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 16:26:09 volumio volumio[953]: info: CorePlayQueue::clearPlayQueue May 12 16:26:09 volumio volumio[953]: info: CorePlayQueue::saveQueue May 12 16:26:09 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:09 volumio volumio[953]: info: CoreCommandRouter::volumioPushQueue May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: error: updateQueue error: null May 12 16:26:09 volumio volumio[953]: error: updateQueue error: null May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: Starting UPNP Playback May 12 16:26:09 volumio volumio[953]: info: Preparing playback through UPNP May 12 16:26:09 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 12 16:26:09 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 12 16:26:09 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService mpd May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 1ms May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 1ms May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 1ms May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 1ms May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: May 12 16:26:09 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:09 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 2ms May 12 16:26:09 volumio volumio[953]: info: ------------------------------ 1ms May 12 16:26:10 volumio volumio[953]: info: May 12 16:26:10 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::getState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:26:10 volumio volumio[953]: info: May 12 16:26:10 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::getState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:26:10 volumio volumio[953]: info: May 12 16:26:10 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::getState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand status took 2 milliseconds May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:26:10 volumio volumio[953]: info: May 12 16:26:10 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::getState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand status took 4 milliseconds May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand playlistinfo took 4 milliseconds May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand playlistinfo took 3 milliseconds May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand status took 2 milliseconds May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:26:10 volumio volumio[953]: verbose: In UPNP mode May 12 16:26:10 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"可能","artist":"黄锦明","album":"可能","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500000zRPxf2Kgmtt.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=457B1C8FF0549EE9B38B461F58C3AD9E3C3BF40244C2E20F816F2A5DC581B1CBD86E853F7924BC106086D0A7525D0970B1EECE3E042B574D&uin=289399252&src=M500003X4rDD2dazrO.mp3&redirect=1&fromtag=111042","trackType":""} May 12 16:26:10 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:26:10 volumio volumio[953]: verbose: In UPNP mode May 12 16:26:10 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"可能","artist":"黄锦明","album":"可能","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500000zRPxf2Kgmtt.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=457B1C8FF0549EE9B38B461F58C3AD9E3C3BF40244C2E20F816F2A5DC581B1CBD86E853F7924BC106086D0A7525D0970B1EECE3E042B574D&uin=289399252&src=M500003X4rDD2dazrO.mp3&redirect=1&fromtag=111042","trackType":""} May 12 16:26:10 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:26:10 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: ------------------------------ 20ms May 12 16:26:10 volumio volumio[953]: info: ------------------------------ 27ms May 12 16:26:10 volumio volumio[953]: info: May 12 16:26:10 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:10 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:10 volumio volumio[953]: info: May 12 16:26:10 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::getState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand playlistinfo took 20 milliseconds May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand playlistinfo took 19 milliseconds May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:26:10 volumio volumio[953]: verbose: In UPNP mode May 12 16:26:10 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"可能","artist":"黄锦明","album":"可能","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500000zRPxf2Kgmtt.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=457B1C8FF0549EE9B38B461F58C3AD9E3C3BF40244C2E20F816F2A5DC581B1CBD86E853F7924BC106086D0A7525D0970B1EECE3E042B574D&uin=289399252&src=M500003X4rDD2dazrO.mp3&redirect=1&fromtag=111042","trackType":""} May 12 16:26:10 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:26:10 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:26:10 volumio volumio[953]: verbose: In UPNP mode May 12 16:26:10 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"可能","artist":"黄锦明","album":"可能","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500000zRPxf2Kgmtt.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=457B1C8FF0549EE9B38B461F58C3AD9E3C3BF40244C2E20F816F2A5DC581B1CBD86E853F7924BC106086D0A7525D0970B1EECE3E042B574D&uin=289399252&src=M500003X4rDD2dazrO.mp3&redirect=1&fromtag=111042","trackType":""} May 12 16:26:10 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:26:10 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: ------------------------------ 54ms May 12 16:26:10 volumio volumio[953]: info: ------------------------------ 53ms May 12 16:26:10 volumio volumio[953]: info: May 12 16:26:10 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 12 16:26:10 volumio volumio[953]: info: Ignoring MPD Status Update May 12 16:26:10 volumio volumio[953]: info: May 12 16:26:10 volumio volumio[953]: ---------------------------- MPD announces state update: player May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::getState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: ------------------------------ 51ms May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand status took 51 milliseconds May 12 16:26:10 volumio volumio[953]: info: ------------------------------ 22ms May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand status took 21 milliseconds May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseState May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 12 16:26:10 volumio volumio[953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:26:10 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:26:10 volumio volumio[953]: verbose: In UPNP mode May 12 16:26:10 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"可能","artist":"黄锦明","album":"可能","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500000zRPxf2Kgmtt.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=457B1C8FF0549EE9B38B461F58C3AD9E3C3BF40244C2E20F816F2A5DC581B1CBD86E853F7924BC106086D0A7525D0970B1EECE3E042B574D&uin=289399252&src=M500003X4rDD2dazrO.mp3&redirect=1&fromtag=111042","trackType":""} May 12 16:26:10 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:26:10 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: ControllerMpd::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 12 16:26:10 volumio volumio[953]: verbose: In UPNP mode May 12 16:26:10 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":283,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"可能","artist":"黄锦明","album":"可能","uri":"http://61.54.94.40/amobile.music.tc.qq.com/M500000zRPxf2Kgmtt.mp3?guid=0000000068a32e250000018d21b7ff87&vkey=457B1C8FF0549EE9B38B461F58C3AD9E3C3BF40244C2E20F816F2A5DC581B1CBD86E853F7924BC106086D0A7525D0970B1EECE3E042B574D&uin=289399252&src=M500003X4rDD2dazrO.mp3&redirect=1&fromtag=111042","trackType":""} May 12 16:26:10 volumio volumio[953]: verbose: CURRENT POSITION 0 May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 12 16:26:10 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::pushState May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 12 16:26:10 volumio volumio[953]: info: ------------------------------ 81ms May 12 16:26:10 volumio volumio[953]: info: ------------------------------ 51ms May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 12 16:26:10 volumio volumio[953]: info: CoreStateMachine::getQueue May 12 16:26:10 volumio volumio[953]: info: CorePlayQueue::getQueue May 12 16:26:17 volumio volumio[953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 16:26:17 volumio volumio[953]: Error [ERR_TLS_CERT_ALTNAME_INVALID]: Hostname/IP does not match certificate's altnames: Host: lastfm.freetls.fastly.net. is not in the cert's altnames: DNS:*.facebook.com, DNS:*.facebook.net, DNS:*.fbcdn.net, DNS:*.fbsbx.com, DNS:*.m.facebook.com, DNS:*.messenger.com, DNS:*.xx.fbcdn.net, DNS:*.xy.fbcdn.net, DNS:*.xz.fbcdn.net, DNS:facebook.com, DNS:messenger.com May 12 16:26:17 volumio volumio[953]: at Object.checkServerIdentity (tls.js:297:12) May 12 16:26:17 volumio volumio[953]: at TLSSocket.onConnectSecure (_tls_wrap.js:1507:27) May 12 16:26:17 volumio volumio[953]: at TLSSocket.emit (events.js:315:20) May 12 16:26:17 volumio volumio[953]: at TLSSocket._finishInit (_tls_wrap.js:932:8) May 12 16:26:17 volumio volumio[953]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { May 12 16:26:17 volumio volumio[953]: reason: "Host: lastfm.freetls.fastly.net. is not in the cert's altnames: DNS:*.facebook.com, DNS:*.facebook.net, DNS:*.fbcdn.net, DNS:*.fbsbx.com, DNS:*.m.facebook.com, DNS:*.messenger.com, DNS:*.xx.fbcdn.net, DNS:*.xy.fbcdn.net, DNS:*.xz.fbcdn.net, DNS:facebook.com, DNS:messenger.com", May 12 16:26:17 volumio volumio[953]: host: 'lastfm.freetls.fastly.net', May 12 16:26:17 volumio volumio[953]: cert: { May 12 16:26:17 volumio volumio[953]: subject: [Object: null prototype] { May 12 16:26:17 volumio volumio[953]: C: 'US', May 12 16:26:17 volumio volumio[953]: ST: 'California', May 12 16:26:17 volumio volumio[953]: L: 'Menlo Park', May 12 16:26:17 volumio volumio[953]: O: 'Meta Platforms, Inc.', May 12 16:26:17 volumio volumio[953]: CN: '*.facebook.com' May 12 16:26:17 volumio volumio[953]: }, May 12 16:26:17 volumio volumio[953]: issuer: [Object: null prototype] { May 12 16:26:17 volumio volumio[953]: C: 'US', May 12 16:26:17 volumio volumio[953]: O: 'DigiCert Inc', May 12 16:26:17 volumio volumio[953]: OU: 'www.digicert.com', May 12 16:26:17 volumio volumio[953]: CN: 'DigiCert SHA2 High Assurance Server CA' May 12 16:26:17 volumio volumio[953]: }, May 12 16:26:17 volumio volumio[953]: subjectaltname: 'DNS:*.facebook.com, DNS:*.facebook.net, DNS:*.fbcdn.net, DNS:*.fbsbx.com, DNS:*.m.facebook.com, DNS:*.messenger.com, DNS:*.xx.fbcdn.net, DNS:*.xy.fbcdn.net, DNS:*.xz.fbcdn.net, DNS:facebook.com, DNS:messenger.com', May 12 16:26:17 volumio volumio[953]: infoAccess: [Object: null prototype] { May 12 16:26:17 volumio volumio[953]: 'OCSP - URI': [Array], May 12 16:26:17 volumio volumio[953]: 'CA Issuers - URI': [Array] May 12 16:26:17 volumio volumio[953]: }, May 12 16:26:17 volumio volumio[953]: bits: 256, May 12 16:26:17 volumio volumio[953]: pubkey: , May 12 16:26:17 volumio volumio[953]: asn1Curve: 'prime256v1', May 12 16:26:17 volumio volumio[953]: nistCurve: 'P-256', May 12 16:26:17 volumio volumio[953]: valid_from: 'Feb 19 00:00:00 2024 GMT', May 12 16:26:17 volumio volumio[953]: valid_to: 'May 19 23:59:59 2024 GMT', May 12 16:26:17 volumio volumio[953]: fingerprint: '27:CA:64:E9:1A:89:14:3F:DF:1B:9E:7C:EC:05:E1:CB:0B:00:EF:6B', May 12 16:26:17 volumio volumio[953]: fingerprint256: '51:B4:40:57:24:7D:D6:61:46:70:4A:94:F9:A1:D8:F4:4A:52:CD:EE:7E:4F:18:5B:E6:A3:A8:3C:0C:60:B1:F1', May 12 16:26:17 volumio volumio[953]: ext_key_usage: [ '1.3.6.1.5.5.7.3.1', '1.3.6.1.5.5.7.3.2' ], May 12 16:26:17 volumio volumio[953]: serialNumber: '028953B36C777F399312669E37ED7C28', May 12 16:26:17 volumio volumio[953]: raw: , May 12 16:26:17 volumio volumio[953]: issuerCertificate: { May 12 16:26:17 volumio volumio[953]: subject: [Object: null prototype], May 12 16:26:17 volumio volumio[953]: issuer: [Object: null prototype], May 12 16:26:17 volumio volumio[953]: infoAccess: [Object: null prototype], May 12 16:26:17 volumio volumio[953]: modulus: 'B6E02FC22406C86D045FD7EF0A6406B27D22266516AE42409BCEDC9F9F76073EC330558719B94F940E5A941F5556B4C2022AAFD098EE0B40D7C4D03B72C8149EEF90B111A9AED2C8B8433AD90B0BD5D595F540AFC81DED4D9C5F57B786506899F58ADAD2C7051FA897C9DCA4B182842DC6ADA59CC71982A6850F5E44582A378FFD35F10B0827325AF5BB8B9EA4BD51D027E2DD3B4233A30528C4BB28CC9AAC2B230D78C67BE65E71B74A3E08FB81B71616A19D23124DE5D79208AC75A49CBACD17B21E4435657F532539D11C0A9A631B199274680A37C2C25248CB395AA2B6E15DC1DDA020B821A293266F144A2141C7ED6D9BF2482FF303F5A26892532F5EE3', May 12 16:26:17 volumio volumio[953]: bits: 2048, May 12 16:26:17 volumio volumio[953]: exponent: '0x10001', May 12 16:26:17 volumio volumio[953]: pubkey: , May 12 16:26:17 volumio volumio[953]: valid_from: 'Oct 22 12:00:00 2013 GMT', May 12 16:26:17 volumio volumio[953]: valid_to: 'Oct 22 12:00:00 2028 GMT', May 12 16:26:17 volumio volumio[953]: fingerprint: 'A0:31:C4:67:82:E6:E6:C6:62:C2:C8:7C:76:DA:9A:A6:2C:CA:BD:8E', May 12 16:26:17 volumio volumio[953]: fingerprint256: '19:40:0B:E5:B7:A3:1F:B7:33:91:77:00:78:9D:2F:0A:24:71:C0:C9:D5:06:C0:E5:04:C0:6C:16:D7:CB:17:C0', May 12 16:26:17 volumio volumio[953]: ext_key_usage: [Array], May 12 16:26:17 volumio volumio[953]: serialNumber: '04E1E7A4DC5CF2F36DC02B42B85D159F', May 12 16:26:17 volumio volumio[953]: raw: , May 12 16:26:17 volumio volumio[953]: issuerCertificate: [Object] May 12 16:26:17 volumio volumio[953]: } May 12 16:26:17 volumio volumio[953]: }, May 12 16:26:17 volumio volumio[953]: code: 'ERR_TLS_CERT_ALTNAME_INVALID' May 12 16:26:17 volumio volumio[953]: } May 12 16:26:17 volumio volumio[953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 16:26:18 volumio sudo[2264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 16:25 May 12 16:26:18 volumio sudo[2264]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"