-- Logs begin at Wed 2025-05-21 23:27:18 CST, end at Wed 2025-05-21 23:33:05 CST. -- May 21 23:32:01 volumio3ontb16p01 ntpd[1523]: Soliciting pool server 2001:1600:3:6::123 May 21 23:32:01 volumio3ontb16p01 ntpd[1523]: Soliciting pool server 116.203.151.74 May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: Discovery: Getting this device information May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 23:32:11 volumio3ontb16p01 volumio[1541]: verbose: New Socket.io Connection to 192.168.2.22:3000 from 192.168.2.26 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 21 23:32:11 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 21 23:32:16 volumio3ontb16p01 kernel: mce: [Hardware Error]: Machine check events logged May 21 23:32:16 volumio3ontb16p01 kernel: mce: [Hardware Error]: CPU 3: Machine Check: 0 Bank 4: 8400000000010151 May 21 23:32:16 volumio3ontb16p01 kernel: mce: [Hardware Error]: TSC 0 ADDR 11b104cd0 May 21 23:32:16 volumio3ontb16p01 kernel: mce: [Hardware Error]: PROCESSOR 0:406c4 TIME 1747841536 SOCKET 0 APIC 6 microcode 40b May 21 23:32:18 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:18 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:19 volumio3ontb16p01 sudo[3402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 23:32:19 volumio3ontb16p01 sudo[3402]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 23:32:19 volumio3ontb16p01 sudo[3402]: pam_unix(sudo:session): session closed for user root May 21 23:32:19 volumio3ontb16p01 sudo[3405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 23:32:19 volumio3ontb16p01 sudo[3405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 23:32:19 volumio3ontb16p01 sudo[3405]: pam_unix(sudo:session): session closed for user root May 21 23:32:19 volumio3ontb16p01 volumio[1541]: verbose: New Socket.io Connection to 192.168.2.22 from 192.168.2.26 UA: Mozilla/5.0 (Linux; Android 13; 21091116C Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.135 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 21 23:32:20 volumio3ontb16p01 sudo[3408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 23:32:20 volumio3ontb16p01 sudo[3408]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 23:32:20 volumio3ontb16p01 sudo[3408]: pam_unix(sudo:session): session closed for user root May 21 23:32:20 volumio3ontb16p01 sudo[3411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 23:32:20 volumio3ontb16p01 sudo[3411]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 23:32:20 volumio3ontb16p01 sudo[3411]: pam_unix(sudo:session): session closed for user root May 21 23:32:20 volumio3ontb16p01 volumio[1541]: verbose: New Socket.io Connection to 192.168.2.22 from 192.168.2.26 UA: Mozilla/5.0 (Linux; Android 13; 21091116C Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.135 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetVisibleSources May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: Received Get System Info May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: Discovery: Getting this device information May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: Listing playlists May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 23:32:20 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 23:32:21 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 21 23:32:22 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 23:32:22 volumio3ontb16p01 volumio[1541]: info: Received Get System Info May 21 23:32:22 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 23:32:22 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 23:32:22 volumio3ontb16p01 volumio[1541]: info: Discovery: Getting this device information May 21 23:32:22 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:22 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:22 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 23:32:24 volumio3ontb16p01 volumio[1541]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 23:32:24 volumio3ontb16p01 volumio[1541]: info: Retrying Login Due to Network Error May 21 23:32:26 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:26 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioNext May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::next May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stPlaybackTimer May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::serviceStop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 0 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::serviceStop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::stop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:29 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 20 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::play index undefined May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::startPlaybackTimer May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/02 罗刹海市.flac May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 7 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 4 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:29 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 4 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand clear took 5 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/02 罗刹海市.flac" May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"序曲","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/01 序曲.flac","trackType":"flac"} May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService stop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus stop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: No code May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 56ms May 21 23:32:29 volumio3ontb16p01 volumio[1541]: error: updateQueue error: null May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:29 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 46ms May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/02 罗刹海市.flac" took 44 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand play May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 20ms May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand play took 20 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:29 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:29 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 5 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 3 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":333,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"罗刹海市","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/02 罗刹海市.flac","trackType":"flac"} May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus stop May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 15ms May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 22 milliseconds May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":333,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"罗刹海市","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/02 罗刹海市.flac","trackType":"flac"} May 21 23:32:29 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus play May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: Received an update from plugin. extracting info from payload May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:29 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 73ms May 21 23:32:30 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 21 23:32:31 volumio3ontb16p01 volumio[1541]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 May 21 23:32:32 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:32 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:34 volumio3ontb16p01 volumio[1541]: info: MyVolumio login type: Token May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioNext May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::next May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stop May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stPlaybackTimer May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::serviceStop May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 1 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::serviceStop May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::stop May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 22 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::play index undefined May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::startPlaybackTimer May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/03 花妖.flac May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:36 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 3 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 5 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:36 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand clear took 7 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/03 花妖.flac" May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:36 volumio3ontb16p01 volumio[1541]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 14ms May 21 23:32:36 volumio3ontb16p01 volumio[1541]: error: updateQueue error: null May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:36 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 7ms May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/03 花妖.flac" took 5 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand play May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 3ms May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand play took 2 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:36 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:36 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 6 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 1 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":355,"duration":276,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1000 Kbps","isStreaming":false,"title":"花妖","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/03 花妖.flac","trackType":"flac"} May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 2 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus stop May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":355,"duration":276,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1000 Kbps","isStreaming":false,"title":"花妖","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/03 花妖.flac","trackType":"flac"} May 21 23:32:36 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 2 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus play May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: Received an update from plugin. extracting info from payload May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 49ms May 21 23:32:36 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 51ms May 21 23:32:38 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 23:32:38 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 23:32:38 volumio3ontb16p01 volumio[1541]: info: Discovery: Getting this device information May 21 23:32:38 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:38 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:38 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 23:32:39 volumio3ontb16p01 volumio[1541]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 May 21 23:32:39 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:39 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioNext May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::next May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stPlaybackTimer May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::serviceStop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 2 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::serviceStop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::stop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:41 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 30 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::play index undefined May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::startPlaybackTimer May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/04 镜听.flac May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 4 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 2 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:41 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand clear took 4 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/04 镜听.flac" May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"花妖","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/03 花妖.flac","trackType":"flac"} May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService stop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus stop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: No code May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 53ms May 21 23:32:41 volumio3ontb16p01 volumio[1541]: error: updateQueue error: null May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:41 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 47ms May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/04 镜听.flac" took 45 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand play May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 12ms May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand play took 11 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:41 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:41 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 5 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 2 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"镜听","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/04 镜听.flac","trackType":"flac"} May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus stop May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 13ms May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 4 milliseconds May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"镜听","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/04 镜听.flac","trackType":"flac"} May 21 23:32:41 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus play May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: Received an update from plugin. extracting info from payload May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:41 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 56ms May 21 23:32:44 volumio3ontb16p01 volumio[1541]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 May 21 23:32:44 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:44 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioNext May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::next May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stop May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stPlaybackTimer May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::serviceStop May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 3 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::serviceStop May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::stop May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 26 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::play index undefined May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::startPlaybackTimer May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/05 路南柯.flac May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:46 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 3 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:46 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 4 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand clear took 3 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/05 路南柯.flac" May 21 23:32:46 volumio3ontb16p01 volumio[1541]: error: updateQueue error: null May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:46 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 8ms May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/05 路南柯.flac" took 7 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand play May 21 23:32:46 volumio3ontb16p01 volumio[1541]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 15ms May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 7ms May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand play took 4 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:46 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:46 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 10 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 8 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"路南柯","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/05 路南柯.flac","trackType":"flac"} May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 4 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus stop May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 34ms May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 9 milliseconds May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":146,"duration":289,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1223 Kbps","isStreaming":false,"title":"路南柯","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/05 路南柯.flac","trackType":"flac"} May 21 23:32:46 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 4 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus play May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: Received an update from plugin. extracting info from payload May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:46 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 80ms May 21 23:32:49 volumio3ontb16p01 volumio[1541]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 May 21 23:32:49 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:49 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioNext May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::next May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stop May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stPlaybackTimer May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::serviceStop May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 4 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::serviceStop May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::stop May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 27 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::play index undefined May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::startPlaybackTimer May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/06 颠倒歌.flac May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::updateTrackBlock May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrackBlock May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:55 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand stop took 4 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 2 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:55 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand clear took 3 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 4 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/06 颠倒歌.flac" May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:55 volumio3ontb16p01 volumio[1541]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 10ms May 21 23:32:55 volumio3ontb16p01 volumio[1541]: error: updateQueue error: null May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:55 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces system playlist update May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: Ignoring MPD Status Update May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 6ms May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand add "NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/06 颠倒歌.flac" took 4 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand play May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 4ms May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand play took 3 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:55 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: May 21 23:32:55 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 5 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 4 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 4 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"颠倒歌","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/06 颠倒歌.flac","trackType":"flac"} May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 5 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus stop May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 14ms May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"颠倒歌","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/06 颠倒歌.flac","trackType":"flac"} May 21 23:32:55 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 5 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService play May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus play May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: Received an update from plugin. extracting info from payload May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:32:55 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 59ms May 21 23:32:56 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:56 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:32:58 volumio3ontb16p01 volumio[1541]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 May 21 23:32:58 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioGetState May 21 23:32:58 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:33:04 volumio3ontb16p01 volumio[1541]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 23:33:04 volumio3ontb16p01 volumio[1541]: info: Retrying Login Due to Network Error May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPause May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pause May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stPlaybackTimer May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::servicePause May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePause May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pause May 21 23:33:05 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand pause May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand pause took 3 milliseconds May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: May 21 23:33:05 volumio3ontb16p01 volumio[1541]: ---------------------------- MPD announces state update: player May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::getState May 21 23:33:05 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand status May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand status took 1 milliseconds May 21 23:33:05 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseState May 21 23:33:05 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:33:05 volumio3ontb16p01 volumio[1541]: verbose: ControllerMpd::parseTrackInfo May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: ControllerMpd::pushState May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::servicePushState May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:33:05 volumio3ontb16p01 volumio[1541]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10123,"duration":218,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1953 Kbps","isStreaming":false,"title":"颠倒歌","artist":"刀郎","album":"山歌寥哉","uri":"NAS/Music_Box/00CD/刀郎.-.[山歌寥哉]/06 颠倒歌.flac","trackType":"flac"} May 21 23:33:05 volumio3ontb16p01 volumio[1541]: verbose: CURRENT POSITION 5 May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState stateService pause May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::syncState currentStatus pause May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::pushState May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CorePlayQueue::getTrack 5 May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreCommandRouter::volumioPushState May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: CoreStateMachine::stPlaybackTimer May 21 23:33:05 volumio3ontb16p01 volumio[1541]: info: ------------------------------ 21ms May 21 23:33:05 volumio3ontb16p01 volumio[1541]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 23:33:05 volumio3ontb16p01 volumio[1541]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Object.readdirSync (fs.js:1048:3) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) May 21 23:33:05 volumio3ontb16p01 volumio[1541]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { May 21 23:33:05 volumio3ontb16p01 volumio[1541]: errno: -20, May 21 23:33:05 volumio3ontb16p01 volumio[1541]: syscall: 'scandir', May 21 23:33:05 volumio3ontb16p01 volumio[1541]: code: 'ENOTDIR', May 21 23:33:05 volumio3ontb16p01 volumio[1541]: path: '/tmp/peppyrunning' May 21 23:33:05 volumio3ontb16p01 volumio[1541]: } May 21 23:33:05 volumio3ontb16p01 volumio[1541]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 23:33:05 volumio3ontb16p01 sudo[3814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 23:32 May 21 23:33:05 volumio3ontb16p01 sudo[3814]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"