-- Logs begin at Sat 2026-04-04 10:03:22 JST, end at Sat 2026-04-04 10:05:29 JST. -- Apr 04 10:04:03 vesper sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 10:04:03 vesper sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:04:03 vesper sudo[1709]: pam_unix(sudo:session): session closed for user root Apr 04 10:04:03 vesper sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 10:04:03 vesper sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 10:04:03 vesper sudo[1712]: pam_unix(sudo:session): session closed for user root Apr 04 10:04:03 vesper volumio[950]: verbose: New Socket.io Connection to 192.168.0.27 from 192.168.0.17 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 04 10:04:04 vesper volumio[950]: info: BOOT COMPLETED Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::volumioGetVisibleSources Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::volumioGetState Apr 04 10:04:04 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 04 10:04:04 vesper volumio[950]: info: Received Get System Info Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:04:04 vesper volumio[950]: info: Discovery: Getting this device information Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::volumioGetState Apr 04 10:04:04 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::volumioGetState Apr 04 10:04:04 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:04:04 vesper volumio[950]: info: Listing playlists Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 10:04:04 vesper volumio[950]: info: CoreCommandRouter::volumioGetState Apr 04 10:04:04 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:04:07 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:04:07 vesper volumio[950]: info: Received Get System Info Apr 04 10:04:07 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:04:07 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:04:07 vesper volumio[950]: info: Discovery: Getting this device information Apr 04 10:04:07 vesper volumio[950]: info: CoreCommandRouter::volumioGetState Apr 04 10:04:07 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:04:07 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:04:07 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 04 10:04:08 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 10:04:08 vesper volumio[950]: info: Received Get System Info Apr 04 10:04:08 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:04:08 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:04:08 vesper volumio[950]: info: Discovery: Getting this device information Apr 04 10:04:08 vesper volumio[950]: info: CoreCommandRouter::volumioGetState Apr 04 10:04:08 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:04:08 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:04:09 vesper systemd[1]: systemd-timedated.service: Succeeded. Apr 04 10:04:15 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 04 10:04:29 vesper ntpd[1048]: Soliciting pool server 163.44.119.85 Apr 04 10:04:29 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 10:04:29 vesper volumio[950]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 04 10:04:29 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 04 10:04:29 vesper volumio[950]: info: Received Get System Version Apr 04 10:04:29 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 10:04:30 vesper volumio[950]: info: Received Get System Info Apr 04 10:04:30 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 10:04:30 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 10:04:30 vesper volumio[950]: info: Discovery: Getting this device information Apr 04 10:04:30 vesper volumio[950]: info: CoreCommandRouter::volumioGetState Apr 04 10:04:30 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:04:30 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 10:04:30 vesper ntpd[1048]: Soliciting pool server 129.250.35.251 Apr 04 10:04:31 vesper ntpd[1048]: Soliciting pool server 45.77.20.103 Apr 04 10:04:31 vesper ntpd[1048]: Soliciting pool server 129.250.35.250 Apr 04 10:04:31 vesper ntpd[1048]: Soliciting pool server 208.88.66.8 Apr 04 10:04:32 vesper ntpd[1048]: Soliciting pool server 162.159.200.1 Apr 04 10:04:32 vesper ntpd[1048]: Soliciting pool server 167.179.119.205 Apr 04 10:04:32 vesper ntpd[1048]: Soliciting pool server 108.160.132.224 Apr 04 10:04:32 vesper ntpd[1048]: Soliciting pool server 157.7.213.62 Apr 04 10:04:33 vesper ntpd[1048]: Soliciting pool server 142.91.108.61 Apr 04 10:04:33 vesper ntpd[1048]: Soliciting pool server 142.91.108.61 Apr 04 10:04:33 vesper ntpd[1048]: Soliciting pool server 172.105.192.74 Apr 04 10:04:34 vesper ntpd[1048]: Soliciting pool server 160.16.113.133 Apr 04 10:04:35 vesper ntpd[1048]: Soliciting pool server 43.167.237.61 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioNext Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::next Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::stop Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::play index undefined Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::startPlaybackTimer Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::clearAddPlayTracks NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::updateTrackBlock Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrackBlock Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand stop took 1 milliseconds Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand clear took 1 milliseconds Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand add "NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a" Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:04:55 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:04:55 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:04:55 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:04:55 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand add "NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a" took 3 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 3ms Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 2ms Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 1ms Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand play Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:04:55 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:04:55 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 3ms Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand play took 2 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 1ms Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 1ms Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand status took 37 milliseconds Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand status took 39 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand status took 38 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"257 Kbps","isStreaming":false,"title":"Billie's Bounce","artist":"中島紅音","album":"Jewelry Of Jazz Essence","uri":"NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a","trackType":"m4a"} Apr 04 10:04:55 vesper volumio[950]: verbose: CURRENT POSITION 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus stop Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 44ms Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:04:55 vesper volumio[950]: info: Apr 04 10:04:55 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand status took 6 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand status took 2 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand status took 1 milliseconds Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":181,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"257 Kbps","isStreaming":false,"title":"Billie's Bounce","artist":"中島紅音","album":"Jewelry Of Jazz Essence","uri":"NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a","trackType":"m4a"} Apr 04 10:04:55 vesper volumio[950]: verbose: CURRENT POSITION 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:04:55 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":181,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"257 Kbps","isStreaming":false,"title":"Billie's Bounce","artist":"中島紅音","album":"Jewelry Of Jazz Essence","uri":"NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a","trackType":"m4a"} Apr 04 10:04:55 vesper volumio[950]: verbose: CURRENT POSITION 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:04:55 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 61ms Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 61ms Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 04 10:04:55 vesper volumio[950]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:04:55 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":181,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"257 Kbps","isStreaming":false,"title":"Billie's Bounce","artist":"中島紅音","album":"Jewelry Of Jazz Essence","uri":"NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a","trackType":"m4a"} Apr 04 10:04:55 vesper volumio[950]: verbose: CURRENT POSITION 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:04:55 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":181,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"257 Kbps","isStreaming":false,"title":"Billie's Bounce","artist":"中島紅音","album":"Jewelry Of Jazz Essence","uri":"NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a","trackType":"m4a"} Apr 04 10:04:55 vesper volumio[950]: verbose: CURRENT POSITION 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:04:55 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":856,"duration":181,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"257 Kbps","isStreaming":false,"title":"Billie's Bounce","artist":"中島紅音","album":"Jewelry Of Jazz Essence","uri":"NAS/disk1/iTunes/iTunes Media/Music/中島紅音/Jewelry Of Jazz Essence/09 Billie's Bounce.m4a","trackType":"m4a"} Apr 04 10:04:55 vesper volumio[950]: verbose: CURRENT POSITION 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:04:55 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:04:55 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:04:55 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 42ms Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 39ms Apr 04 10:04:55 vesper volumio[950]: info: ------------------------------ 36ms Apr 04 10:05:11 vesper volumio[950]: info: CoreCommandRouter::volumioGetQueue Apr 04 10:05:11 vesper volumio[950]: info: CoreStateMachine::getQueue Apr 04 10:05:11 vesper volumio[950]: info: CorePlayQueue::getQueue Apr 04 10:05:13 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 04 10:05:13 vesper volumio[950]: info: CURURI: artists:// Apr 04 10:05:13 vesper volumio[950]: info: Preload queue cleared Apr 04 10:05:21 vesper volumio[950]: info: Preload queue cleared Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::ClearQueue Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::stop Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::stPlaybackTimer Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::updateTrackBlock Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrackBlock Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::serviceStop Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 913 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::serviceStop Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::stop Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::clearPlayQueue Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::saveQueue Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushQueue Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::addQueueItems Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::addQueueItems Apr 04 10:05:21 vesper volumio[950]: info: Preload queue cleared Apr 04 10:05:21 vesper volumio[950]: info: Adding Item to queue: artists://CNN Apr 04 10:05:21 vesper volumio[950]: info: Exploding uri artists://CNN in service mpd Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushQueue Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::saveQueue Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::updateTrackBlock Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrackBlock Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPlay Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::play index 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::stop Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::play index undefined Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::startPlaybackTimer Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::clearAddPlayTracks NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4 Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand stop took 38 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand stop took 25 milliseconds Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:05:21 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand clear took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand add "NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4" Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: 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} Apr 04 10:05:21 vesper volumio[950]: verbose: CURRENT POSITION 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState stateService stop Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus stop Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: No code Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: 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} Apr 04 10:05:21 vesper volumio[950]: verbose: CURRENT POSITION 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState stateService stop Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus stop Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: No code Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 27ms Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 27ms Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:05:21 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:05:21 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:05:21 vesper volumio[950]: error: updateQueue error: null Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:05:21 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 29ms Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand add "NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4" took 28 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 2ms Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 2ms Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand play Apr 04 10:05:21 vesper volumio[950]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 31ms Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:05:21 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces system playlist update Apr 04 10:05:21 vesper volumio[950]: info: Ignoring MPD Status Update Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 4ms Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand play took 3 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 3ms Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 3ms Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 165 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 164 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 163 milliseconds Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: Apr 04 10:05:21 vesper volumio[950]: ---------------------------- MPD announces state update: player Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::getState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand status Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 2 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand status took 1 milliseconds Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseState Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":600,"samplerate":22.05,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"38 Kbps","isStreaming":false,"title":"CNN Student News - 08/19/15","artist":"CNN","album":"CNN Student News (video)","uri":"NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4","trackType":"mp4"} Apr 04 10:05:21 vesper volumio[950]: verbose: CURRENT POSITION 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus stop Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20,"duration":600,"samplerate":22.05,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"38 Kbps","isStreaming":false,"title":"CNN Student News - 08/19/15","artist":"CNN","album":"CNN Student News (video)","uri":"NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4","trackType":"mp4"} Apr 04 10:05:21 vesper volumio[950]: verbose: CURRENT POSITION 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:05:21 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330,"duration":600,"samplerate":22.05,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"38 Kbps","isStreaming":false,"title":"CNN Student News - 08/19/15","artist":"CNN","album":"CNN Student News (video)","uri":"NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4","trackType":"mp4"} Apr 04 10:05:21 vesper volumio[950]: verbose: CURRENT POSITION 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:05:21 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 172ms Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 180ms Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 178ms Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 04 10:05:21 vesper volumio[950]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:05:21 vesper volumio[950]: verbose: ControllerMpd::parseTrackInfo Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1130,"duration":600,"samplerate":22.05,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"38 Kbps","isStreaming":false,"title":"CNN Student News - 08/19/15","artist":"CNN","album":"CNN Student News (video)","uri":"NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4","trackType":"mp4"} Apr 04 10:05:21 vesper volumio[950]: verbose: CURRENT POSITION 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:05:21 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1130,"duration":600,"samplerate":22.05,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"38 Kbps","isStreaming":false,"title":"CNN Student News - 08/19/15","artist":"CNN","album":"CNN Student News (video)","uri":"NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4","trackType":"mp4"} Apr 04 10:05:21 vesper volumio[950]: verbose: CURRENT POSITION 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:05:21 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: ControllerMpd::pushState Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::servicePushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1130,"duration":600,"samplerate":22.05,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"38 Kbps","isStreaming":false,"title":"CNN Student News - 08/19/15","artist":"CNN","album":"CNN Student News (video)","uri":"NAS/disk1/iTunes/iTunes Media/Music/Downloads/Podcasts/CNN Student News - 08_19_15 _ CNN St.tmp/download.mp4","trackType":"mp4"} Apr 04 10:05:21 vesper volumio[950]: verbose: CURRENT POSITION 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState stateService play Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::syncState currentStatus play Apr 04 10:05:21 vesper volumio[950]: info: Received an update from plugin. extracting info from payload Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: CoreStateMachine::pushState Apr 04 10:05:21 vesper volumio[950]: info: CorePlayQueue::getTrack 0 Apr 04 10:05:21 vesper volumio[950]: info: CoreCommandRouter::volumioPushState Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 31ms Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 31ms Apr 04 10:05:21 vesper volumio[950]: info: ------------------------------ 31ms Apr 04 10:05:29 vesper volumio[950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 10:05:29 vesper volumio[950]: TypeError: Cannot read property 'slice' of null Apr 04 10:05:29 vesper volumio[950]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 04 10:05:29 vesper volumio[950]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 04 10:05:29 vesper volumio[950]: at Object.onceWrapper (events.js:422:26) Apr 04 10:05:29 vesper volumio[950]: at ClientRequest.emit (events.js:315:20) Apr 04 10:05:29 vesper volumio[950]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Apr 04 10:05:29 vesper volumio[950]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Apr 04 10:05:29 vesper volumio[950]: at TLSSocket.socketOnData (_http_client.js:509:22) Apr 04 10:05:29 vesper volumio[950]: at TLSSocket.emit (events.js:315:20) Apr 04 10:05:29 vesper volumio[950]: at addChunk (internal/streams/readable.js:309:12) Apr 04 10:05:29 vesper volumio[950]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 04 10:05:29 vesper volumio[950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 10:05:29 vesper sudo[1931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-04 10:04 Apr 04 10:05:29 vesper sudo[1931]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"