-- Logs begin at Sat 2026-05-30 12:30:36 MDT, end at Sat 2026-05-30 14:12:50 MDT. -- May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 14:11:07 volumio volumio[962]: info: Discovery: Getting this device information May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 14:11:07 volumio volumio[962]: info: Discovery: Getting this device information May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 14:11:07 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.0.17:3000 from 192.168.0.35 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 14:11:07 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.0.17:3000 from 192.168.0.35 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 14:11:07 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 14:11:08 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.0.17:3000 from 192.168.0.35 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 14:11:08 volumio volumio[962]: info: Discovery: Getting this device information May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 14:11:08 volumio volumio[962]: info: Discovery: Getting this device information May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 30 14:11:08 volumio sudo[26262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 30 14:11:08 volumio sudo[26262]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:08 volumio sudo[26262]: pam_unix(sudo:session): session closed for user root May 30 14:11:08 volumio sudo[26268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 30 14:11:08 volumio sudo[26268]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:08 volumio sudo[26268]: pam_unix(sudo:session): session closed for user root May 30 14:11:08 volumio sudo[26276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 30 14:11:08 volumio sudo[26276]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:08 volumio sudo[26276]: pam_unix(sudo:session): session closed for user root May 30 14:11:08 volumio sudo[26282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 30 14:11:08 volumio sudo[26282]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:08 volumio sudo[26282]: pam_unix(sudo:session): session closed for user root May 30 14:11:08 volumio sudo[26287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 14:11:08 volumio sudo[26287]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:08 volumio sudo[26287]: pam_unix(sudo:session): session closed for user root May 30 14:11:08 volumio sudo[26291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 14:11:08 volumio sudo[26291]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:08 volumio sudo[26291]: pam_unix(sudo:session): session closed for user root May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 14:11:08 volumio volumio[962]: info: Discovery: Getting this device information May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 14:11:08 volumio volumio[962]: info: Discovery: Getting this device information May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 14:11:08 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.0.17:3000 from 192.168.0.35 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 14:11:08 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 14:11:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 14:11:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 14:11:10 volumio sudo[26296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 14:11:10 volumio sudo[26296]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:10 volumio sudo[26296]: pam_unix(sudo:session): session closed for user root May 30 14:11:10 volumio sudo[26299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 14:11:10 volumio sudo[26299]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:10 volumio sudo[26299]: pam_unix(sudo:session): session closed for user root May 30 14:11:10 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.35 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::volumioGetVisibleSources May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 14:11:10 volumio sudo[26302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 14:11:10 volumio sudo[26302]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:10 volumio sudo[26302]: pam_unix(sudo:session): session closed for user root May 30 14:11:10 volumio sudo[26304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 14:11:10 volumio sudo[26304]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 14:11:10 volumio sudo[26304]: pam_unix(sudo:session): session closed for user root May 30 14:11:10 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.0.17 from 192.168.0.35 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 14:11:10 volumio volumio[962]: info: Listing playlists May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 30 14:11:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 14:11:11 volumio volumio[962]: info: CoreCommandRouter::volumioGetQueue May 30 14:11:11 volumio volumio[962]: info: CoreStateMachine::getQueue May 30 14:11:11 volumio volumio[962]: info: CorePlayQueue::getQueue May 30 14:11:11 volumio volumio[962]: info: CoreCommandRouter::volumioClearQueue May 30 14:11:11 volumio volumio[962]: info: CoreStateMachine::ClearQueue May 30 14:11:11 volumio volumio[962]: info: CoreStateMachine::stop May 30 14:11:11 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 14:11:11 volumio volumio[962]: info: CoreStateMachine::stPlaybackTimer May 30 14:11:11 volumio volumio[962]: info: CoreStateMachine::updateTrackBlock May 30 14:11:11 volumio volumio[962]: info: CorePlayQueue::getTrackBlock May 30 14:11:11 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:11 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:11:11 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 14:11:11 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:11 volumio volumio[962]: info: CoreStateMachine::serviceStop May 30 14:11:11 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:11:11 volumio volumio[962]: info: CoreCommandRouter::serviceStop May 30 14:11:11 volumio volumio[962]: info: [1780171871997] ControllerPandora::stop May 30 14:11:11 volumio volumio[962]: info: ControllerMpd::stop May 30 14:11:11 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand stop May 30 14:11:11 volumio volumio[962]: info: CorePlayQueue::clearPlayQueue May 30 14:11:11 volumio volumio[962]: info: CorePlayQueue::saveQueue May 30 14:11:11 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue May 30 14:11:12 volumio volumio[962]: info: May 30 14:11:12 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:11:12 volumio volumio[962]: info: sendMpdCommand stop took 29 milliseconds May 30 14:11:12 volumio volumio[962]: info: ControllerMpd::getState May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:11:12 volumio volumio[962]: info: ControllerMpd::clear May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand clear May 30 14:11:12 volumio volumio[962]: info: May 30 14:11:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:11:12 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:11:12 volumio volumio[962]: info: sendMpdCommand status took 3 milliseconds May 30 14:11:12 volumio volumio[962]: info: sendMpdCommand clear took 2 milliseconds May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:11:12 volumio volumio[962]: info: [1780171872031] ControllerPandora::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:11:12 volumio volumio[962]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Sail","name":"Sail","artist":"AWOLNATION","album":"Megalithic Symphony (10th Anniversary Deluxe Edition)","albumart":"http://cont-2.p-cdn.us/images/dd/ba/a1/da/3ef446fdb4fc6a09ecbf9b2d/1080W_1080H.jpg","realUri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/544341316092235155.mp3?version=5&lid=146541526&token=ma7psRfMHdB9IboaCthHbHG%2BthkbBR%2BRGyLxSqFPWOGDolfpzTFOk0gM1NyDEV6bx9hpy9PPrK4%2FEkS6KXseLF17fwfTqdZQpQKSiqvP9ZOsJzt3D2%2F3fK3%2BPwEa84q3Huq%2Fz9s19WGY4XJJSzrK6tydC6yfuOp%2Btcitso5Q5wWVRbJkGRVt3qd15XtcFvjHwv2TI%2BDVfL%2BEZdAtW4bGnPD%2B59jqmXkKxCLJnz8CoOKEr6oFz1X9sGEtagp0mav94p9WC2I2wSAO0cBcjL%2FA2FgDY%2BH6OixfpuLjJSk6weixAKqhHl7ctikbx35IfvYKaletmwsKr5Qsf%2FlZvu0yUQ%3D%3D","isStreaming":true,"duration":259,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-sv5-t1-1-v4v6.pandora.com/access/544341316092235155.mp3?version=5&lid=146541526&token=ma7psRfMHdB9IboaCthHbHG%2BthkbBR%2BRGyLxSqFPWOGDolfpzTFOk0gM1NyDEV6bx9hpy9PPrK4%2FEkS6KXseLF17fwfTqdZQpQKSiqvP9ZOsJzt3D2%2F3fK3%2BPwEa84q3Huq%2Fz9s19WGY4XJJSzrK6tydC6yfuOp%2Btcitso5Q5wWVRbJkGRVt3qd15XtcFvjHwv2TI%2BDVfL%2BEZdAtW4bGnPD%2B59jqmXkKxCLJnz8CoOKEr6oFz1X9sGEtagp0mav94p9WC2I2wSAO0cBcjL%2FA2FgDY%2BH6OixfpuLjJSk6weixAKqhHl7ctikbx35IfvYKaletmwsKr5Qsf%2FlZvu0yUQ%3D%3D","seek":0,"status":"stop"} May 30 14:11:12 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::syncState stateService stop May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus stop May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: No code May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 14:11:12 volumio volumio[962]: info: May 30 14:11:12 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:11:12 volumio volumio[962]: info: ControllerMpd::getState May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:11:12 volumio volumio[962]: info: May 30 14:11:12 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:11:12 volumio volumio[962]: info: ControllerMpd::getState May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:11:12 volumio volumio[962]: error: updateQueue error: null May 30 14:11:12 volumio volumio[962]: info: ------------------------------ 38ms May 30 14:11:12 volumio volumio[962]: info: sendMpdCommand playlistinfo took 37 milliseconds May 30 14:11:12 volumio volumio[962]: info: sendMpdCommand status took 4 milliseconds May 30 14:11:12 volumio volumio[962]: info: sendMpdCommand status took 3 milliseconds May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:11:12 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:11:12 volumio volumio[962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 30 14:11:12 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:11:12 volumio volumio[962]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 30 14:11:12 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::syncState stateService stop May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus stop May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: No code May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:11:12 volumio volumio[962]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 30 14:11:12 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::syncState stateService stop May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus stop May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: No code May 30 14:11:12 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:11:12 volumio volumio[962]: info: ------------------------------ 60ms May 30 14:11:12 volumio volumio[962]: info: ------------------------------ 40ms May 30 14:11:12 volumio volumio[962]: info: ------------------------------ 39ms May 30 14:11:12 volumio volumio[962]: info: May 30 14:11:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:11:12 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:11:12 volumio volumio[962]: info: May 30 14:11:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:11:12 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:11:12 volumio volumio[962]: error: updateQueue error: null May 30 14:11:12 volumio volumio[962]: error: updateQueue error: null May 30 14:11:12 volumio volumio[962]: info: ------------------------------ 15ms May 30 14:11:12 volumio volumio[962]: info: ------------------------------ 12ms May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 14:11:12 volumio volumio[962]: info: Received Get System Info May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 14:11:12 volumio volumio[962]: info: Discovery: Getting this device information May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 14:11:21 volumio volumio[962]: info: CoreCommandRouter::volumioGetVisibleSources May 30 14:11:21 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 14:11:22 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:11:22 volumio volumio[962]: info: Preload queue cleared May 30 14:11:29 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 14:11:29 volumio volumio[962]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 30 14:11:29 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 30 14:11:29 volumio volumio[962]: info: Received Get System Version May 30 14:11:29 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 14:11:29 volumio volumio[962]: info: Received Get System Info May 30 14:11:29 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 14:11:29 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 14:11:29 volumio volumio[962]: info: Discovery: Getting this device information May 30 14:11:29 volumio volumio[962]: info: CoreCommandRouter::volumioGetState May 30 14:11:29 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 14:11:33 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:11:34 volumio volumio[962]: info: Preload queue cleared May 30 14:11:41 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:11:41 volumio volumio[962]: info: Preload queue cleared May 30 14:11:45 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:11:49 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:11:51 volumio volumio[962]: info: Preload queue cleared May 30 14:11:51 volumio volumio[962]: error: error May 30 14:11:51 volumio volumio[962]: error: error May 30 14:11:51 volumio volumio[962]: info: Preload queue cleared May 30 14:11:51 volumio volumio[962]: error: error May 30 14:11:51 volumio volumio[962]: error: error May 30 14:11:51 volumio volumio[962]: error: error May 30 14:11:51 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:52 volumio volumio[962]: error: error May 30 14:11:54 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Aquabats/9e190741-7b29-4f08-a08f-aa488f4ea621.jpg' May 30 14:11:54 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Band%20Perry/5b0d4a74-ebcd-49c8-82e5-82694988e8e7.jpg' May 30 14:11:54 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Beach%20Boys/50cd6f7f-eed3-4b3a-a32c-edc7498b9cf2.jpg' May 30 14:11:54 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Beatles/77f3e677-b62e-4ca3-b3a4-2334b9d861f5.jpg' May 30 14:11:55 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:11:55 volumio volumio[962]: info: Preload queue cleared May 30 14:11:57 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Crystal%20Method/46497dd1-fe41-470a-a842-9643d62d2fa3.jpg' May 30 14:11:58 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Disclaimers/4cd50812-1d06-4f76-8904-f21f89115512.jpg' May 30 14:12:00 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:12:00 volumio volumio[962]: info: Preload queue cleared May 30 14:12:00 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10133 May 30 14:12:00 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10133 in service upnp_browser May 30 14:12:02 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Green%20Day/52ee0dfe-0911-4a15-a8fa-a1bfab6fe465.jpg' May 30 14:12:02 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Herman%27s%20Hermits/2496caad-aafc-4297-89d5-0ae75dc8f25b.jpg' May 30 14:12:02 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hoobastank/38f5fa2d-ee6c-44f6-ba5e-538ee187769a.jpg' May 30 14:12:05 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jars%20of%20Clay/f2a507cd-58de-4102-80d9-b0b9f3974012.jpg' May 30 14:12:05 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jason%20Mraz/f0ed38e2-a9a8-4b7f-8ff8-3e7eef0961f0.jpg' May 30 14:12:05 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jet/8fc42fb0-78dc-4e47-a51d-b6cc8a0a5f3d.jpg' May 30 14:12:10 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:12:10 volumio volumio[962]: info: Preload queue cleared May 30 14:12:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 14:12:12 volumio volumio[962]: info: Preload queue cleared May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10095 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10094 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10100 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10096 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10098 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10097 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10102 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10101 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10103 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10099 May 30 14:12:12 volumio volumio[962]: info: Preloading song: upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10104 May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10095 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10094 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10100 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10096 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10098 in service upnp_browser May 30 14:12:12 volumio volumio[962]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lemon%20Jelly/1f3edd43-6fd8-4d6e-8659-77a293ba29ee.jpg' May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10097 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10102 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10101 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10103 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10099 in service upnp_browser May 30 14:12:12 volumio volumio[962]: info: Exploding uri upnp/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10104 in service upnp_browser May 30 14:12:17 volumio volumio[962]: info: Preload queue cleared May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::ClearQueue May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::stop May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::clearPlayQueue May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::saveQueue May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::addQueueItems May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::addQueueItems May 30 14:12:17 volumio volumio[962]: info: Preload queue cleared May 30 14:12:17 volumio volumio[962]: info: Adding Item to queue: upnp/folder/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10296 May 30 14:12:17 volumio volumio[962]: info: Exploding uri upnp/folder/http://192.168.0.12:8096/dlna/9c8254a7-6634-467d-a5ca-f33f7ff12ec1/contentdirectory/control@10296 in service upnp_browser May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::saveQueue May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::updateTrackBlock May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrackBlock May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPlay May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::play index 0 May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::stop May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::play index undefined May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::startPlaybackTimer May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:17 volumio volumio[962]: info: [1780171937433] ControllerUPNPBrowser::clearAddPlayTrack May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand stop May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand stop took 5 milliseconds May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand clear May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:17 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand clear took 1 milliseconds May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c" May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:17 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:17 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:17 volumio volumio[962]: error: updateQueue error: null May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 11ms May 30 14:12:17 volumio volumio[962]: error: Upnp client error: Error: This socket has been ended by the other party May 30 14:12:17 volumio volumio[962]: error: updateQueue error: null May 30 14:12:17 volumio volumio[962]: error: updateQueue error: null May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c" May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 261ms May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 260ms May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:17 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand add "http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c" took 2 milliseconds May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand play May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:17 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:17 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 12ms May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand play took 10 milliseconds May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 9ms May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 4ms May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand status took 10 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand status took 8 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand status took 7 milliseconds May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:17 volumio volumio[962]: info: May 30 14:12:17 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand status took 8 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand status took 4 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand status took 4 milliseconds May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:17 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","artist":null,"album":null,"uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:17 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus stop May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:17 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","artist":null,"album":null,"uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:17 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play May 30 14:12:17 volumio volumio[962]: info: Received an update from plugin. extracting info from payload May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:17 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","artist":null,"album":null,"uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:17 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play May 30 14:12:17 volumio volumio[962]: info: Received an update from plugin. extracting info from payload May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 35ms May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 39ms May 30 14:12:17 volumio volumio[962]: info: ------------------------------ 38ms May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand playlistinfo took 27 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand playlistinfo took 24 milliseconds May 30 14:12:17 volumio volumio[962]: info: sendMpdCommand playlistinfo took 24 milliseconds May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:17 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:17 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","artist":null,"album":null,"uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:17 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play May 30 14:12:17 volumio volumio[962]: info: Received an update from plugin. extracting info from payload May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:17 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","artist":null,"album":null,"uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:17 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play May 30 14:12:17 volumio volumio[962]: info: Received an update from plugin. extracting info from payload May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:17 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:17 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","artist":null,"album":null,"uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:17 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play May 30 14:12:17 volumio volumio[962]: info: Received an update from plugin. extracting info from payload May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:17 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:17 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:18 volumio volumio[962]: info: ------------------------------ 72ms May 30 14:12:18 volumio volumio[962]: info: ------------------------------ 67ms May 30 14:12:18 volumio volumio[962]: info: May 30 14:12:18 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:18 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:18 volumio volumio[962]: info: May 30 14:12:18 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:18 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:18 volumio volumio[962]: info: May 30 14:12:18 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:18 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:18 volumio volumio[962]: info: May 30 14:12:18 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:18 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:18 volumio volumio[962]: info: May 30 14:12:18 volumio volumio[962]: ---------------------------- MPD announces system playlist update May 30 14:12:18 volumio volumio[962]: info: Ignoring MPD Status Update May 30 14:12:18 volumio volumio[962]: info: May 30 14:12:18 volumio volumio[962]: ---------------------------- MPD announces state update: player May 30 14:12:18 volumio volumio[962]: info: ControllerMpd::getState May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status May 30 14:12:18 volumio volumio[962]: info: ------------------------------ 9ms May 30 14:12:18 volumio volumio[962]: info: sendMpdCommand status took 7 milliseconds May 30 14:12:18 volumio volumio[962]: info: ------------------------------ 7ms May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:18 volumio volumio[962]: info: sendMpdCommand status took 8 milliseconds May 30 14:12:18 volumio volumio[962]: info: ------------------------------ 7ms May 30 14:12:18 volumio volumio[962]: info: sendMpdCommand status took 7 milliseconds May 30 14:12:18 volumio volumio[962]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::parseState May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:18 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:18 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Believer","artist":"American Authors","album":"Oh, What a Life","uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:18 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play May 30 14:12:18 volumio volumio[962]: info: Received an update from plugin. extracting info from payload May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:18 volumio volumio[962]: info: ------------------------------ 31ms May 30 14:12:18 volumio volumio[962]: info: sendMpdCommand playlistinfo took 15 milliseconds May 30 14:12:18 volumio volumio[962]: info: sendMpdCommand playlistinfo took 16 milliseconds May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:18 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo May 30 14:12:18 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:18 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Believer","artist":"American Authors","album":"Oh, What a Life","uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:18 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play May 30 14:12:18 volumio volumio[962]: info: Received an update from plugin. extracting info from payload May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:18 volumio volumio[962]: info: ControllerMpd::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::servicePushState May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getTrack 0 May 30 14:12:18 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":184,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Believer","artist":"American Authors","album":"Oh, What a Life","uri":"http://192.168.0.12:8096/audio/10095/original.mp3?DeviceProfileId=a6cb4d6f54054ca4a5b4f4ccb3fdae06&DeviceId=82eead03eafbc2f807d68e621b99a036&MediaSourceId=mediasource_10095&PlaySessionId=bdde25b1062e4fd2225357dd2a93e04e&api_key=c2e3323e12964a528daceb1cac20f78c","trackType":""} May 30 14:12:18 volumio volumio[962]: verbose: CURRENT POSITION 0 May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::syncState stateService play May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play May 30 14:12:18 volumio volumio[962]: info: Received an update from plugin. extracting info from payload May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::pushState May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioPushState May 30 14:12:18 volumio volumio[962]: info: ------------------------------ 48ms May 30 14:12:18 volumio volumio[962]: info: ------------------------------ 47ms May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetQueue May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::getQueue May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getQueue May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetQueue May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::getQueue May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getQueue May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetQueue May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::getQueue May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getQueue May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetQueue May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::getQueue May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getQueue May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetQueue May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::getQueue May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getQueue May 30 14:12:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetQueue May 30 14:12:18 volumio volumio[962]: info: CoreStateMachine::getQueue May 30 14:12:18 volumio volumio[962]: info: CorePlayQueue::getQueue May 30 14:12:41 volumio nmbd[739]: [2026/05/30 14:12:41.158671, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 30 14:12:41 volumio nmbd[739]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.17 for name WORKGROUP<1d>. May 30 14:12:41 volumio nmbd[739]: This response was from IP 192.168.0.10, reporting an IP address of 192.168.0.10. May 30 14:12:49 volumio volumio[962]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 14:12:49 volumio volumio[962]: TypeError: Cannot read property 'slice' of null May 30 14:12:49 volumio volumio[962]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) May 30 14:12:49 volumio volumio[962]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) May 30 14:12:49 volumio volumio[962]: at Object.onceWrapper (events.js:422:26) May 30 14:12:49 volumio volumio[962]: at ClientRequest.emit (events.js:315:20) May 30 14:12:49 volumio volumio[962]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) May 30 14:12:49 volumio volumio[962]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) May 30 14:12:49 volumio volumio[962]: at HTTPParser.execute () May 30 14:12:49 volumio volumio[962]: at TLSSocket.socketOnData (_http_client.js:509:22) May 30 14:12:49 volumio volumio[962]: at TLSSocket.emit (events.js:315:20) May 30 14:12:49 volumio volumio[962]: at addChunk (internal/streams/readable.js:309:12) May 30 14:12:49 volumio volumio[962]: at readableAddChunk (internal/streams/readable.js:284:9) May 30 14:12:49 volumio volumio[962]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) May 30 14:12:49 volumio volumio[962]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) May 30 14:12:49 volumio volumio[962]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 14:12:50 volumio sudo[26629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-30 14:11 May 30 14:12:50 volumio sudo[26629]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"