-- Logs begin at Sun 2024-04-28 02:34:15 UTC, end at Mon 2024-04-29 05:20:33 UTC. --
Apr 29 05:19:00 volumio volumio[6552]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 29 05:19:00 volumio volumio[6552]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 29 05:19:00 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 29 05:19:00 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 29 05:19:00 volumio volumio[6552]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 29 05:19:00 volumio volumio[6552]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 29 05:19:00 volumio volumio[6552]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 29 05:19:00 volumio volumio[6552]: info: Streaming services startup
Apr 29 05:19:00 volumio volumio[6552]: info: Starting Streaming Daemon
Apr 29 05:19:00 volumio sudo[6795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 29 05:19:00 volumio sudo[6795]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:19:00 volumio systemd[1]: Stopping Volumio Streaming Daemon...
Apr 29 05:19:00 volumio systemd[1]: Starting Volumio Streaming Daemon...
Apr 29 05:19:00 volumio volumio[6552]: info: MyVolumio not started
Apr 29 05:19:00 volumio volumio[6552]: info: Initializing device activation check
Apr 29 05:19:00 volumio systemd[1]: Started Volumio Streaming Daemon.
Apr 29 05:19:00 volumio sudo[6795]: pam_unix(sudo:session): session closed for user root
Apr 29 05:19:00 volumio volumio[6552]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 29 05:19:00 volumio volumio-streaming-daemon[6805]: ############################
Apr 29 05:19:00 volumio volumio-streaming-daemon[6805]: # Volumio Streaming Daemon #
Apr 29 05:19:00 volumio volumio-streaming-daemon[6805]: # Running on port 7777 #
Apr 29 05:19:00 volumio volumio-streaming-daemon[6805]: ############################
Apr 29 05:19:00 volumio volumio-streaming-daemon[6805]: Environment: production
Apr 29 05:19:00 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount.
Apr 29 05:19:00 volumio sudo[6753]: pam_unix(sudo:session): session closed for user root
Apr 29 05:19:00 volumio kernel: Status code returned 0xc0000022 STATUS_ACCESS_DENIED
Apr 29 05:19:00 volumio kernel: CIFS VFS: Send error in SessSetup = -13
Apr 29 05:19:00 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -13
Apr 29 05:19:00 volumio volumio[6552]: info: Cannot mount NAS TDDOWNLOAD at system boot, trial number 2 ,retrying in 5 seconds
Apr 29 05:19:01 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:02 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:02 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:02 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:02 volumio volumio[6552]: info:
Apr 29 05:19:02 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:02 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:02 volumio volumio[6552]: info:
Apr 29 05:19:02 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:02 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:02 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:02 volumio volumio[6552]: info: sendMpdCommand stop took 124 milliseconds
Apr 29 05:19:02 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:02 volumio volumio[6552]: info: ------------------------------ 5ms
Apr 29 05:19:02 volumio volumio[6552]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:19:02 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:02 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:02 volumio volumio[6552]: 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 29 05:19:02 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:02 volumio volumio[6552]: info: No code
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:02 volumio volumio[6552]: info: ------------------------------ 22ms
Apr 29 05:19:02 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:02 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:02 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:02 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:02 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:02 volumio volumio-streaming-daemon[6805]: Environment: production
Apr 29 05:19:02 volumio volumio[6552]: info: Fetching Streaming Services browse cache
Apr 29 05:19:02 volumio volumio[6552]: info:
Apr 29 05:19:02 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:02 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:02 volumio volumio[6552]: info: ------------------------------ 8ms
Apr 29 05:19:02 volumio volumio[6552]: info:
Apr 29 05:19:02 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:02 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:02 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:02 volumio volumio[6552]: info:
Apr 29 05:19:02 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:02 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:02 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:02 volumio volumio[6552]: info:
Apr 29 05:19:02 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:02 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:02 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:02 volumio volumio[6552]: info:
Apr 29 05:19:02 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:02 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:02 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:02 volumio volumio[6552]: info:
Apr 29 05:19:02 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:02 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:02 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:03 volumio volumio[6552]: info:
Apr 29 05:19:03 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:03 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:03 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:03 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:03 volumio volumio[6552]: info:
Apr 29 05:19:03 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:03 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:03 volumio volumio[6552]: info:
Apr 29 05:19:03 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:03 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:19:03 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:03 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:03 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:03 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:03 volumio volumio[6552]: info: ------------------------------ 6ms
Apr 29 05:19:03 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:19:03 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 5 milliseconds
Apr 29 05:19:03 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:03 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:03 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:03 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:03 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:03 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Il Barbiere Di Siviglia: Ecco Ridente In Cielo (Conte)","artist":"London Symphony Orchestra","album":"Nicolai Gedda - Lyric Poet of the Tenor Voice","uri":"http://isure6.stream.qqmusic.qq.com/M500000Wre541Kmusc.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=99EA312C7F65778491F88C046B13F3C656B7A0A981FD7472D06A67BBA65683ED06542FEB6F472DF7211A3EC822DDB58B9F1806F05A593BFF&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:03 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:03 volumio volumio[6552]: info: ------------------------------ 36ms
Apr 29 05:19:03 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 14 milliseconds
Apr 29 05:19:03 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:03 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:03 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:03 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":302,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Il Barbiere Di Siviglia: Ecco Ridente In Cielo (Conte)","artist":"London Symphony Orchestra","album":"Nicolai Gedda - Lyric Poet of the Tenor Voice","uri":"http://isure6.stream.qqmusic.qq.com/M500000Wre541Kmusc.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=99EA312C7F65778491F88C046B13F3C656B7A0A981FD7472D06A67BBA65683ED06542FEB6F472DF7211A3EC822DDB58B9F1806F05A593BFF&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:03 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:03 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:03 volumio volumio[6552]: info: ------------------------------ 44ms
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:03 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:03 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:03 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:03 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:03 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:05 volumio sudo[6846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //X201-PC/TDDOWNLOAD /mnt/NAS/TDDOWNLOAD
Apr 29 05:19:05 volumio sudo[6846]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:19:05 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount.
Apr 29 05:19:05 volumio sudo[6846]: pam_unix(sudo:session): session closed for user root
Apr 29 05:19:05 volumio kernel: Status code returned 0xc0000022 STATUS_ACCESS_DENIED
Apr 29 05:19:05 volumio kernel: CIFS VFS: Send error in SessSetup = -13
Apr 29 05:19:05 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -13
Apr 29 05:19:05 volumio volumio[6552]: info: Cannot mount NAS TDDOWNLOAD at system boot, trial number 3 ,retrying in 5 seconds
Apr 29 05:19:06 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:07 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:07 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:07 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:07 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:07 volumio volumio[6552]: info: sendMpdCommand stop took 33 milliseconds
Apr 29 05:19:07 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:07 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:07 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 5ms
Apr 29 05:19:07 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:07 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:07 volumio volumio[6552]: 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 29 05:19:07 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:07 volumio volumio[6552]: info: No code
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 23ms
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:07 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:07 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:07 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:07 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:07 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:07 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:07 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:07 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:07 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:19:07 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:07 volumio volumio[6552]: info:
Apr 29 05:19:07 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:07 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:07 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:19:07 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:07 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:07 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:07 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Norma, Act I, Scene 1: Ah! Bello A Me Ritorna!","artist":"Münchner Rundfunkorchester","album":"Edita Gruberova: A Portrait - Belcanto Festival","uri":"http://isure6.stream.qqmusic.qq.com/M500002nop3i0EWvy5.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=85430ABBF160C0456D679E288B281D38308DFF23322BCD3595CF2A5B662D5A9B8556904E98A1F2ADCDCD14AED091E822D942DF476BB44427&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:07 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 33ms
Apr 29 05:19:07 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 29 05:19:07 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:07 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:07 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:07 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":205,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Norma, Act I, Scene 1: Ah! Bello A Me Ritorna!","artist":"Münchner Rundfunkorchester","album":"Edita Gruberova: A Portrait - Belcanto Festival","uri":"http://isure6.stream.qqmusic.qq.com/M500002nop3i0EWvy5.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=85430ABBF160C0456D679E288B281D38308DFF23322BCD3595CF2A5B662D5A9B8556904E98A1F2ADCDCD14AED091E822D942DF476BB44427&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:07 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:07 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:07 volumio volumio[6552]: info: ------------------------------ 38ms
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:07 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:10 volumio sudo[6882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //X201-PC/TDDOWNLOAD /mnt/NAS/TDDOWNLOAD
Apr 29 05:19:10 volumio sudo[6882]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:19:11 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount.
Apr 29 05:19:11 volumio sudo[6882]: pam_unix(sudo:session): session closed for user root
Apr 29 05:19:11 volumio kernel: Status code returned 0xc0000022 STATUS_ACCESS_DENIED
Apr 29 05:19:11 volumio kernel: CIFS VFS: Send error in SessSetup = -13
Apr 29 05:19:11 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -13
Apr 29 05:19:11 volumio volumio[6552]: info: Cannot mount NAS TDDOWNLOAD at system boot, trial number 4 ,retrying in 5 seconds
Apr 29 05:19:11 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:11 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:11 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:11 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:11 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:11 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:11 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:11 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:11 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:11 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:11 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:11 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:11 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:11 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:11 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:11 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:11 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:11 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:11 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:11 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:11 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:12 volumio volumio[6552]: info: sendMpdCommand stop took 41 milliseconds
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:12 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:12 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:12 volumio volumio[6552]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:12 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:12 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:12 volumio volumio[6552]: 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 29 05:19:12 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:12 volumio volumio[6552]: info: No code
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 20ms
Apr 29 05:19:12 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:12 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:12 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:12 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 20ms
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:12 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:12 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:12 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:12 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:12 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:12 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:12 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:12 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:12 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:12 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:12 volumio volumio[6552]: info:
Apr 29 05:19:12 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:12 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:12 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:12 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:12 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:12 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":512,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lakmé, Act II: \"Ah ! Par les dieux inspirés...Où va la jeune hindoue...\" (Lakmé)","artist":"Natalie Dessay","album":"Le miracle d'une voix","uri":"http://isure6.stream.qqmusic.qq.com/M500001fqJhU1V2R1v.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=761BC062DE1957BF0F1DD46E1B2F19F0E7E105DDBDEC3FD0C1BB3824B7CB2BF454934E82EAF1C93D083D88A3E3A01E72A8D88C17B16088D2&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:12 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 29ms
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 14ms
Apr 29 05:19:12 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:12 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:12 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 6 milliseconds
Apr 29 05:19:12 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:12 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:12 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:12 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":512,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lakmé, Act II: \"Ah ! Par les dieux inspirés...Où va la jeune hindoue...\" (Lakmé)","artist":"Natalie Dessay","album":"Le miracle d'une voix","uri":"http://isure6.stream.qqmusic.qq.com/M500001fqJhU1V2R1v.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=761BC062DE1957BF0F1DD46E1B2F19F0E7E105DDBDEC3FD0C1BB3824B7CB2BF454934E82EAF1C93D083D88A3E3A01E72A8D88C17B16088D2&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:12 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:12 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:12 volumio volumio[6552]: info: ------------------------------ 37ms
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:12 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:12 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:12 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:12 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:16 volumio sudo[6917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //X201-PC/TDDOWNLOAD /mnt/NAS/TDDOWNLOAD
Apr 29 05:19:16 volumio sudo[6917]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:19:16 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount.
Apr 29 05:19:16 volumio sudo[6917]: pam_unix(sudo:session): session closed for user root
Apr 29 05:19:16 volumio kernel: Status code returned 0xc0000022 STATUS_ACCESS_DENIED
Apr 29 05:19:16 volumio kernel: CIFS VFS: Send error in SessSetup = -13
Apr 29 05:19:16 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -13
Apr 29 05:19:16 volumio volumio[6552]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Apr 29 05:19:18 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:18 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:18 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:18 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:18 volumio volumio[6552]: info:
Apr 29 05:19:18 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:18 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:18 volumio volumio[6552]: info:
Apr 29 05:19:18 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:18 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:18 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:18 volumio volumio[6552]: info: sendMpdCommand stop took 17 milliseconds
Apr 29 05:19:18 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:18 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:19:18 volumio volumio[6552]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:19:18 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:18 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:18 volumio volumio[6552]: 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 29 05:19:18 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:18 volumio volumio[6552]: info: No code
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:18 volumio volumio[6552]: info: ------------------------------ 19ms
Apr 29 05:19:18 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:18 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:18 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:18 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:18 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:19 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:19 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:19 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:19 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:19 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:19 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:19 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:19 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:19 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:19 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:19:19 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:19 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:19 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:19 volumio volumio[6552]: info:
Apr 29 05:19:19 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:19 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:19 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:19 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 29 05:19:19 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:19 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:19 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:19 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":414,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Act I: Caro nome","artist":"Anna Moffo","album":"Le Pass Classique RTL","uri":"http://isure6.stream.qqmusic.qq.com/M500001FExNq4aysnV.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=CB843E06DCBF06D3E0CFE2442C466E5A714F2386A39CE5E41FBC385AA601F64CC9E1F3DF122C1D39F60421102163A98022EE718250D7A19B&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:19 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 28ms
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 14ms
Apr 29 05:19:19 volumio volumio[6552]: info: sendMpdCommand status took 15 milliseconds
Apr 29 05:19:19 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:19 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:19 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:19 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 6 milliseconds
Apr 29 05:19:19 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:19 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:19 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:19 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":414,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Act I: Caro nome","artist":"Anna Moffo","album":"Le Pass Classique RTL","uri":"http://isure6.stream.qqmusic.qq.com/M500001FExNq4aysnV.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=CB843E06DCBF06D3E0CFE2442C466E5A714F2386A39CE5E41FBC385AA601F64CC9E1F3DF122C1D39F60421102163A98022EE718250D7A19B&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:19 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:19 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:19 volumio volumio[6552]: info: ------------------------------ 38ms
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:19 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:19 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:19 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:19 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:23 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:23 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:23 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:23 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:23 volumio volumio[6552]: info:
Apr 29 05:19:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:23 volumio volumio[6552]: info:
Apr 29 05:19:23 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:23 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:23 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:23 volumio volumio[6552]: info: sendMpdCommand stop took 57 milliseconds
Apr 29 05:19:23 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:23 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:23 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:23 volumio volumio[6552]: info: ------------------------------ 7ms
Apr 29 05:19:23 volumio volumio[6552]: info: sendMpdCommand status took 6 milliseconds
Apr 29 05:19:23 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:23 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:23 volumio volumio[6552]: 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 29 05:19:23 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:23 volumio volumio[6552]: info: No code
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:23 volumio volumio[6552]: info: ------------------------------ 28ms
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:23 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:23 volumio volumio[6552]: info:
Apr 29 05:19:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:23 volumio volumio[6552]: info:
Apr 29 05:19:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:23 volumio volumio[6552]: info:
Apr 29 05:19:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:23 volumio volumio[6552]: info:
Apr 29 05:19:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:23 volumio volumio[6552]: info:
Apr 29 05:19:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:23 volumio volumio[6552]: info:
Apr 29 05:19:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:23 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:24 volumio volumio[6552]: info:
Apr 29 05:19:24 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:24 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:24 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:24 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:24 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:19:24 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:24 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:24 volumio volumio[6552]: info:
Apr 29 05:19:24 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:24 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:24 volumio volumio[6552]: info:
Apr 29 05:19:24 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:24 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:24 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:24 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:19:24 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:24 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:24 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:24 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Verdi: La Traviata / Act 1 - \"Sempre libera\"","artist":"Anna Netrebko","album":"Anna Netrebko - Sempre libera","uri":"http://isure6.stream.qqmusic.qq.com/M5000043cPlY104OuQ.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=BE0FDBC4C336BC1D8901BA3B7671083FCA0A3E3E44917C6C48CAFBBAE229B2D77BD2520206F3108197BD414E0496EE86019473415D8DC354&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:24 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:24 volumio volumio[6552]: info: ------------------------------ 34ms
Apr 29 05:19:24 volumio volumio[6552]: info: ------------------------------ 21ms
Apr 29 05:19:24 volumio volumio[6552]: info: sendMpdCommand status took 20 milliseconds
Apr 29 05:19:24 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:24 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:24 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:24 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 5 milliseconds
Apr 29 05:19:24 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:24 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:24 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:24 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":219,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Verdi: La Traviata / Act 1 - \"Sempre libera\"","artist":"Anna Netrebko","album":"Anna Netrebko - Sempre libera","uri":"http://isure6.stream.qqmusic.qq.com/M5000043cPlY104OuQ.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=BE0FDBC4C336BC1D8901BA3B7671083FCA0A3E3E44917C6C48CAFBBAE229B2D77BD2520206F3108197BD414E0496EE86019473415D8DC354&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:24 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:24 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:24 volumio volumio[6552]: info: ------------------------------ 43ms
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:24 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:24 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:24 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:24 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:25 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:25 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:25 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:25 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:25 volumio volumio[6552]: info:
Apr 29 05:19:25 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:25 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:25 volumio volumio[6552]: info:
Apr 29 05:19:25 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:25 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:25 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:25 volumio volumio[6552]: info: sendMpdCommand stop took 18 milliseconds
Apr 29 05:19:25 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:25 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:25 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:25 volumio volumio[6552]: info: ------------------------------ 5ms
Apr 29 05:19:25 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:19:25 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:25 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:25 volumio volumio[6552]: 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 29 05:19:25 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:25 volumio volumio[6552]: info: No code
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:25 volumio volumio[6552]: info: ------------------------------ 20ms
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:25 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:26 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:26 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:26 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:26 volumio volumio[6552]: info:
Apr 29 05:19:26 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:26 volumio volumio[6552]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:19:26 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:26 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:26 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:26 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 8ms
Apr 29 05:19:26 volumio volumio[6552]: info: sendMpdCommand status took 7 milliseconds
Apr 29 05:19:26 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 6 milliseconds
Apr 29 05:19:26 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:26 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:26 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:26 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:26 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:26 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":127,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gianni Schicchi: O mio babbino caro (Lauretta)","artist":"Angela Gheorghiu","album":"100 Best 20th Century Classics","uri":"http://isure6.stream.qqmusic.qq.com/M500003lf1l34J1KE0.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=9450CF7D05AC4BDD294A34B0F394E3E85B7661BFEBA96DA1A227B078A66D6398440638BC341276B220C0711906B55022DA733EE46B04EFE6&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:26 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 36ms
Apr 29 05:19:26 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 14 milliseconds
Apr 29 05:19:26 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:26 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:26 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:26 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":127,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Gianni Schicchi: O mio babbino caro (Lauretta)","artist":"Angela Gheorghiu","album":"100 Best 20th Century Classics","uri":"http://isure6.stream.qqmusic.qq.com/M500003lf1l34J1KE0.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=9450CF7D05AC4BDD294A34B0F394E3E85B7661BFEBA96DA1A227B078A66D6398440638BC341276B220C0711906B55022DA733EE46B04EFE6&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:26 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:26 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:26 volumio volumio[6552]: info: ------------------------------ 86ms
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:29 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:29 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:29 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:29 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:29 volumio volumio[6552]: info:
Apr 29 05:19:29 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:29 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:29 volumio volumio[6552]: info:
Apr 29 05:19:29 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:29 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:29 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:29 volumio volumio[6552]: info: sendMpdCommand stop took 67 milliseconds
Apr 29 05:19:29 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:29 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:19:29 volumio volumio[6552]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:19:29 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:29 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:29 volumio volumio[6552]: 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 29 05:19:29 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:29 volumio volumio[6552]: info: No code
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:29 volumio volumio[6552]: info: ------------------------------ 19ms
Apr 29 05:19:29 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:29 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:29 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:29 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:29 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:29 volumio volumio[6552]: info:
Apr 29 05:19:29 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:29 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:29 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:29 volumio volumio[6552]: info:
Apr 29 05:19:30 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:30 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:30 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:30 volumio volumio[6552]: info:
Apr 29 05:19:30 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:30 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:30 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:30 volumio volumio[6552]: info:
Apr 29 05:19:30 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:30 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:30 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:30 volumio volumio[6552]: info:
Apr 29 05:19:30 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:30 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:30 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:30 volumio volumio[6552]: info:
Apr 29 05:19:30 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:30 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:30 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:30 volumio volumio[6552]: info:
Apr 29 05:19:30 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:30 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:30 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:30 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:30 volumio volumio[6552]: info:
Apr 29 05:19:30 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:30 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:30 volumio volumio[6552]: info:
Apr 29 05:19:30 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:30 volumio volumio[6552]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:19:30 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:30 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:30 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:30 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:30 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:19:30 volumio volumio[6552]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:19:30 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:19:30 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:30 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:30 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:30 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:30 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:30 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":330,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nabucco, Act 3: \"Va pensiero\" (Chorus of the Hebrew Slaves)","artist":"Bernard Haitink","album":"100 Best Classics One (CD3)","uri":"http://isure6.stream.qqmusic.qq.com/M500001L6VAK0IAktw.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=2A153B2136C8495D37259D8156E9D53E1F9A135138F915296E24A63918523759E0318AC8E1B925EA1A206CD60FA583D174059CD0FFB960EF&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:30 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:30 volumio volumio[6552]: info: ------------------------------ 29ms
Apr 29 05:19:30 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 9 milliseconds
Apr 29 05:19:30 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:30 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:30 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:30 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":330,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Nabucco, Act 3: \"Va pensiero\" (Chorus of the Hebrew Slaves)","artist":"Bernard Haitink","album":"100 Best Classics One (CD3)","uri":"http://isure6.stream.qqmusic.qq.com/M500001L6VAK0IAktw.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=2A153B2136C8495D37259D8156E9D53E1F9A135138F915296E24A63918523759E0318AC8E1B925EA1A206CD60FA583D174059CD0FFB960EF&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:30 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:30 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:30 volumio volumio[6552]: info: ------------------------------ 29ms
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:30 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:30 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:30 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:30 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:30 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:34 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:34 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 29 05:19:34 volumio volumio[6552]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 29 05:19:34 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 29 05:19:34 volumio volumio[6552]: info: Listing playlists
Apr 29 05:19:34 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:34 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:34 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:36 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:37 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:37 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:37 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:37 volumio volumio[6552]: info:
Apr 29 05:19:37 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:37 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:37 volumio volumio[6552]: info:
Apr 29 05:19:37 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:37 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:37 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:37 volumio volumio[6552]: info: sendMpdCommand stop took 64 milliseconds
Apr 29 05:19:37 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:37 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:37 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:37 volumio volumio[6552]: info: ------------------------------ 5ms
Apr 29 05:19:37 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:19:37 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:37 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:37 volumio volumio[6552]: 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 29 05:19:37 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:37 volumio volumio[6552]: info: No code
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:37 volumio volumio[6552]: info: ------------------------------ 22ms
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:37 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:37 volumio volumio[6552]: info:
Apr 29 05:19:37 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:37 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:37 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:37 volumio volumio[6552]: info:
Apr 29 05:19:37 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:37 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:37 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:37 volumio volumio[6552]: info:
Apr 29 05:19:37 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:37 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:37 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:37 volumio volumio[6552]: info:
Apr 29 05:19:37 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:37 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:37 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:37 volumio volumio[6552]: info:
Apr 29 05:19:37 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:37 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:37 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:37 volumio volumio[6552]: info:
Apr 29 05:19:37 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:37 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:37 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:38 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:38 volumio volumio[6552]: info:
Apr 29 05:19:38 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:38 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:38 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:38 volumio volumio[6552]: info:
Apr 29 05:19:38 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:38 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:38 volumio volumio[6552]: info:
Apr 29 05:19:38 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:38 volumio volumio[6552]: info: sendMpdCommand status took 12 milliseconds
Apr 29 05:19:38 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:38 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:38 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:38 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:38 volumio volumio[6552]: info: ------------------------------ 5ms
Apr 29 05:19:38 volumio volumio[6552]: info: sendMpdCommand status took 9 milliseconds
Apr 29 05:19:38 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 29 05:19:38 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:38 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:38 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:38 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:38 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:38 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Les Contes d'Hoffmann, '(The) Tales of Hoffmann' : Belle nuit, ô nuit d'amour","artist":"Elisabeth Schwarzkopf","album":"The Most Relaxing Classical Album in The World....Ever!","uri":"http://isure6.stream.qqmusic.qq.com/M500001PaCC21vrtUt.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=02AA53126768C738E24A107B887EA00187DB0C3B51B8D1B806443AAF3007BF4E9725EBFF07FBD61C4BD370DBE4D66886CA0EB60337E41BF1&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:38 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:38 volumio volumio[6552]: info: ------------------------------ 43ms
Apr 29 05:19:38 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 16 milliseconds
Apr 29 05:19:38 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:38 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:38 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:38 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":232,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Les Contes d'Hoffmann, '(The) Tales of Hoffmann' : Belle nuit, ô nuit d'amour","artist":"Elisabeth Schwarzkopf","album":"The Most Relaxing Classical Album in The World....Ever!","uri":"http://isure6.stream.qqmusic.qq.com/M500001PaCC21vrtUt.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=02AA53126768C738E24A107B887EA00187DB0C3B51B8D1B806443AAF3007BF4E9725EBFF07FBD61C4BD370DBE4D66886CA0EB60337E41BF1&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:38 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:38 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:38 volumio volumio[6552]: info: ------------------------------ 46ms
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:38 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:38 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:38 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:38 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:38 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:40 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:41 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:41 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:41 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:41 volumio volumio[6552]: info:
Apr 29 05:19:41 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:41 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:41 volumio volumio[6552]: info:
Apr 29 05:19:41 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:41 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:41 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:41 volumio volumio[6552]: info: sendMpdCommand stop took 38 milliseconds
Apr 29 05:19:41 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:41 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:41 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:41 volumio volumio[6552]: info: ------------------------------ 9ms
Apr 29 05:19:41 volumio volumio[6552]: info: sendMpdCommand status took 9 milliseconds
Apr 29 05:19:41 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:41 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:41 volumio volumio[6552]: 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 29 05:19:41 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:41 volumio volumio[6552]: info: No code
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:41 volumio volumio[6552]: info: ------------------------------ 32ms
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:41 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:41 volumio volumio[6552]: info:
Apr 29 05:19:41 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:41 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:41 volumio volumio[6552]: info:
Apr 29 05:19:41 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:41 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:41 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:41 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:41 volumio volumio[6552]: info:
Apr 29 05:19:41 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:41 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:41 volumio volumio[6552]: info:
Apr 29 05:19:41 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:41 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:41 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:41 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:41 volumio volumio[6552]: info:
Apr 29 05:19:41 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:41 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:41 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:41 volumio volumio[6552]: info:
Apr 29 05:19:41 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:41 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:41 volumio volumio[6552]: info: ------------------------------ 9ms
Apr 29 05:19:42 volumio volumio[6552]: info:
Apr 29 05:19:42 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:42 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:42 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:42 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:42 volumio volumio[6552]: info:
Apr 29 05:19:42 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:42 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:42 volumio volumio[6552]: info:
Apr 29 05:19:42 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:42 volumio volumio[6552]: info: sendMpdCommand status took 54 milliseconds
Apr 29 05:19:42 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:42 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:42 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:42 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:42 volumio volumio[6552]: info: ------------------------------ 7ms
Apr 29 05:19:42 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:19:42 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:19:42 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:42 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:42 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:42 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:42 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:42 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Bohemian Girl / Act 2:I Dreamt I Dwelt In Marble Halls","artist":"Richard Bonynge","album":"Irelands Call: Songs From The Land Of Giants","uri":"http://isure6.stream.qqmusic.qq.com/M500002QX3IJ4WuFOD.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=0F0523755604DD7CE9AFE3599D30C423E9B71DFF148BA3AC25DB3A0604835323D1BAA1ADFA673CF26BD6B5094EAD4B2534671C131CE3D92E&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:42 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:42 volumio volumio[6552]: info: ------------------------------ 75ms
Apr 29 05:19:42 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 29 05:19:42 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:42 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:42 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:42 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Bohemian Girl / Act 2:I Dreamt I Dwelt In Marble Halls","artist":"Richard Bonynge","album":"Irelands Call: Songs From The Land Of Giants","uri":"http://isure6.stream.qqmusic.qq.com/M500002QX3IJ4WuFOD.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=0F0523755604DD7CE9AFE3599D30C423E9B71DFF148BA3AC25DB3A0604835323D1BAA1ADFA673CF26BD6B5094EAD4B2534671C131CE3D92E&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:42 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:42 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:42 volumio volumio[6552]: info: ------------------------------ 39ms
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:42 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:42 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:42 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:42 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:42 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:45 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:45 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:45 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:45 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:45 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:45 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:45 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:45 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:45 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:46 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:46 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:46 volumio volumio[6552]: info:
Apr 29 05:19:46 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:46 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:46 volumio volumio[6552]: info:
Apr 29 05:19:46 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:46 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:46 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:46 volumio volumio[6552]: info: sendMpdCommand stop took 96 milliseconds
Apr 29 05:19:46 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:46 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:46 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:46 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:19:46 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:19:46 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:46 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:46 volumio volumio[6552]: 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 29 05:19:46 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:46 volumio volumio[6552]: info: No code
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:46 volumio volumio[6552]: info: ------------------------------ 23ms
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:46 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:46 volumio volumio[6552]: info:
Apr 29 05:19:46 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:46 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:46 volumio volumio[6552]: info:
Apr 29 05:19:46 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:46 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:46 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:19:46 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:19:46 volumio volumio[6552]: info:
Apr 29 05:19:46 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:46 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:46 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:46 volumio volumio[6552]: info:
Apr 29 05:19:46 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:46 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:46 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:46 volumio volumio[6552]: info:
Apr 29 05:19:46 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:46 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:46 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:46 volumio volumio[6552]: info:
Apr 29 05:19:46 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:46 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:46 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:47 volumio volumio[6552]: info:
Apr 29 05:19:47 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:47 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:47 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:47 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:47 volumio volumio[6552]: info:
Apr 29 05:19:47 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:47 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:47 volumio volumio[6552]: info:
Apr 29 05:19:47 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:47 volumio volumio[6552]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:19:47 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:47 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:47 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:47 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:47 volumio volumio[6552]: info: ------------------------------ 6ms
Apr 29 05:19:47 volumio volumio[6552]: info: sendMpdCommand status took 5 milliseconds
Apr 29 05:19:47 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:19:47 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:47 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:47 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:47 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:47 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:47 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Die Entführung aus dem Serail, K.384 / Act 1 -Ach ich liebte, war so glücklich","artist":"Renée Fleming","album":"Visions of Love ~ Mozart Arias","uri":"http://isure6.stream.qqmusic.qq.com/M5000044Xbfh12Neqy.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=09992B19F2CAF595BBFC36DF1BD3BD236D42B3F47ECDCF0257654FBBA11EF55462952DAB0AD84319D92F1CBE4954EF8BAEE4EB8BA3F8E095&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:47 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:47 volumio volumio[6552]: info: ------------------------------ 40ms
Apr 29 05:19:47 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 18 milliseconds
Apr 29 05:19:47 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:47 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:47 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:47 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Die Entführung aus dem Serail, K.384 / Act 1 -Ach ich liebte, war so glücklich","artist":"Renée Fleming","album":"Visions of Love ~ Mozart Arias","uri":"http://isure6.stream.qqmusic.qq.com/M5000044Xbfh12Neqy.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=09992B19F2CAF595BBFC36DF1BD3BD236D42B3F47ECDCF0257654FBBA11EF55462952DAB0AD84319D92F1CBE4954EF8BAEE4EB8BA3F8E095&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:47 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:47 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:47 volumio volumio[6552]: info: ------------------------------ 45ms
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:47 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:47 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:47 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:47 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:47 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:53 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:53 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:53 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:53 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:53 volumio volumio[6552]: info:
Apr 29 05:19:53 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:53 volumio volumio[6552]: info: sendMpdCommand stop took 84 milliseconds
Apr 29 05:19:53 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:53 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:53 volumio volumio[6552]: info:
Apr 29 05:19:53 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:53 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:53 volumio volumio[6552]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:19:53 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:53 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:53 volumio volumio[6552]: 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 29 05:19:53 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:53 volumio volumio[6552]: info: No code
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:53 volumio volumio[6552]: info: ------------------------------ 25ms
Apr 29 05:19:53 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:53 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:53 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:53 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:53 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:53 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:53 volumio volumio[6552]: info: ------------------------------ 24ms
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:54 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:54 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:54 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:54 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:54 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:54 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:54 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:54 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:54 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:54 volumio volumio[6552]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:19:54 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:54 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:54 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:54 volumio volumio[6552]: info:
Apr 29 05:19:54 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:54 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:54 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:54 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:19:54 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:54 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:54 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:54 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Por Una Cabeza","artist":"The Tango Project","album":"闻香识女人","uri":"http://isure6.stream.qqmusic.qq.com/M500000zcys91o326K.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=5F2B0275FFCE3391BA56604FAC994002C0397B7AC17A4D51552D03FD8F6EAA053154C404F7FE64107BAAFE3852CCC03AD6A590549A547D99&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:54 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 34ms
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 19ms
Apr 29 05:19:54 volumio volumio[6552]: info: sendMpdCommand status took 17 milliseconds
Apr 29 05:19:54 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:54 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:54 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:54 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 8 milliseconds
Apr 29 05:19:54 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:54 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:54 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:54 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Por Una Cabeza","artist":"The Tango Project","album":"闻香识女人","uri":"http://isure6.stream.qqmusic.qq.com/M500000zcys91o326K.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=5F2B0275FFCE3391BA56604FAC994002C0397B7AC17A4D51552D03FD8F6EAA053154C404F7FE64107BAAFE3852CCC03AD6A590549A547D99&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:54 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:54 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:54 volumio volumio[6552]: info: ------------------------------ 50ms
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:54 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:54 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:54 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:54 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:58 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:19:58 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:58 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:19:58 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:19:58 volumio volumio[6552]: info:
Apr 29 05:19:58 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:58 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:58 volumio volumio[6552]: info:
Apr 29 05:19:58 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:58 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:58 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:58 volumio volumio[6552]: info: sendMpdCommand stop took 53 milliseconds
Apr 29 05:19:58 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:19:58 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:19:58 volumio volumio[6552]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:19:58 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:58 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:58 volumio volumio[6552]: 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 29 05:19:58 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:58 volumio volumio[6552]: info: No code
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:58 volumio volumio[6552]: info: ------------------------------ 20ms
Apr 29 05:19:58 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:19:58 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:19:58 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:19:58 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:19:58 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:19:58 volumio volumio[6552]: info:
Apr 29 05:19:58 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:58 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:58 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:58 volumio volumio[6552]: info:
Apr 29 05:19:58 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:58 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:58 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:58 volumio volumio[6552]: info:
Apr 29 05:19:58 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:58 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:58 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:58 volumio volumio[6552]: info:
Apr 29 05:19:58 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:58 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:58 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:19:58 volumio volumio[6552]: info:
Apr 29 05:19:58 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:58 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:58 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:58 volumio volumio[6552]: info:
Apr 29 05:19:58 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:58 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:59 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:19:59 volumio volumio[6552]: info:
Apr 29 05:19:59 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:59 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:59 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:59 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:19:59 volumio volumio[6552]: info:
Apr 29 05:19:59 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:19:59 volumio volumio[6552]: info: sendMpdCommand status took 17 milliseconds
Apr 29 05:19:59 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:19:59 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:19:59 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:59 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:59 volumio volumio[6552]: info:
Apr 29 05:19:59 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:19:59 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:19:59 volumio volumio[6552]: info: sendMpdCommand status took 5 milliseconds
Apr 29 05:19:59 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:19:59 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:19:59 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:19:59 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:59 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:59 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:59 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Salut d'amour","artist":"David Garrett","album":"Classic Romance","uri":"http://isure6.stream.qqmusic.qq.com/M500002b7Mb04Lb8WL.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=59B3F0829F760BA9D4CD2B92554737D2355E92E015779C838A9FBE5A63F2D0F114B2CF6FFC47730D42D812F5D164967DEC178123004CDE92&uin=1152921504689611133&src=M500004e2om60zs5hz.mp3&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:59 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:59 volumio volumio[6552]: info: ------------------------------ 38ms
Apr 29 05:19:59 volumio volumio[6552]: info: ------------------------------ 16ms
Apr 29 05:19:59 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 29 05:19:59 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:19:59 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:19:59 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:19:59 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Salut d'amour","artist":"David Garrett","album":"Classic Romance","uri":"http://isure6.stream.qqmusic.qq.com/M500002b7Mb04Lb8WL.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=59B3F0829F760BA9D4CD2B92554737D2355E92E015779C838A9FBE5A63F2D0F114B2CF6FFC47730D42D812F5D164967DEC178123004CDE92&uin=1152921504689611133&src=M500004e2om60zs5hz.mp3&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:19:59 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:19:59 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:19:59 volumio volumio[6552]: info: ------------------------------ 41ms
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:59 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:59 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:19:59 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:19:59 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:19:59 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:04 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:20:04 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:20:04 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:20:04 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:20:04 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:20:04 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:20:04 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:20:04 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:20:04 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:04 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:04 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:04 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:04 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:20:04 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:04 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:20:04 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:20:04 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:20:04 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:20:04 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:04 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:04 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:05 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:05 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:05 volumio volumio[6552]: info: sendMpdCommand stop took 84 milliseconds
Apr 29 05:20:05 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:20:05 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:20:05 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:20:05 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:20:05 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:05 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:05 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:05 volumio volumio[6552]: 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 29 05:20:05 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:05 volumio volumio[6552]: info: No code
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 26ms
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:05 volumio volumio[6552]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/David%20Garrett/Classic%20Romance/171670d2-9be2-4b81-b416-6e0f87ff7e5d.jpg'
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:05 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:05 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:05 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:05 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:05 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:05 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:05 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:05 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:20:05 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:05 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:05 volumio volumio[6552]: info:
Apr 29 05:20:05 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:05 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:05 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:05 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:05 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:05 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":294,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Romance in F Major","artist":"André Rieu","album":"Triple Best Of André Rieu","uri":"http://isure6.stream.qqmusic.qq.com/M500004SrT9W2rXfBC.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=4993FEA9F7E82C5AB8A83DBAFD9E277E8C4571F78DCD894D619EA840D1CB4FA83CB72EE02EF6B1ACFBB8B8627FEE70FC48CDB9CE0CD747FB&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:05 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 28ms
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 14ms
Apr 29 05:20:05 volumio volumio[6552]: info: sendMpdCommand status took 15 milliseconds
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 5 milliseconds
Apr 29 05:20:05 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:05 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:05 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:05 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":294,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Romance in F Major","artist":"André Rieu","album":"Triple Best Of André Rieu","uri":"http://isure6.stream.qqmusic.qq.com/M500004SrT9W2rXfBC.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=4993FEA9F7E82C5AB8A83DBAFD9E277E8C4571F78DCD894D619EA840D1CB4FA83CB72EE02EF6B1ACFBB8B8627FEE70FC48CDB9CE0CD747FB&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:05 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:20:05 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:05 volumio volumio[6552]: info: ------------------------------ 44ms
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:05 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:08 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:20:09 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:09 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:20:09 volumio volumio[6552]: info: sendMpdCommand stop took 29 milliseconds
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:09 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:09 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:09 volumio volumio[6552]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:09 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:09 volumio volumio[6552]: 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 29 05:20:09 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:09 volumio volumio[6552]: info: No code
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 20ms
Apr 29 05:20:09 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:20:09 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:20:09 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 22ms
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:09 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:09 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:09 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:09 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:09 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:09 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:09 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:09 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:20:09 volumio volumio[6552]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:09 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:09 volumio volumio[6552]: info:
Apr 29 05:20:09 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:09 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:09 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:09 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:09 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:09 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liebesfreud","artist":"Joshua Bell","album":"Essential Violin","uri":"http://isure6.stream.qqmusic.qq.com/M500002KFtLy1Redug.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=A9B4D6BB419F166E83D582D26EF6845ABC983E466D377F6B0012DF1C14DBD10660565389C6FE8FE2C2693688416E78FE3172B15D18D34B02&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:09 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 31ms
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 16ms
Apr 29 05:20:09 volumio volumio[6552]: info: sendMpdCommand status took 15 milliseconds
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:09 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 7 milliseconds
Apr 29 05:20:09 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:09 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:09 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:09 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Liebesfreud","artist":"Joshua Bell","album":"Essential Violin","uri":"http://isure6.stream.qqmusic.qq.com/M500002KFtLy1Redug.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=A9B4D6BB419F166E83D582D26EF6845ABC983E466D377F6B0012DF1C14DBD10660565389C6FE8FE2C2693688416E78FE3172B15D18D34B02&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:09 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:20:09 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:09 volumio volumio[6552]: info: ------------------------------ 42ms
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:09 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:13 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:20:13 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:13 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:20:13 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:20:13 volumio volumio[6552]: info:
Apr 29 05:20:13 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:13 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:13 volumio volumio[6552]: info:
Apr 29 05:20:13 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:13 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:13 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:13 volumio volumio[6552]: info: sendMpdCommand stop took 23 milliseconds
Apr 29 05:20:13 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:20:13 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:20:13 volumio volumio[6552]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:20:13 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:13 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:13 volumio volumio[6552]: 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 29 05:20:13 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:13 volumio volumio[6552]: info: No code
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:13 volumio volumio[6552]: info: ------------------------------ 21ms
Apr 29 05:20:13 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:20:13 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:20:13 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:20:13 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:13 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:14 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:14 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:14 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:14 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:14 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:14 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:14 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:14 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:14 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:14 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:20:14 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:14 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:14 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:14 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:14 volumio volumio[6552]: info:
Apr 29 05:20:14 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:14 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:14 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:20:14 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:14 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:14 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:14 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liebesleid","artist":"Fritz Kreisler","album":"Violin Masterworks","uri":"http://isure6.stream.qqmusic.qq.com/M5000008rY1X08Cp2l.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=41E7F174134D50CF79AD49C9BC8FF765FBD9B3C5B367E34B9EF7F6E077FD48A4037C88A71F23FAD12C6E015E5F0A59D70E9B027B333134E0&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:14 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 30ms
Apr 29 05:20:14 volumio volumio[6552]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 13ms
Apr 29 05:20:14 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:14 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:14 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:14 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 7 milliseconds
Apr 29 05:20:14 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:14 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:14 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:14 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Liebesleid","artist":"Fritz Kreisler","album":"Violin Masterworks","uri":"http://isure6.stream.qqmusic.qq.com/M5000008rY1X08Cp2l.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=41E7F174134D50CF79AD49C9BC8FF765FBD9B3C5B367E34B9EF7F6E077FD48A4037C88A71F23FAD12C6E015E5F0A59D70E9B027B333134E0&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:14 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:20:14 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:14 volumio volumio[6552]: info: ------------------------------ 39ms
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:14 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:14 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:14 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:14 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:17 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:20:17 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:17 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:20:17 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:20:17 volumio volumio[6552]: info:
Apr 29 05:20:17 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:17 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:17 volumio volumio[6552]: info:
Apr 29 05:20:17 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:17 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:17 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:17 volumio volumio[6552]: info: sendMpdCommand stop took 19 milliseconds
Apr 29 05:20:17 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:20:17 volumio volumio[6552]: info: ------------------------------ 10ms
Apr 29 05:20:17 volumio volumio[6552]: info: sendMpdCommand status took 9 milliseconds
Apr 29 05:20:17 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:17 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:17 volumio volumio[6552]: 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 29 05:20:17 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:17 volumio volumio[6552]: info: No code
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:17 volumio volumio[6552]: info: ------------------------------ 26ms
Apr 29 05:20:17 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:20:17 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:20:17 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:20:17 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:17 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:20:18 volumio volumio[6552]: info:
Apr 29 05:20:18 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:18 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:18 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:18 volumio volumio[6552]: info:
Apr 29 05:20:18 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:18 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:18 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:18 volumio volumio[6552]: info:
Apr 29 05:20:18 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:18 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:18 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:18 volumio volumio[6552]: info:
Apr 29 05:20:18 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:18 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:18 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:18 volumio volumio[6552]: info:
Apr 29 05:20:18 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:18 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:18 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:18 volumio volumio[6552]: info:
Apr 29 05:20:18 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:18 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:18 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:19 volumio volumio[6552]: info:
Apr 29 05:20:19 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:19 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:19 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:19 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:20:19 volumio volumio[6552]: info:
Apr 29 05:20:19 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:19 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:19 volumio volumio[6552]: info:
Apr 29 05:20:19 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:19 volumio volumio[6552]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:20:19 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:19 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:19 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:19 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:19 volumio volumio[6552]: info: ------------------------------ 6ms
Apr 29 05:20:19 volumio volumio[6552]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:20:19 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:20:19 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:19 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:19 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:19 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:19 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:19 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":396,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Violin Concerto Noin G minor, Op. 26: Finale. Allegro energico - Presto","artist":"Max Bruch","album":"布鲁赫第一小提琴协奏曲等维厄当第五小提琴协奏曲","uri":"http://isure6.stream.qqmusic.qq.com/M500003q8glh1f3jKx.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=1FC09015FDE34A0E412DEDFBEAF4BDB5CB2D5BB619982D3E3A8F59B26CF5160E44DC758430315C470E27F1DA4DDD338EB853F4F51899AEA6&uin=1152921504689611133&src=M500003q8glh1f3jKx.mp3&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:19 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:19 volumio volumio[6552]: info: ------------------------------ 31ms
Apr 29 05:20:19 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 29 05:20:19 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:19 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:19 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:19 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":396,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Violin Concerto Noin G minor, Op. 26: Finale. Allegro energico - Presto","artist":"Max Bruch","album":"布鲁赫第一小提琴协奏曲等维厄当第五小提琴协奏曲","uri":"http://isure6.stream.qqmusic.qq.com/M500003q8glh1f3jKx.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=1FC09015FDE34A0E412DEDFBEAF4BDB5CB2D5BB619982D3E3A8F59B26CF5160E44DC758430315C470E27F1DA4DDD338EB853F4F51899AEA6&uin=1152921504689611133&src=M500003q8glh1f3jKx.mp3&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:19 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:20:19 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:19 volumio volumio[6552]: info: ------------------------------ 36ms
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:19 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:19 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:19 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:19 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:19 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:22 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:20:22 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:22 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:20:22 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:20:22 volumio volumio[6552]: info:
Apr 29 05:20:22 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:22 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:22 volumio volumio[6552]: info:
Apr 29 05:20:22 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:22 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:22 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:22 volumio volumio[6552]: info: sendMpdCommand stop took 65 milliseconds
Apr 29 05:20:22 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:20:22 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:20:22 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:20:22 volumio volumio[6552]: info: ------------------------------ 5ms
Apr 29 05:20:22 volumio volumio[6552]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:20:22 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:22 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:22 volumio volumio[6552]: 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 29 05:20:22 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:22 volumio volumio[6552]: info: No code
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:22 volumio volumio[6552]: info: ------------------------------ 26ms
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:22 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:23 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:23 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:23 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:20:23 volumio volumio[6552]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:20:23 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:23 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:23 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:23 volumio volumio[6552]: info:
Apr 29 05:20:23 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:23 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:23 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:23 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 29 05:20:23 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:23 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:23 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:23 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HumorseQue","artist":"Classical Artists","album":"The Most Romantic Violin Famous Music","uri":"http://isure6.stream.qqmusic.qq.com/M500000bxs7p1CX4Kd.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=F220059A6F08C587CA46131253F911A66E795C15E3FE60B958A297D96A7D17108CB18C2A336084115B7E042A7383C821DE504C76F4CE07F6&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:23 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 31ms
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 17ms
Apr 29 05:20:23 volumio volumio[6552]: info: sendMpdCommand status took 17 milliseconds
Apr 29 05:20:23 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:23 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:23 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:20:23 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:23 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:23 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:23 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"HumorseQue","artist":"Classical Artists","album":"The Most Romantic Violin Famous Music","uri":"http://isure6.stream.qqmusic.qq.com/M500000bxs7p1CX4Kd.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=F220059A6F08C587CA46131253F911A66E795C15E3FE60B958A297D96A7D17108CB18C2A336084115B7E042A7383C821DE504C76F4CE07F6&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:23 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:20:23 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:23 volumio volumio[6552]: info: ------------------------------ 35ms
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:23 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:23 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:23 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:23 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:23 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:25 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:20:25 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:20:25 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:25 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:20:25 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:25 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:20:25 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:20:25 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:20:25 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:20:25 volumio volumio[6552]: info:
Apr 29 05:20:25 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:25 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:25 volumio volumio[6552]: info:
Apr 29 05:20:25 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:25 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:25 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:25 volumio volumio[6552]: info: sendMpdCommand stop took 98 milliseconds
Apr 29 05:20:25 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:20:25 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:20:25 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:20:25 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:20:25 volumio volumio[6552]: info: ------------------------------ 21ms
Apr 29 05:20:25 volumio volumio[6552]: info: sendMpdCommand status took 19 milliseconds
Apr 29 05:20:25 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:25 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:25 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:25 volumio volumio[6552]: 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 29 05:20:25 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:25 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:25 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:26 volumio volumio[6552]: info: No code
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 66ms
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 5ms
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 4ms
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 11ms
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 7ms
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 5ms
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:26 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:20:26 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:26 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:26 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:26 volumio volumio[6552]: info:
Apr 29 05:20:26 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:26 volumio volumio[6552]: info: sendMpdCommand status took 17 milliseconds
Apr 29 05:20:26 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:26 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:26 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:26 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 7ms
Apr 29 05:20:26 volumio volumio[6552]: info: sendMpdCommand status took 7 milliseconds
Apr 29 05:20:26 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 7 milliseconds
Apr 29 05:20:26 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:26 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:26 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:26 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:26 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:26 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Thaïs: Meditation","artist":"The City of Prague Philharmonic Orchestra","album":"100 Greatest Classical Pieces","uri":"http://isure6.stream.qqmusic.qq.com/M500004GNBGj0m3LAa.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=2C3D48CACC49438370F2D844F789764777490F5D73E983ED2E624A3ABA2C7CE403FC8B2EA51C07B49BCED727730611A21CAB1B628C757968&uin=1152921504689611133&src=M500002vykbu07xGVm.mp3&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:26 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 50ms
Apr 29 05:20:26 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 23 milliseconds
Apr 29 05:20:26 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:26 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:26 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:26 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":317,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Thaïs: Meditation","artist":"The City of Prague Philharmonic Orchestra","album":"100 Greatest Classical Pieces","uri":"http://isure6.stream.qqmusic.qq.com/M500004GNBGj0m3LAa.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=2C3D48CACC49438370F2D844F789764777490F5D73E983ED2E624A3ABA2C7CE403FC8B2EA51C07B49BCED727730611A21CAB1B628C757968&uin=1152921504689611133&src=M500002vykbu07xGVm.mp3&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:26 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:20:26 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:26 volumio volumio[6552]: info: ------------------------------ 63ms
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:26 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:30 volumio volumio[6552]: info: Clearing queue after UPNP request
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::ClearQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::stop
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:20:31 volumio volumio[6552]: verbose: UNSET VOLATILE
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getTrackBlock
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::serviceStop
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:31 volumio volumio[6552]: info: ControllerMpd::stop
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::saveQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:31 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:31 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:31 volumio volumio[6552]: info: sendMpdCommand stop took 89 milliseconds
Apr 29 05:20:31 volumio volumio[6552]: info: Starting UPNP Playback
Apr 29 05:20:31 volumio volumio[6552]: info: Preparing playback through UPNP
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioGetState
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:20:31 volumio volumio[6552]: error: updateQueue error: null
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 9ms
Apr 29 05:20:31 volumio volumio[6552]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:31 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getTrack 0
Apr 29 05:20:31 volumio volumio[6552]: 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 29 05:20:31 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:31 volumio volumio[6552]: info: No code
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 23ms
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:31 volumio volumio[6552]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20City%20of%20Prague%20Philharmonic%20Orchestra/100%20Greatest%20Classical%20Pieces/a5146ac8-ae35-4dab-8cdf-303db8abbd22.jpg'
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:31 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:31 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:31 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:31 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 3ms
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:31 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 2ms
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:31 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 6ms
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:31 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:31 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces state update: player
Apr 29 05:20:31 volumio volumio[6552]: info: sendMpdCommand status took 11 milliseconds
Apr 29 05:20:31 volumio volumio[6552]: info: ControllerMpd::getState
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:31 volumio volumio[6552]: info:
Apr 29 05:20:31 volumio volumio[6552]: ---------------------------- MPD announces system playlist update
Apr 29 05:20:31 volumio volumio[6552]: info: Ignoring MPD Status Update
Apr 29 05:20:31 volumio volumio[6552]: info: sendMpdCommand status took 8 milliseconds
Apr 29 05:20:31 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 7 milliseconds
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::parseState
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:31 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:31 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:31 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Air in G","artist":"Classical Artists","album":"The Most Romantic Violin Famous Music","uri":"http://isure6.stream.qqmusic.qq.com/M500000uDhIi3X2D57.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=A46B631855530A62A63DC6784969A018F2A8DBC8FEECAD0A15CC10290CCA2D6169F0C2CA1F6463463A130783B0E8F504DED98D2E49D3C601&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:31 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 52ms
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 31ms
Apr 29 05:20:31 volumio volumio[6552]: info: sendMpdCommand playlistinfo took 22 milliseconds
Apr 29 05:20:31 volumio volumio[6552]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:20:31 volumio volumio[6552]: info: ControllerMpd::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::servicePushState
Apr 29 05:20:31 volumio volumio[6552]: verbose: In UPNP mode
Apr 29 05:20:31 volumio volumio[6552]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":298,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Air in G","artist":"Classical Artists","album":"The Most Romantic Violin Famous Music","uri":"http://isure6.stream.qqmusic.qq.com/M500000uDhIi3X2D57.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=A46B631855530A62A63DC6784969A018F2A8DBC8FEECAD0A15CC10290CCA2D6169F0C2CA1F6463463A130783B0E8F504DED98D2E49D3C601&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:20:31 volumio volumio[6552]: verbose: CURRENT POSITION 0
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::syncState stateService play
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:20:31 volumio volumio[6552]: info: Received an update from plugin. extracting info from payload
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::pushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioPushState
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:20:31 volumio volumio[6552]: info: ------------------------------ 62ms
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CoreStateMachine::getQueue
Apr 29 05:20:31 volumio volumio[6552]: info: CorePlayQueue::getQueue
Apr 29 05:20:32 volumio volumio[6552]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 29 05:20:32 volumio volumio[6552]: { Error: connect ETIMEDOUT 31.13.95.37:443
Apr 29 05:20:32 volumio volumio[6552]: at Object._errnoException (util.js:1022:11)
Apr 29 05:20:32 volumio volumio[6552]: at _exceptionWithHostPort (util.js:1044:20)
Apr 29 05:20:32 volumio volumio[6552]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14)
Apr 29 05:20:32 volumio volumio[6552]: code: 'ETIMEDOUT',
Apr 29 05:20:32 volumio volumio[6552]: errno: 'ETIMEDOUT',
Apr 29 05:20:32 volumio volumio[6552]: syscall: 'connect',
Apr 29 05:20:32 volumio volumio[6552]: address: '31.13.95.37',
Apr 29 05:20:32 volumio volumio[6552]: port: 443 }
Apr 29 05:20:32 volumio volumio[6552]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 29 05:20:33 volumio sudo[7354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-29 05:19
Apr 29 05:20:33 volumio sudo[7354]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)"
NAME="Raspbian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03"
VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae"
VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021"
VOLUMIO_VERSION="2.917"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"