-- Logs begin at Sun 2024-04-28 02:34:15 UTC, end at Mon 2024-04-29 05:18:39 UTC. --
Apr 29 05:17:03 volumio volumio[5760]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::Close All Modals sent
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::Close All Modals sent
Apr 29 05:17:03 volumio volumio[5760]: Express server listening on port 3000
Apr 29 05:17:03 volumio volumio[5760]: [Metrics] WebUI: 10s 369.56ms
Apr 29 05:17:03 volumio volumio[5760]: Volumio Calling Home
Apr 29 05:17:03 volumio volumio[5760]: info: CoreStateMachine::resetVolumioState
Apr 29 05:17:03 volumio volumio[5760]: info: CoreStateMachine::getcurrentVolume
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::volumioRetrievevolume
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 29 05:17:03 volumio volumio[5760]: info: Setting Device type: Raspberry PI
Apr 29 05:17:03 volumio volumio[5760]: info: Cannot mount NAS TDDOWNLOAD at system boot, trial number 1 ,retrying in 5 seconds
Apr 29 05:17:03 volumio volumio[5760]: info: MPD running with PID2373
Apr 29 05:17:03 volumio volumio[5760]: ,establishing connection
Apr 29 05:17:03 volumio volumio[5760]: info: VolumeController:: Volume=81 Mute =false
Apr 29 05:17:03 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:03 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:03 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:03 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::volumioRetrievevolume
Apr 29 05:17:03 volumio volumio[5760]: info: Reloading queue from file
Apr 29 05:17:03 volumio volumio[5760]: info: CoreStateMachine::setRepeat null single undefined
Apr 29 05:17:03 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:03 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:03 volumio volumio[5760]: info: CoreStateMachine::setRandom null
Apr 29 05:17:03 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:03 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:03 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 29 05:17:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 29 05:17:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 29 05:17:04 volumio volumio[5760]: info: Volumio called home
Apr 29 05:17:04 volumio volumio[5760]: info: Starting Shairport Sync
Apr 29 05:17:04 volumio volumio[5760]: info: Starting Shairport Sync
Apr 29 05:17:04 volumio sudo[5891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 29 05:17:04 volumio sudo[5891]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:04 volumio sudo[5894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 29 05:17:04 volumio sudo[5894]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 29 05:17:04 volumio volumio[5760]: info: VolumeController:: Volume=81 Mute =false
Apr 29 05:17:04 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:04 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:04 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver...
Apr 29 05:17:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 29 05:17:04 volumio sudo[5891]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:04 volumio sudo[5894]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:04 volumio volumio[5760]: Discovery: adding 82f534ed-d274-4b70-bd12-7733b3956d07
Apr 29 05:17:04 volumio volumio[5760]: info: mDNS: Found device Volumio
Apr 29 05:17:04 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:04 volumio volumio[5760]: info: Shairport-Sync Started
Apr 29 05:17:04 volumio volumio[5760]: Error adding Membership: Error: addMembership EINVAL
Apr 29 05:17:04 volumio volumio[5760]: info: Shairport-Sync Started
Apr 29 05:17:04 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:05 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:05 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:05 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:05 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:05 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:05 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:05 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:05 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:05 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:05 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:05 volumio volumio[5760]: info:
Apr 29 05:17:05 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:05 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:05 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:05 volumio volumio[5760]: info: ------------------------------ 19ms
Apr 29 05:17:05 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:05 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:05 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:05 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:05 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:06 volumio sudo[5926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 29 05:17:06 volumio sudo[5926]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:06 volumio sudo[5926]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:06 volumio sudo[5929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 29 05:17:06 volumio sudo[5929]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:06 volumio sudo[5929]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:06 volumio sudo[5940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 29 05:17:06 volumio sudo[5940]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:06 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 6ms
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:06 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 6ms
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:06 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 7ms
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:06 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 6ms
Apr 29 05:17:06 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Apr 29 05:17:06 volumio sudo[5940]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:06 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 7ms
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:06 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 7ms
Apr 29 05:17:06 volumio volumio[5760]: info: Upmpdcli Daemon Started
Apr 29 05:17:06 volumio volumio[2918]: 100
Apr 29 05:17:06 volumio volumio[2918]: % Total % Received % Xferd Average Speed Time Time Time Current
Apr 29 05:17:06 volumio volumio[2918]: Dload Upload Total Spent Left Speed
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- Client requests Volume 100
Apr 29 05:17:06 volumio volumio[5760]: info: VolumeController::SetAlsaVolume100
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:06 volumio volumio[2918]: [155B blob data]
Apr 29 05:17:06 volumio volumio[2918]: {"time":1714367826417,"response":"volume Success"}100
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces state update: mixer
Apr 29 05:17:06 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:06 volumio volumio[5760]: info: sendMpdCommand status took 8 milliseconds
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:06 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:06 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:06 volumio volumio[5760]: 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:17:06 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:06 volumio volumio[5760]: info: No code
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 62ms
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:06 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:06 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:06 volumio volumio[5760]: info: sendMpdCommand status took 18 milliseconds
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:06 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:06 volumio volumio[5760]: info:
Apr 29 05:17:06 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:06 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:06 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 14 milliseconds
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:06 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:06 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:06 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lucia Di Lammermoor","artist":"Eric Serra","album":"The Fifth Element: Original Motion Picture Soundtrack","uri":"http://isure6.stream.qqmusic.qq.com/M500000IJlH70ZBNwn.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=3CED6AAD464B72F7FB5373F11893E27086811BECDC0BAF61BBC9D3A45607369FE2E8AE92A8416EC069DED8D1B81F45BCAC993FE32C6CB6E0&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:06 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 72ms
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 49ms
Apr 29 05:17:06 volumio volumio[5760]: info: sendMpdCommand status took 40 milliseconds
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:06 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 29 05:17:06 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:06 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:06 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:06 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lucia Di Lammermoor","artist":"Eric Serra","album":"The Fifth Element: Original Motion Picture Soundtrack","uri":"http://isure6.stream.qqmusic.qq.com/M500000IJlH70ZBNwn.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=3CED6AAD464B72F7FB5373F11893E27086811BECDC0BAF61BBC9D3A45607369FE2E8AE92A8416EC069DED8D1B81F45BCAC993FE32C6CB6E0&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:06 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:06 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:06 volumio volumio[5760]: info: ------------------------------ 106ms
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:06 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:06 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:06 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:06 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:08 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:08 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:08 volumio sudo[5978]: 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:17:08 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:08 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:08 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:08 volumio sudo[5978]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:08 volumio volumio[5760]: info:
Apr 29 05:17:08 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:08 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:08 volumio volumio[5760]: info:
Apr 29 05:17:08 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:08 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:08 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:08 volumio volumio[5760]: info: sendMpdCommand stop took 27 milliseconds
Apr 29 05:17:08 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:08 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:08 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:08 volumio volumio[5760]: info: ------------------------------ 10ms
Apr 29 05:17:08 volumio volumio[5760]: info: sendMpdCommand status took 7 milliseconds
Apr 29 05:17:08 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:08 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:08 volumio volumio[5760]: 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:17:08 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:08 volumio volumio[5760]: info: No code
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:08 volumio volumio[5760]: info: ------------------------------ 26ms
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:08 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:08 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:17:08 volumio sudo[5978]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:08 volumio kernel: Status code returned 0xc0000022 STATUS_ACCESS_DENIED
Apr 29 05:17:08 volumio kernel: CIFS VFS: Send error in SessSetup = -13
Apr 29 05:17:08 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -13
Apr 29 05:17:08 volumio volumio[5760]: info: Cannot mount NAS TDDOWNLOAD at system boot, trial number 2 ,retrying in 5 seconds
Apr 29 05:17:08 volumio volumio-remote-updater[607]: [2024-04-29 05:17:08] [connect] Successful connection
Apr 29 05:17:08 volumio volumio-remote-updater[607]: [2024-04-29 05:17:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1714367828 101
Apr 29 05:17:08 volumio volumio[5760]: info:
Apr 29 05:17:08 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:08 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:08 volumio volumio[5760]: info:
Apr 29 05:17:08 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:08 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:08 volumio volumio[5760]: info: ------------------------------ 7ms
Apr 29 05:17:08 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:17:08 volumio volumio[5760]: info:
Apr 29 05:17:08 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:08 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:08 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:08 volumio volumio[5760]: info:
Apr 29 05:17:08 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:08 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:08 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:08 volumio volumio[5760]: info:
Apr 29 05:17:08 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:08 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:08 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:08 volumio volumio[5760]: info:
Apr 29 05:17:08 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:08 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:08 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:09 volumio volumio[5760]: info:
Apr 29 05:17:09 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:09 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:09 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:09 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:09 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:17:09 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:09 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:09 volumio volumio[5760]: info:
Apr 29 05:17:09 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:09 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:09 volumio volumio[5760]: info:
Apr 29 05:17:09 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:09 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:09 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:09 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:17:09 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:09 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:09 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:09 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don't Cry","artist":"Guns N' Roses","album":"重金属摇滚精选 CD3","uri":"http://isure6.stream.qqmusic.qq.com/M500000AGPH53OdZiY.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=262306B915555BF7ED5D5EC04038B5922D183099FB9C878A77258E43D612C20773927AB7B67A92C5C4260A7CCA73E288B3E2E81C56A6B5D4&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:09 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:09 volumio volumio[5760]: info: ------------------------------ 32ms
Apr 29 05:17:09 volumio volumio[5760]: info: ------------------------------ 16ms
Apr 29 05:17:09 volumio volumio[5760]: info: sendMpdCommand status took 15 milliseconds
Apr 29 05:17:09 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:09 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:09 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:09 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 7 milliseconds
Apr 29 05:17:09 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:09 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:09 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:09 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don't Cry","artist":"Guns N' Roses","album":"重金属摇滚精选 CD3","uri":"http://isure6.stream.qqmusic.qq.com/M500000AGPH53OdZiY.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=262306B915555BF7ED5D5EC04038B5922D183099FB9C878A77258E43D612C20773927AB7B67A92C5C4260A7CCA73E288B3E2E81C56A6B5D4&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:09 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:09 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:09 volumio volumio[5760]: info: ------------------------------ 44ms
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:09 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:09 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:09 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 29 05:17:09 volumio volumio[5760]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 29 05:17:09 volumio volumio[5760]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 29 05:17:09 volumio volumio[5760]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 29 05:17:09 volumio volumio[5760]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 29 05:17:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 29 05:17:09 volumio volumio[5760]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 29 05:17:09 volumio volumio[5760]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 29 05:17:09 volumio volumio[5760]: info: Streaming services startup
Apr 29 05:17:09 volumio volumio[5760]: info: Starting Streaming Daemon
Apr 29 05:17:10 volumio sudo[5998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 29 05:17:10 volumio sudo[5998]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:10 volumio systemd[1]: Stopping Volumio Streaming Daemon...
Apr 29 05:17:10 volumio volumio[5760]: info: MyVolumio not started
Apr 29 05:17:10 volumio volumio[5760]: info: Initializing device activation check
Apr 29 05:17:10 volumio volumio[5760]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 29 05:17:10 volumio systemd[1]: Starting Volumio Streaming Daemon...
Apr 29 05:17:10 volumio systemd[1]: Started Volumio Streaming Daemon.
Apr 29 05:17:10 volumio sudo[5998]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:10 volumio volumio-streaming-daemon[6008]: ############################
Apr 29 05:17:10 volumio volumio-streaming-daemon[6008]: # Volumio Streaming Daemon #
Apr 29 05:17:10 volumio volumio-streaming-daemon[6008]: # Running on port 7777 #
Apr 29 05:17:10 volumio volumio-streaming-daemon[6008]: ############################
Apr 29 05:17:10 volumio volumio-streaming-daemon[6008]: Environment: production
Apr 29 05:17:10 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:10 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:10 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:10 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:10 volumio volumio[5760]: info:
Apr 29 05:17:10 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:10 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:10 volumio volumio[5760]: info:
Apr 29 05:17:10 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:10 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:10 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:10 volumio volumio[5760]: info: sendMpdCommand stop took 17 milliseconds
Apr 29 05:17:10 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:10 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:10 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:10 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:17:10 volumio volumio[5760]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:17:10 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:10 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:10 volumio volumio[5760]: 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:17:10 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:10 volumio volumio[5760]: info: No code
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:10 volumio volumio[5760]: info: ------------------------------ 21ms
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:10 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:11 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:11 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:11 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:11 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:11 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:11 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:11 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:11 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:11 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:11 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:17:11 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:11 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:11 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:11 volumio volumio[5760]: info:
Apr 29 05:17:11 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:11 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:11 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:11 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 29 05:17:11 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:11 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:11 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:11 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Make You Feel My Love","artist":"Adele","album":"Live At The Royal Albert Hall","uri":"http://isure6.stream.qqmusic.qq.com/M500004ALN8x0WHS30.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=BE922C9F8D477B77D202B7BF489A6F0E1C8BF3610DBCEE13D76A6A33914A2A7212FC4272A6F10C51DDE0DE72E29ECCE92F909CB049409D02&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:11 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 28ms
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 11ms
Apr 29 05:17:11 volumio volumio[5760]: info: sendMpdCommand status took 11 milliseconds
Apr 29 05:17:11 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:11 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:11 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:11 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 8 milliseconds
Apr 29 05:17:11 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:11 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:11 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:11 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Make You Feel My Love","artist":"Adele","album":"Live At The Royal Albert Hall","uri":"http://isure6.stream.qqmusic.qq.com/M500004ALN8x0WHS30.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=BE922C9F8D477B77D202B7BF489A6F0E1C8BF3610DBCEE13D76A6A33914A2A7212FC4272A6F10C51DDE0DE72E29ECCE92F909CB049409D02&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:11 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:11 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:11 volumio volumio[5760]: info: ------------------------------ 39ms
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:11 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:11 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:11 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:11 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:12 volumio volumio-streaming-daemon[6008]: Environment: production
Apr 29 05:17:12 volumio volumio[5760]: info: Fetching Streaming Services browse cache
Apr 29 05:17:13 volumio sudo[6051]: 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:17:13 volumio sudo[6051]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:13 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:17:13 volumio kernel: Status code returned 0xc0000022 STATUS_ACCESS_DENIED
Apr 29 05:17:13 volumio kernel: CIFS VFS: Send error in SessSetup = -13
Apr 29 05:17:13 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -13
Apr 29 05:17:13 volumio sudo[6051]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:13 volumio volumio[5760]: info: Cannot mount NAS TDDOWNLOAD at system boot, trial number 3 ,retrying in 5 seconds
Apr 29 05:17:13 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:14 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:14 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:14 volumio volumio[5760]: info: sendMpdCommand stop took 28 milliseconds
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:14 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:14 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:14 volumio volumio[5760]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:14 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:14 volumio volumio[5760]: 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:17:14 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:14 volumio volumio[5760]: info: No code
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 19ms
Apr 29 05:17:14 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:14 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:14 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 20ms
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:14 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:14 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:14 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:14 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:14 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:14 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:14 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:14 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:14 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:14 volumio volumio[5760]: info:
Apr 29 05:17:14 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:14 volumio volumio[5760]: info: sendMpdCommand status took 15 milliseconds
Apr 29 05:17:14 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 6ms
Apr 29 05:17:14 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:17:14 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:14 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:14 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:14 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Set Fire to the Rain","artist":"Adele","album":"21","uri":"http://isure6.stream.qqmusic.qq.com/M500003s1ugS1kOIeR.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=782DF1EAC3652DC1DC3BBD330520196EBE9825DDB5F6B81C6FF7B941ECFFC233F50B97A2B1128FA8D51259A188924109EF477FE3C4280E99&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:14 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 37ms
Apr 29 05:17:14 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 15 milliseconds
Apr 29 05:17:14 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:14 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:14 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:14 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Set Fire to the Rain","artist":"Adele","album":"21","uri":"http://isure6.stream.qqmusic.qq.com/M500003s1ugS1kOIeR.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=782DF1EAC3652DC1DC3BBD330520196EBE9825DDB5F6B81C6FF7B941ECFFC233F50B97A2B1128FA8D51259A188924109EF477FE3C4280E99&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:14 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:14 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:14 volumio volumio[5760]: info: ------------------------------ 44ms
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:14 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:16 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:16 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:16 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:16 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:16 volumio volumio[5760]: info:
Apr 29 05:17:16 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:16 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:16 volumio volumio[5760]: info:
Apr 29 05:17:16 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:16 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:16 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:16 volumio volumio[5760]: info: sendMpdCommand stop took 85 milliseconds
Apr 29 05:17:16 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:16 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:16 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:16 volumio volumio[5760]: info: ------------------------------ 9ms
Apr 29 05:17:16 volumio volumio[5760]: info: sendMpdCommand status took 7 milliseconds
Apr 29 05:17:16 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:16 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:16 volumio volumio[5760]: 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:17:16 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:16 volumio volumio[5760]: info: No code
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:16 volumio volumio[5760]: info: ------------------------------ 41ms
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:16 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:17 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:17 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:17 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:17 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:17 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:17 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:17 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:17 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:17 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:17 volumio volumio[5760]: info: sendMpdCommand status took 11 milliseconds
Apr 29 05:17:17 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:17 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:17 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:17 volumio volumio[5760]: info:
Apr 29 05:17:17 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:17 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:17 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:17 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 29 05:17:17 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:17 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:17 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:17 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":315,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hello Feat. Adele","artist":"Rick Ross","album":"Renzel Remixes","uri":"http://isure6.stream.qqmusic.qq.com/M5000031dOLg0y1RfR.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=5349BF5F0B53EF7A39D49A8C76613CB809309CE0B31232164E9D47F89C9C688B2EA8D66298B0197550583FE8680BE5C11D63EB66ECAA637C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:17 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 54ms
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 31ms
Apr 29 05:17:17 volumio volumio[5760]: info: sendMpdCommand status took 26 milliseconds
Apr 29 05:17:17 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:17 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:17 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:17 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 29 05:17:17 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:17 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:17 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:17 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":315,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hello Feat. Adele","artist":"Rick Ross","album":"Renzel Remixes","uri":"http://isure6.stream.qqmusic.qq.com/M5000031dOLg0y1RfR.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=5349BF5F0B53EF7A39D49A8C76613CB809309CE0B31232164E9D47F89C9C688B2EA8D66298B0197550583FE8680BE5C11D63EB66ECAA637C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:17 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:17 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:17 volumio volumio[5760]: info: ------------------------------ 75ms
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:17 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:17 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:17 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:17 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:18 volumio sudo[6104]: 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:17:18 volumio sudo[6104]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:19 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:17:19 volumio sudo[6104]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:19 volumio kernel: Status code returned 0xc0000022 STATUS_ACCESS_DENIED
Apr 29 05:17:19 volumio kernel: CIFS VFS: Send error in SessSetup = -13
Apr 29 05:17:19 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -13
Apr 29 05:17:19 volumio volumio[5760]: info: Cannot mount NAS TDDOWNLOAD at system boot, trial number 4 ,retrying in 5 seconds
Apr 29 05:17:19 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:20 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:20 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:20 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:20 volumio volumio[5760]: info:
Apr 29 05:17:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:20 volumio volumio[5760]: info:
Apr 29 05:17:20 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:20 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:20 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:20 volumio volumio[5760]: info: sendMpdCommand stop took 86 milliseconds
Apr 29 05:17:20 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:20 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:20 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:20 volumio volumio[5760]: info: ------------------------------ 6ms
Apr 29 05:17:20 volumio volumio[5760]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:17:20 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:20 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:20 volumio volumio[5760]: 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:17:20 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:20 volumio volumio[5760]: info: No code
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:20 volumio volumio[5760]: info: ------------------------------ 22ms
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:20 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:20 volumio volumio[5760]: info:
Apr 29 05:17:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:20 volumio volumio[5760]: info:
Apr 29 05:17:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:20 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:20 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:20 volumio volumio[5760]: info:
Apr 29 05:17:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:20 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:20 volumio volumio[5760]: info:
Apr 29 05:17:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:20 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:20 volumio volumio[5760]: info:
Apr 29 05:17:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:20 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:20 volumio volumio[5760]: info:
Apr 29 05:17:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:20 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:21 volumio volumio[5760]: info:
Apr 29 05:17:21 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:21 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:21 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:21 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:21 volumio volumio[5760]: info:
Apr 29 05:17:21 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:21 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:21 volumio volumio[5760]: info:
Apr 29 05:17:21 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:21 volumio volumio[5760]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:17:21 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:21 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:21 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:21 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:21 volumio volumio[5760]: info: ------------------------------ 6ms
Apr 29 05:17:21 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:17:21 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 29 05:17:21 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:21 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:21 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:21 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:21 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:21 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":128,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"【流行热歌】We Will Rock You(Movie Mix)-《波西米亚狂想曲》电影插曲-Queen","artist":"南山枫","album":"岁月留声机:经典金曲,怀旧味道","uri":"http://isure6.stream.qqmusic.qq.com/M500003Gb3IB3lPYS2.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=7D5E9647BDF1E7C3D01E2CEC221091F700CB73269BE51945E96F31444FDDA7000AFA42590F571D3C9BB3553509D226159B59A8E91F1866BE&uin=1152921504689611133&redirect=1&fromtag=101982","trackType":""}
Apr 29 05:17:21 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:21 volumio volumio[5760]: info: ------------------------------ 32ms
Apr 29 05:17:21 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 29 05:17:21 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:21 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:21 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:21 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":128,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"【流行热歌】We Will Rock You(Movie Mix)-《波西米亚狂想曲》电影插曲-Queen","artist":"南山枫","album":"岁月留声机:经典金曲,怀旧味道","uri":"http://isure6.stream.qqmusic.qq.com/M500003Gb3IB3lPYS2.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=7D5E9647BDF1E7C3D01E2CEC221091F700CB73269BE51945E96F31444FDDA7000AFA42590F571D3C9BB3553509D226159B59A8E91F1866BE&uin=1152921504689611133&redirect=1&fromtag=101982","trackType":""}
Apr 29 05:17:21 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:21 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:21 volumio volumio[5760]: info: ------------------------------ 44ms
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:21 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:21 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:21 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:21 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:21 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:24 volumio sudo[6140]: 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:17:24 volumio sudo[6140]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 29 05:17:24 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:17:24 volumio sudo[6140]: pam_unix(sudo:session): session closed for user root
Apr 29 05:17:24 volumio kernel: Status code returned 0xc0000022 STATUS_ACCESS_DENIED
Apr 29 05:17:24 volumio kernel: CIFS VFS: Send error in SessSetup = -13
Apr 29 05:17:24 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -13
Apr 29 05:17:24 volumio volumio[5760]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Apr 29 05:17:27 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 29 05:17:27 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 29 05:17:37 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:38 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:38 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:38 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:38 volumio volumio[5760]: info:
Apr 29 05:17:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:38 volumio volumio[5760]: info:
Apr 29 05:17:38 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:38 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:38 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:38 volumio volumio[5760]: info: sendMpdCommand stop took 62 milliseconds
Apr 29 05:17:38 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:38 volumio volumio[5760]: info: ------------------------------ 12ms
Apr 29 05:17:38 volumio volumio[5760]: info: sendMpdCommand status took 8 milliseconds
Apr 29 05:17:38 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:38 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:38 volumio volumio[5760]: 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:17:38 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:38 volumio volumio[5760]: info: No code
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:38 volumio volumio[5760]: info: ------------------------------ 58ms
Apr 29 05:17:38 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:38 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:38 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:38 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:38 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:38 volumio volumio[5760]: info:
Apr 29 05:17:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:38 volumio volumio[5760]: info:
Apr 29 05:17:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:38 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:38 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:38 volumio volumio[5760]: info:
Apr 29 05:17:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:38 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:38 volumio volumio[5760]: info:
Apr 29 05:17:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:38 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:38 volumio volumio[5760]: info:
Apr 29 05:17:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:38 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:38 volumio volumio[5760]: info:
Apr 29 05:17:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:38 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:39 volumio volumio[5760]: info:
Apr 29 05:17:39 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:39 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:39 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:39 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:39 volumio volumio[5760]: info:
Apr 29 05:17:39 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:39 volumio volumio[5760]: info: sendMpdCommand status took 11 milliseconds
Apr 29 05:17:39 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:39 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:39 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:39 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:39 volumio volumio[5760]: info:
Apr 29 05:17:39 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:39 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:39 volumio volumio[5760]: info: sendMpdCommand status took 7 milliseconds
Apr 29 05:17:39 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 8 milliseconds
Apr 29 05:17:39 volumio volumio[5760]: info: ------------------------------ 6ms
Apr 29 05:17:39 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:39 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:39 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:39 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:39 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:39 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"晴朗的一天","artist":"Mirella Freni","album":"Ten Top of Opera Hits","uri":"http://isure6.stream.qqmusic.qq.com/M500003VBR1l2Asx8a.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=1D6CBF908513D643A61B83A72FDE226CE35710155B9EFBE33816300806CBDCC9A941B9081CA8557426AAEC95FF6214A058E3BF0A5112396F&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:39 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:39 volumio volumio[5760]: info: ------------------------------ 41ms
Apr 29 05:17:39 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 18 milliseconds
Apr 29 05:17:39 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:39 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:39 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:39 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"晴朗的一天","artist":"Mirella Freni","album":"Ten Top of Opera Hits","uri":"http://isure6.stream.qqmusic.qq.com/M500003VBR1l2Asx8a.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=1D6CBF908513D643A61B83A72FDE226CE35710155B9EFBE33816300806CBDCC9A941B9081CA8557426AAEC95FF6214A058E3BF0A5112396F&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:39 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:39 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:39 volumio volumio[5760]: info: ------------------------------ 49ms
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:39 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:39 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:39 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:39 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:39 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:40 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:40 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 29 05:17:40 volumio volumio[5760]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 29 05:17:40 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 29 05:17:40 volumio volumio[5760]: info: Listing playlists
Apr 29 05:17:40 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:40 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:40 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:43 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:44 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:44 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:44 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:44 volumio volumio[5760]: info: sendMpdCommand stop took 85 milliseconds
Apr 29 05:17:44 volumio volumio[5760]: info:
Apr 29 05:17:44 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:44 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:44 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:44 volumio volumio[5760]: info:
Apr 29 05:17:44 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:44 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:44 volumio volumio[5760]: info: sendMpdCommand status took 9 milliseconds
Apr 29 05:17:44 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:44 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:44 volumio volumio[5760]: 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:17:44 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:44 volumio volumio[5760]: info: No code
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:44 volumio volumio[5760]: info: ------------------------------ 29ms
Apr 29 05:17:44 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:44 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:44 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:44 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:44 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:44 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:44 volumio volumio[5760]: info: ------------------------------ 31ms
Apr 29 05:17:44 volumio volumio[5760]: info:
Apr 29 05:17:44 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:44 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:44 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:44 volumio volumio[5760]: info:
Apr 29 05:17:44 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:44 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:44 volumio volumio[5760]: info:
Apr 29 05:17:44 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:44 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:44 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:44 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:44 volumio volumio[5760]: info:
Apr 29 05:17:44 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:44 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:44 volumio volumio[5760]: info:
Apr 29 05:17:44 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:44 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:44 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:44 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:44 volumio volumio[5760]: info:
Apr 29 05:17:44 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:44 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:44 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:45 volumio volumio[5760]: info:
Apr 29 05:17:45 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:45 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:45 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:45 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:45 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:17:45 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:45 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:45 volumio volumio[5760]: info:
Apr 29 05:17:45 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:45 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:45 volumio volumio[5760]: info:
Apr 29 05:17:45 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:45 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:45 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:45 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 5 milliseconds
Apr 29 05:17:45 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:17:45 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:17:45 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:45 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:45 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:45 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:45 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:45 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Rinaldo - Act II: Lascia ch'io pianga","artist":"Cecilia Bartoli","album":"The Opera Album 2011","uri":"http://isure6.stream.qqmusic.qq.com/M500000kL0cv12cdCn.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=E0C8C60CFF9773151C0CE22D41C203718CC6D001B7F4BAE08D9C6B41106AD259B06CE10A1AE2D67C92523A49FCCF470A82B0045725FF4934&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:45 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:45 volumio volumio[5760]: info: ------------------------------ 54ms
Apr 29 05:17:45 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 33 milliseconds
Apr 29 05:17:45 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:45 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:45 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:45 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Rinaldo - Act II: Lascia ch'io pianga","artist":"Cecilia Bartoli","album":"The Opera Album 2011","uri":"http://isure6.stream.qqmusic.qq.com/M500000kL0cv12cdCn.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=E0C8C60CFF9773151C0CE22D41C203718CC6D001B7F4BAE08D9C6B41106AD259B06CE10A1AE2D67C92523A49FCCF470A82B0045725FF4934&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:45 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:45 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:45 volumio volumio[5760]: info: ------------------------------ 61ms
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:45 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:45 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:45 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:45 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:45 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:49 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:49 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:49 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:49 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:49 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:49 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:49 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:49 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:49 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:49 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:49 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:49 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:49 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:49 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:49 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:49 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:49 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:49 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:49 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:49 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:49 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:49 volumio volumio[5760]: info:
Apr 29 05:17:49 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:49 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:49 volumio volumio[5760]: info:
Apr 29 05:17:49 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:49 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:49 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:49 volumio volumio[5760]: info: sendMpdCommand stop took 68 milliseconds
Apr 29 05:17:49 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:49 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:49 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:49 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:50 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:17:50 volumio volumio[5760]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:50 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:50 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:50 volumio volumio[5760]: 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:17:50 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:50 volumio volumio[5760]: info: No code
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 27ms
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:50 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:50 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 9ms
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 9ms
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:50 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:50 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:50 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:50 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:50 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:50 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:50 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:50 volumio volumio[5760]: info:
Apr 29 05:17:50 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:50 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:17:50 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:17:50 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:17:50 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:50 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:50 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:50 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La Wally - Act I: Ebben Ne andrò lontana","artist":"Angela Gheorghiu","album":"The Opera Album 2011","uri":"http://isure6.stream.qqmusic.qq.com/M500003EozcG3U0R3d.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=EF23A42EFCBC85D6FF268296D6443B9B217D524DF20D40C8A756D432CF495B522A9FF48AFF280C69969E594332223C2BFB03ABE436795666&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:50 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 36ms
Apr 29 05:17:50 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 17 milliseconds
Apr 29 05:17:50 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:50 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:50 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:50 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"La Wally - Act I: Ebben Ne andrò lontana","artist":"Angela Gheorghiu","album":"The Opera Album 2011","uri":"http://isure6.stream.qqmusic.qq.com/M500003EozcG3U0R3d.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=EF23A42EFCBC85D6FF268296D6443B9B217D524DF20D40C8A756D432CF495B522A9FF48AFF280C69969E594332223C2BFB03ABE436795666&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:50 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:50 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:50 volumio volumio[5760]: info: ------------------------------ 42ms
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:50 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:53 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:54 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:54 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:54 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:54 volumio volumio[5760]: info:
Apr 29 05:17:54 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:54 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:54 volumio volumio[5760]: info:
Apr 29 05:17:54 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:54 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:54 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:54 volumio volumio[5760]: info: sendMpdCommand stop took 75 milliseconds
Apr 29 05:17:54 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:54 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:54 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:54 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:17:54 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:17:54 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:54 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:54 volumio volumio[5760]: 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:17:54 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:54 volumio volumio[5760]: info: No code
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:54 volumio volumio[5760]: info: ------------------------------ 26ms
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:54 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:54 volumio volumio[5760]: info:
Apr 29 05:17:54 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:54 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:54 volumio volumio[5760]: info:
Apr 29 05:17:54 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:54 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:54 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:54 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:54 volumio volumio[5760]: info:
Apr 29 05:17:54 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:54 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:54 volumio volumio[5760]: info:
Apr 29 05:17:54 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:54 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:54 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:54 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:54 volumio volumio[5760]: info:
Apr 29 05:17:54 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:54 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:54 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:54 volumio volumio[5760]: info:
Apr 29 05:17:54 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:54 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:54 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:55 volumio volumio[5760]: info:
Apr 29 05:17:55 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:55 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:55 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:55 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:17:55 volumio volumio[5760]: info:
Apr 29 05:17:55 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:55 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:55 volumio volumio[5760]: info:
Apr 29 05:17:55 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:55 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:17:55 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:55 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:55 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:55 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:55 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:17:55 volumio volumio[5760]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:17:55 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:17:55 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:55 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:17:55 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:55 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:55 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:55 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Madama Butterfly - Act II: Humming Chorus","artist":"Philharmonia Orchestra","album":"The Opera Album 2011","uri":"http://isure6.stream.qqmusic.qq.com/M5000047Ytfh3JN4rU.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=ABDE59B68D071BDCFA09D374EBF8BA759775B61A2EF9C5E03AB39BB730EDF65DB1AE8963C64589EDFB11759ED1CF1BCB2F54DCB59695DE79&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:55 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:55 volumio volumio[5760]: info: ------------------------------ 30ms
Apr 29 05:17:55 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 12 milliseconds
Apr 29 05:17:55 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:17:55 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:55 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:17:55 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":190,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Madama Butterfly - Act II: Humming Chorus","artist":"Philharmonia Orchestra","album":"The Opera Album 2011","uri":"http://isure6.stream.qqmusic.qq.com/M5000047Ytfh3JN4rU.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=ABDE59B68D071BDCFA09D374EBF8BA759775B61A2EF9C5E03AB39BB730EDF65DB1AE8963C64589EDFB11759ED1CF1BCB2F54DCB59695DE79&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:17:55 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:17:55 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:55 volumio volumio[5760]: info: ------------------------------ 33ms
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:55 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:55 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:55 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:17:55 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:17:55 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:17:56 volumio kernel: rpi_firmware_get_throttled: 9 callbacks suppressed
Apr 29 05:17:56 volumio kernel: Under-voltage detected! (0x00050005)
Apr 29 05:17:59 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:17:59 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:59 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:17:59 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:17:59 volumio volumio[5760]: info:
Apr 29 05:17:59 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:59 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:59 volumio volumio[5760]: info:
Apr 29 05:17:59 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:17:59 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:17:59 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:17:59 volumio volumio[5760]: info: sendMpdCommand stop took 28 milliseconds
Apr 29 05:17:59 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:17:59 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:59 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:17:59 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:17:59 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:59 volumio volumio[5760]: 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:17:59 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:59 volumio volumio[5760]: info: No code
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:17:59 volumio volumio[5760]: info: ------------------------------ 21ms
Apr 29 05:17:59 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:17:59 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:17:59 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:17:59 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:17:59 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:17:59 volumio volumio[5760]: info:
Apr 29 05:17:59 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:59 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:59 volumio volumio[5760]: info:
Apr 29 05:17:59 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:59 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:59 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:59 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:59 volumio volumio[5760]: info:
Apr 29 05:17:59 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:59 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:59 volumio volumio[5760]: info:
Apr 29 05:17:59 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:59 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:59 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:17:59 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:59 volumio volumio[5760]: info:
Apr 29 05:17:59 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:59 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:59 volumio volumio[5760]: info:
Apr 29 05:17:59 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:17:59 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:17:59 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:17:59 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:00 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:00 volumio volumio[5760]: info:
Apr 29 05:18:00 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:00 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:00 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:00 volumio volumio[5760]: info:
Apr 29 05:18:00 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:00 volumio volumio[5760]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:18:00 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:00 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:00 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:00 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:00 volumio volumio[5760]: info:
Apr 29 05:18:00 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:00 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:00 volumio volumio[5760]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:18:00 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:18:00 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:00 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:00 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:00 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:00 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:00 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:00 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Die Zauberflöte - Act I: Birdcatcher's Song","artist":"Hermann Prey","album":"The Opera Album 2011","uri":"http://isure6.stream.qqmusic.qq.com/M500002izaLE1LQE4s.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=E495C358C7B8B7C8BEA5EDBF5CAF4460B88A800D3258170BB25F52F6736AFFA9C996D745AD476792BC9A2B610B87220A22FE215C048C9D8A&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:00 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:00 volumio volumio[5760]: info: ------------------------------ 33ms
Apr 29 05:18:00 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 15 milliseconds
Apr 29 05:18:00 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:00 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:00 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:00 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":181,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Die Zauberflöte - Act I: Birdcatcher's Song","artist":"Hermann Prey","album":"The Opera Album 2011","uri":"http://isure6.stream.qqmusic.qq.com/M500002izaLE1LQE4s.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=E495C358C7B8B7C8BEA5EDBF5CAF4460B88A800D3258170BB25F52F6736AFFA9C996D745AD476792BC9A2B610B87220A22FE215C048C9D8A&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:00 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:18:00 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:00 volumio volumio[5760]: info: ------------------------------ 41ms
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:00 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:00 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:00 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:00 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:00 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:01 volumio kernel: rpi_firmware_get_throttled: 9 callbacks suppressed
Apr 29 05:18:01 volumio kernel: Voltage normalised (0x00000000)
Apr 29 05:18:04 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:18:04 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:04 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:18:04 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:18:04 volumio volumio[5760]: info:
Apr 29 05:18:04 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:04 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:04 volumio volumio[5760]: info:
Apr 29 05:18:04 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:04 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:04 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:04 volumio volumio[5760]: info: sendMpdCommand stop took 57 milliseconds
Apr 29 05:18:04 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:18:04 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:04 volumio volumio[5760]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:18:04 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:04 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:04 volumio volumio[5760]: 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:18:04 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:04 volumio volumio[5760]: info: No code
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:04 volumio volumio[5760]: info: ------------------------------ 21ms
Apr 29 05:18:04 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:18:04 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:18:04 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:18:04 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:04 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:18:04 volumio volumio[5760]: info:
Apr 29 05:18:04 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:04 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:04 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:04 volumio volumio[5760]: info:
Apr 29 05:18:04 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:04 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:04 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:04 volumio volumio[5760]: info:
Apr 29 05:18:04 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:04 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:04 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:04 volumio volumio[5760]: info:
Apr 29 05:18:04 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:04 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:04 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:04 volumio volumio[5760]: info:
Apr 29 05:18:04 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:04 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:04 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:04 volumio volumio[5760]: info:
Apr 29 05:18:04 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:04 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:04 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:05 volumio volumio[5760]: info:
Apr 29 05:18:05 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:05 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:05 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:05 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:05 volumio volumio[5760]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:18:05 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:05 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:05 volumio volumio[5760]: info:
Apr 29 05:18:05 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:05 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:05 volumio volumio[5760]: info:
Apr 29 05:18:05 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:05 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:05 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:05 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 29 05:18:05 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:05 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:05 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:05 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Take It All","artist":"Adele","album":"21","uri":"http://isure6.stream.qqmusic.qq.com/M50000186MNQ1TupNg.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=DCAE76147D28E57CE6247406DA578A4CEFB19391340B94C3C92707C330DB641445ABCEB00293BF0092DCF51D92FC1B1A0F26846B0751BD34&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:05 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:05 volumio volumio[5760]: info: ------------------------------ 28ms
Apr 29 05:18:05 volumio volumio[5760]: info: ------------------------------ 13ms
Apr 29 05:18:05 volumio volumio[5760]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:18:05 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:05 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:05 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:05 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 7 milliseconds
Apr 29 05:18:05 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:05 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:05 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:05 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Take It All","artist":"Adele","album":"21","uri":"http://isure6.stream.qqmusic.qq.com/M50000186MNQ1TupNg.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=DCAE76147D28E57CE6247406DA578A4CEFB19391340B94C3C92707C330DB641445ABCEB00293BF0092DCF51D92FC1B1A0F26846B0751BD34&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:05 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:18:05 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:05 volumio volumio[5760]: info: ------------------------------ 36ms
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:05 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:05 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:05 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:05 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:07 volumio kernel: Under-voltage detected! (0x00050005)
Apr 29 05:18:08 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:18:08 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:08 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:18:08 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:18:08 volumio volumio[5760]: info:
Apr 29 05:18:08 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:08 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:08 volumio volumio[5760]: info:
Apr 29 05:18:08 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:08 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:08 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:08 volumio volumio[5760]: info: sendMpdCommand stop took 40 milliseconds
Apr 29 05:18:08 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:18:08 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:18:08 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:18:08 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:08 volumio volumio[5760]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:18:08 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:08 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:08 volumio volumio[5760]: 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:18:08 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:08 volumio volumio[5760]: info: No code
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:08 volumio volumio[5760]: info: ------------------------------ 21ms
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:08 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:08 volumio volumio[5760]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Adele/21/e4f5d46d-6560-4ee6-af95-6a0c60c74855.jpg'
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:09 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:09 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:09 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:09 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:09 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:09 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:09 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:09 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:09 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:09 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:09 volumio volumio[5760]: info:
Apr 29 05:18:09 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:09 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:18:09 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:09 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:09 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:09 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:18:09 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:18:09 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:18:09 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:09 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:09 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:09 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:09 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:09 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rinaldo, HWV 7a: Lascia ch'io pianga(Air de Almirena)","artist":"Ragin Derek Lee","album":"Farinelli - Il Castrato (Original Motion Picture Soundtrack)(From Gérard Corbiau's Film Farinelli, il castrato)","uri":"http://isure6.stream.qqmusic.qq.com/M500001o5P4K4gcgl5.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=2C9303F3CFF064C2FF0BCC7BB88BADA13E6F4A8640B3D8096241BBD15CE29149165E9A02B8E5E1F0536C97416EBDC1E886EA5D532FC58606&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:09 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 32ms
Apr 29 05:18:09 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 12 milliseconds
Apr 29 05:18:09 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:09 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:09 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:09 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":243,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Rinaldo, HWV 7a: Lascia ch'io pianga(Air de Almirena)","artist":"Ragin Derek Lee","album":"Farinelli - Il Castrato (Original Motion Picture Soundtrack)(From Gérard Corbiau's Film Farinelli, il castrato)","uri":"http://isure6.stream.qqmusic.qq.com/M500001o5P4K4gcgl5.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=2C9303F3CFF064C2FF0BCC7BB88BADA13E6F4A8640B3D8096241BBD15CE29149165E9A02B8E5E1F0536C97416EBDC1E886EA5D532FC58606&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:09 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:18:09 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:09 volumio volumio[5760]: info: ------------------------------ 34ms
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:09 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:09 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:09 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:09 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:09 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:12 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:18:12 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:12 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:18:12 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:18:12 volumio volumio[5760]: info:
Apr 29 05:18:12 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:12 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:12 volumio volumio[5760]: info:
Apr 29 05:18:12 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:12 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:12 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:12 volumio volumio[5760]: info: sendMpdCommand stop took 39 milliseconds
Apr 29 05:18:12 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:18:12 volumio volumio[5760]: info: ------------------------------ 6ms
Apr 29 05:18:12 volumio volumio[5760]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:18:12 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:12 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:12 volumio volumio[5760]: 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:18:12 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:12 volumio volumio[5760]: info: No code
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:12 volumio volumio[5760]: info: ------------------------------ 39ms
Apr 29 05:18:12 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:18:12 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:18:12 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:18:12 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:12 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:13 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:13 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:13 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:13 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 5ms
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:13 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:13 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:13 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:13 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:13 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:13 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:13 volumio volumio[5760]: info:
Apr 29 05:18:13 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:13 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:18:13 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:13 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:13 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:13 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:13 volumio volumio[5760]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:18:13 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:18:13 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:13 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:13 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:13 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:13 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:13 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":576,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Idaspe: Ombra fedela anch'io(Air de Dario)","artist":"Ragin Derek Lee","album":"Farinelli - Il Castrato (Original Motion Picture Soundtrack)(From Gérard Corbiau's Film Farinelli, il castrato)","uri":"http://isure6.stream.qqmusic.qq.com/M5000015tQlE2RTRAQ.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=5E7CFDA10925E8582707642C218C6D0ACEC2EB520643C497AF3411F35B492A5C402BB2583766FA9F88CC69F42C491A8011DBA011D9276556&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:13 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 29ms
Apr 29 05:18:13 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 29 05:18:13 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:13 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:13 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:13 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":576,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Idaspe: Ombra fedela anch'io(Air de Dario)","artist":"Ragin Derek Lee","album":"Farinelli - Il Castrato (Original Motion Picture Soundtrack)(From Gérard Corbiau's Film Farinelli, il castrato)","uri":"http://isure6.stream.qqmusic.qq.com/M5000015tQlE2RTRAQ.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=5E7CFDA10925E8582707642C218C6D0ACEC2EB520643C497AF3411F35B492A5C402BB2583766FA9F88CC69F42C491A8011DBA011D9276556&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:13 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:18:13 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:13 volumio volumio[5760]: info: ------------------------------ 27ms
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:13 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:13 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:13 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:13 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:13 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:15 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:18:15 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:15 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:18:15 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:18:15 volumio volumio[5760]: info:
Apr 29 05:18:15 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:15 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:15 volumio volumio[5760]: info:
Apr 29 05:18:15 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:15 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:15 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:15 volumio volumio[5760]: info: sendMpdCommand stop took 66 milliseconds
Apr 29 05:18:15 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:18:15 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:18:15 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:18:15 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:15 volumio volumio[5760]: info: sendMpdCommand status took 4 milliseconds
Apr 29 05:18:15 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:15 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:15 volumio volumio[5760]: 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:18:15 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:15 volumio volumio[5760]: info: No code
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:15 volumio volumio[5760]: info: ------------------------------ 17ms
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:15 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:15 volumio kernel: Voltage normalised (0x00000000)
Apr 29 05:18:15 volumio volumio[5760]: info:
Apr 29 05:18:15 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:15 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:15 volumio volumio[5760]: info:
Apr 29 05:18:15 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:15 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:15 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:15 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:15 volumio volumio[5760]: info:
Apr 29 05:18:15 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:15 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:15 volumio volumio[5760]: info:
Apr 29 05:18:15 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:15 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:15 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:15 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:15 volumio volumio[5760]: info:
Apr 29 05:18:15 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:15 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:15 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:15 volumio volumio[5760]: info:
Apr 29 05:18:15 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:15 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:15 volumio volumio[5760]: info: ------------------------------ 1ms
Apr 29 05:18:16 volumio volumio[5760]: info:
Apr 29 05:18:16 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:16 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:16 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:16 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:16 volumio volumio[5760]: info: sendMpdCommand status took 11 milliseconds
Apr 29 05:18:16 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:16 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:16 volumio volumio[5760]: info:
Apr 29 05:18:16 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:16 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:16 volumio volumio[5760]: info:
Apr 29 05:18:16 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:16 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:16 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:16 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:18:16 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:16 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:16 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:16 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:16 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Artaserse: Ouverture","artist":"Christophe Rousset","album":"Farinelli - Il Castrato (Original Motion Picture Soundtrack)(From Gérard Corbiau's Film Farinelli, il castrato)","uri":"http://isure6.stream.qqmusic.qq.com/M500004HZhFx2rdENz.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=F89724BD8115234861B000D00364AE0BBB805F0419A36D89B2D7094A28D486EE78C2E8E2BE67F5E0ABA4FE79A671F6B0C01359AEAEBF7872&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:16 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:16 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:16 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:16 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:16 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:16 volumio volumio[5760]: info: ------------------------------ 37ms
Apr 29 05:18:16 volumio volumio[5760]: info: ------------------------------ 24ms
Apr 29 05:18:16 volumio volumio[5760]: info: sendMpdCommand status took 24 milliseconds
Apr 29 05:18:16 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:16 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:16 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:18:16 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:16 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:16 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:16 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:16 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":363,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Artaserse: Ouverture","artist":"Christophe Rousset","album":"Farinelli - Il Castrato (Original Motion Picture Soundtrack)(From Gérard Corbiau's Film Farinelli, il castrato)","uri":"http://isure6.stream.qqmusic.qq.com/M500004HZhFx2rdENz.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=F89724BD8115234861B000D00364AE0BBB805F0419A36D89B2D7094A28D486EE78C2E8E2BE67F5E0ABA4FE79A671F6B0C01359AEAEBF7872&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:16 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:16 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:16 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:18:16 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:18:16 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:18:16 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:16 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:16 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:16 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:19 volumio systemd-journal[206]: Suppressed 12 messages from /system.slice/volumio.service
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:18:19 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:19 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:18:19 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:18:19 volumio volumio[5760]: info:
Apr 29 05:18:19 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:19 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:19 volumio volumio[5760]: info:
Apr 29 05:18:19 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:19 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:19 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:19 volumio volumio[5760]: info: sendMpdCommand stop took 84 milliseconds
Apr 29 05:18:19 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:18:19 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:18:19 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:18:19 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:19 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:18:19 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:19 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:19 volumio volumio[5760]: 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:18:19 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:19 volumio volumio[5760]: info: No code
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:19 volumio volumio[5760]: info: ------------------------------ 24ms
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:19 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 9ms
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 9ms
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:20 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:20 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:20 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:20 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:20 volumio volumio[5760]: info:
Apr 29 05:18:20 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:20 volumio volumio[5760]: info: sendMpdCommand status took 12 milliseconds
Apr 29 05:18:20 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:20 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:20 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:20 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:20 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:18:20 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 29 05:18:20 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:20 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:20 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:20 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:20 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:20 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Artaserse: Se al labbro mio non credi","artist":"Christophe Rousset","album":"Farinelli - Il Castrato (Original Motion Picture Soundtrack)(From Gérard Corbiau's Film Farinelli, il castrato)","uri":"http://isure6.stream.qqmusic.qq.com/M500002ML4dw2RpSIJ.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=D9A413A4883AF7A2D20C95CD2CE4F42432CA86425713C16269FE3F2008FCCDBED78BBE1C6858B1F087C3E2D51ED5A242F2891B8DFAE12C6C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:20 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 28ms
Apr 29 05:18:20 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 9 milliseconds
Apr 29 05:18:20 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:20 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:20 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:20 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":300,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Artaserse: Se al labbro mio non credi","artist":"Christophe Rousset","album":"Farinelli - Il Castrato (Original Motion Picture Soundtrack)(From Gérard Corbiau's Film Farinelli, il castrato)","uri":"http://isure6.stream.qqmusic.qq.com/M500002ML4dw2RpSIJ.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=D9A413A4883AF7A2D20C95CD2CE4F42432CA86425713C16269FE3F2008FCCDBED78BBE1C6858B1F087C3E2D51ED5A242F2891B8DFAE12C6C&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:20 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:18:20 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:20 volumio volumio[5760]: info: ------------------------------ 28ms
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:20 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:20 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:20 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:20 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:20 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:25 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:18:25 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:25 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:18:25 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:18:25 volumio volumio[5760]: info:
Apr 29 05:18:25 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:25 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:25 volumio volumio[5760]: info:
Apr 29 05:18:25 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:25 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:25 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:25 volumio volumio[5760]: info: sendMpdCommand stop took 66 milliseconds
Apr 29 05:18:25 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:18:25 volumio volumio[5760]: info: ------------------------------ 9ms
Apr 29 05:18:25 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:18:25 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:25 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:25 volumio volumio[5760]: 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:18:25 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:25 volumio volumio[5760]: info: No code
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:25 volumio volumio[5760]: info: ------------------------------ 30ms
Apr 29 05:18:25 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:18:25 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:18:25 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:18:25 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:25 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:26 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:26 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 1ms
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:26 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:26 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:26 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:26 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:26 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:26 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:26 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:26 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:18:26 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:26 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:26 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:26 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:26 volumio volumio[5760]: info:
Apr 29 05:18:26 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:26 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:26 volumio volumio[5760]: info: sendMpdCommand status took 13 milliseconds
Apr 29 05:18:26 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:18:26 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:26 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:26 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:26 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:26 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:26 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Best For Last","artist":"Adele","album":"Hometown Glory","uri":"http://isure6.stream.qqmusic.qq.com/M500002xYxGg3DPC6l.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=35E2F87889891A76C7368F6914D9C433F8CB9CCCDB7AD5F0E2FF5E98CEF5DEA187F0515DE02C34BCDD18C66F652153A3B533753BBDE8400A&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:26 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 42ms
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 17ms
Apr 29 05:18:26 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 14 milliseconds
Apr 29 05:18:26 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:26 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:26 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:26 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":259,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Best For Last","artist":"Adele","album":"Hometown Glory","uri":"http://isure6.stream.qqmusic.qq.com/M500002xYxGg3DPC6l.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=35E2F87889891A76C7368F6914D9C433F8CB9CCCDB7AD5F0E2FF5E98CEF5DEA187F0515DE02C34BCDD18C66F652153A3B533753BBDE8400A&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:26 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:18:26 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:26 volumio volumio[5760]: info: ------------------------------ 41ms
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:26 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:26 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:26 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:26 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:26 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:32 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:18:32 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:32 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:18:32 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:18:32 volumio volumio[5760]: info:
Apr 29 05:18:32 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:32 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:32 volumio volumio[5760]: info:
Apr 29 05:18:32 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:32 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:32 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:32 volumio volumio[5760]: info: sendMpdCommand stop took 58 milliseconds
Apr 29 05:18:32 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:18:32 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:18:32 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:18:32 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:32 volumio volumio[5760]: info: sendMpdCommand status took 3 milliseconds
Apr 29 05:18:32 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:32 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:32 volumio volumio[5760]: 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:18:32 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:32 volumio volumio[5760]: info: No code
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:32 volumio volumio[5760]: info: ------------------------------ 26ms
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:32 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:32 volumio volumio[5760]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Adele/Hometown%20Glory/5e46841a-32cb-4390-8f6b-ced66b44b9f1.png'
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:33 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:33 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:33 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:33 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:33 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:33 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 2ms
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:33 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:33 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:33 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:33 volumio volumio[5760]: info: sendMpdCommand status took 14 milliseconds
Apr 29 05:18:33 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:33 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:33 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:33 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:33 volumio volumio[5760]: info:
Apr 29 05:18:33 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:33 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:33 volumio volumio[5760]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:18:33 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 29 05:18:33 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:33 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 29 05:18:33 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:33 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:33 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:33 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Unaccompanied Cello Suite No. 2 in G minor,Op,45 - II. Scherzo - Allegro molto","artist":"Yo-Yo Ma","album":"大提琴名曲演奏集","uri":"http://isure6.stream.qqmusic.qq.com/M500002hIPWn4QIwNQ.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=E87F5D7A67C418C2F38EE2F84B17845D369D43A02B5F13AF06666379F796E2B1AF9F95F69C9017183B7D4EEC8F51212F5E603C1E98573468&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:33 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 27ms
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 13ms
Apr 29 05:18:33 volumio volumio[5760]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 29 05:18:33 volumio volumio[5760]: verbose: ControllerMpd::parseTrackInfo
Apr 29 05:18:33 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:33 volumio volumio[5760]: verbose: In UPNP mode
Apr 29 05:18:33 volumio volumio[5760]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":209,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Unaccompanied Cello Suite No. 2 in G minor,Op,45 - II. Scherzo - Allegro molto","artist":"Yo-Yo Ma","album":"大提琴名曲演奏集","uri":"http://isure6.stream.qqmusic.qq.com/M500002hIPWn4QIwNQ.mp3?guid=B36106CE22DA41FB872922ED4279558F&vkey=E87F5D7A67C418C2F38EE2F84B17845D369D43A02B5F13AF06666379F796E2B1AF9F95F69C9017183B7D4EEC8F51212F5E603C1E98573468&uin=1152921504689611133&redirect=1&fromtag=101042","trackType":""}
Apr 29 05:18:33 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::syncState stateService play
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus play
Apr 29 05:18:33 volumio volumio[5760]: info: Received an update from plugin. extracting info from payload
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:33 volumio volumio[5760]: info: ------------------------------ 34ms
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:33 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:33 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:33 volumio volumio[5760]: info: CoreCommandRouter::volumioGetQueue
Apr 29 05:18:33 volumio volumio[5760]: info: CoreStateMachine::getQueue
Apr 29 05:18:33 volumio volumio[5760]: info: CorePlayQueue::getQueue
Apr 29 05:18:34 volumio kernel: Under-voltage detected! (0x00050005)
Apr 29 05:18:37 volumio volumio[5760]: info: Clearing queue after UPNP request
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::ClearQueue
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::stop
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 29 05:18:37 volumio volumio[5760]: verbose: UNSET VOLATILE
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::stPlaybackTimer
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::updateTrackBlock
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::getTrackBlock
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::serviceStop
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:37 volumio volumio[5760]: info: ControllerMpd::stop
Apr 29 05:18:37 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand stop
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::clearPlayQueue
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::saveQueue
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::volumioPushQueue
Apr 29 05:18:37 volumio volumio[5760]: info: sendMpdCommand stop took 90 milliseconds
Apr 29 05:18:37 volumio volumio[5760]: info:
Apr 29 05:18:37 volumio volumio[5760]: ---------------------------- MPD announces state update: player
Apr 29 05:18:37 volumio volumio[5760]: info: ControllerMpd::getState
Apr 29 05:18:37 volumio volumio[5760]: verbose: ControllerMpd::sendMpdCommand status
Apr 29 05:18:37 volumio volumio[5760]: info:
Apr 29 05:18:37 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:37 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:37 volumio volumio[5760]: info: sendMpdCommand status took 2 milliseconds
Apr 29 05:18:37 volumio volumio[5760]: verbose: ControllerMpd::parseState
Apr 29 05:18:37 volumio volumio[5760]: info: ControllerMpd::pushState
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::servicePushState
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:37 volumio volumio[5760]: 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:18:37 volumio volumio[5760]: verbose: CURRENT POSITION 0
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::syncState stateService stop
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::syncState currentStatus stop
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:37 volumio volumio[5760]: info: No code
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::pushState
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::volumioPushState
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 29 05:18:37 volumio volumio[5760]: info: ------------------------------ 17ms
Apr 29 05:18:37 volumio volumio[5760]: info: Starting UPNP Playback
Apr 29 05:18:37 volumio volumio[5760]: info: Preparing playback through UPNP
Apr 29 05:18:37 volumio volumio[5760]: info: CoreCommandRouter::volumioGetState
Apr 29 05:18:37 volumio volumio[5760]: info: CorePlayQueue::getTrack 0
Apr 29 05:18:37 volumio volumio[5760]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 29 05:18:37 volumio volumio[5760]: error: updateQueue error: null
Apr 29 05:18:37 volumio volumio[5760]: info: ------------------------------ 20ms
Apr 29 05:18:38 volumio volumio[5760]: info:
Apr 29 05:18:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:38 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:38 volumio volumio[5760]: info:
Apr 29 05:18:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:38 volumio volumio[5760]: info:
Apr 29 05:18:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:38 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:38 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:38 volumio volumio[5760]: info:
Apr 29 05:18:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:38 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:38 volumio volumio[5760]: info:
Apr 29 05:18:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:38 volumio volumio[5760]: info: ------------------------------ 3ms
Apr 29 05:18:38 volumio volumio[5760]: info:
Apr 29 05:18:38 volumio volumio[5760]: ---------------------------- MPD announces system playlist update
Apr 29 05:18:38 volumio volumio[5760]: info: Ignoring MPD Status Update
Apr 29 05:18:38 volumio volumio[5760]: info: ------------------------------ 4ms
Apr 29 05:18:38 volumio volumio[5760]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 29 05:18:38 volumio volumio[5760]: { Error: connect ETIMEDOUT 162.125.2.6:443
Apr 29 05:18:38 volumio volumio[5760]: at Object._errnoException (util.js:1022:11)
Apr 29 05:18:38 volumio volumio[5760]: at _exceptionWithHostPort (util.js:1044:20)
Apr 29 05:18:38 volumio volumio[5760]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14)
Apr 29 05:18:38 volumio volumio[5760]: code: 'ETIMEDOUT',
Apr 29 05:18:38 volumio volumio[5760]: errno: 'ETIMEDOUT',
Apr 29 05:18:38 volumio volumio[5760]: syscall: 'connect',
Apr 29 05:18:38 volumio volumio[5760]: address: '162.125.2.6',
Apr 29 05:18:38 volumio volumio[5760]: port: 443 }
Apr 29 05:18:38 volumio volumio[5760]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 29 05:18:38 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 29 05:18:39 volumio sudo[6527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-29 05:17
Apr 29 05:18:39 volumio sudo[6527]: 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"