-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Tue 2024-07-16 18:51:34 UTC. --
Jul 16 18:50:00 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:50:00 volumio volumio[863]: info: CorePlayQueue::getTrack 0
Jul 16 18:50:00 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:50:00 volumio volumio[863]: info: CorePlayQueue::getTrack 0
Jul 16 18:50:00 volumio volumio[863]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jul 16 18:50:03 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 16 18:50:03 volumio volumio[863]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Jul 16 18:50:03 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:50:03 volumio volumio[863]: info: CorePlayQueue::getTrack 0
Jul 16 18:50:04 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 16 18:50:04 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 16 18:50:04 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 16 18:50:04 volumio volumio-remote-updater[654]: No test mode
Jul 16 18:50:04 volumio volumio-remote-updater[654]: No alpha test mode
Jul 16 18:50:04 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jul 16 18:50:04 volumio volumio[863]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Jul 16 18:50:04 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 16 18:50:08 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 16 18:50:08 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 16 18:50:08 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jul 16 18:50:08 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jul 16 18:50:08 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 16 18:50:08 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jul 16 18:50:08 volumio volumio[863]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 16 18:50:08 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 16 18:50:10 volumio volumio[863]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jul 16 18:50:10 volumio volumio[863]: info: Completed starting MyVolumio Plugin
Jul 16 18:50:10 volumio volumio[863]: info: BOOT COMPLETED
Jul 16 18:50:10 volumio volumio[863]: [Metrics] CommandRouter: 46s 107.63ms
Jul 16 18:50:10 volumio volumio[863]: info: CoreCommandRouter::volumiosetStartupVolume
Jul 16 18:50:10 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 16 18:50:10 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 16 18:50:10 volumio volumio[863]: info: CoreCommandRouter::Close All Modals sent
Jul 16 18:50:10 volumio volumio[863]: info: CoreCommandRouter::Close All Modals sent
Jul 16 18:50:10 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Jul 16 18:50:11 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jul 16 18:50:11 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 16 18:50:11 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jul 16 18:50:11 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:50:11 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:50:13 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:50:13 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:50:15 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:50:15 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:50:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:50:16 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:50:20 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:50:20 volumio volumio[863]: info: CorePlayQueue::getTrack 0
Jul 16 18:50:20 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:50:20 volumio volumio[863]: info: CorePlayQueue::getTrack 0
Jul 16 18:50:21 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:50:21 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:50:25 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:50:25 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:50:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19628
Jul 16 18:50:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19627
Jul 16 18:50:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19628 in service upnp_browser
Jul 16 18:50:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19627 in service upnp_browser
Jul 16 18:50:26 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:50:26 volumio volumio[863]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jul 16 18:50:26 volumio volumio[863]: info: CoreStateMachine::ClearQueue
Jul 16 18:50:26 volumio volumio[863]: info: CoreStateMachine::stop
Jul 16 18:50:26 volumio volumio[863]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 16 18:50:26 volumio volumio[863]: info: CorePlayQueue::clearPlayQueue
Jul 16 18:50:26 volumio volumio[863]: info: CorePlayQueue::saveQueue
Jul 16 18:50:26 volumio volumio[863]: info: CoreCommandRouter::volumioPushQueue
Jul 16 18:50:26 volumio volumio[863]: info: CoreStateMachine::addQueueItems
Jul 16 18:50:26 volumio volumio[863]: info: CorePlayQueue::addQueueItems
Jul 16 18:50:26 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:50:26 volumio volumio[863]: info: Adding Item to queue: upnp/folder/http://192.168.20.12:50001/ContentDirectory/control@22$1298
Jul 16 18:50:26 volumio volumio[863]: info: Exploding uri upnp/folder/http://192.168.20.12:50001/ContentDirectory/control@22$1298 in service upnp_browser
Jul 16 18:50:26 volumio volumio[863]: info: Adding Item to queue: upnp/folder/http://192.168.20.12:50001/ContentDirectory/control@22$1300
Jul 16 18:50:26 volumio volumio[863]: info: Exploding uri upnp/folder/http://192.168.20.12:50001/ContentDirectory/control@22$1300 in service upnp_browser
Jul 16 18:50:26 volumio volumio[863]: info: Adding Item to queue: upnp/folder/http://192.168.20.12:50001/ContentDirectory/control@22$1299
Jul 16 18:50:26 volumio volumio[863]: info: Exploding uri upnp/folder/http://192.168.20.12:50001/ContentDirectory/control@22$1299 in service upnp_browser
Jul 16 18:50:26 volumio volumio[863]: info: Adding Item to queue: upnp/folder/http://192.168.20.12:50001/ContentDirectory/control@22$1297
Jul 16 18:50:26 volumio volumio[863]: info: Exploding uri upnp/folder/http://192.168.20.12:50001/ContentDirectory/control@22$1297 in service upnp_browser
Jul 16 18:50:26 volumio volumio[863]: info: Adding Item to queue: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19628
Jul 16 18:50:26 volumio volumio[863]: info: Using cached record of: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19628
Jul 16 18:50:26 volumio volumio[863]: info: Adding Item to queue: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19627
Jul 16 18:50:26 volumio volumio[863]: info: Using cached record of: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19627
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushQueue
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::saveQueue
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::updateTrackBlock
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrackBlock
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPlay
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::play index 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::stop
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::play index undefined
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::startPlaybackTimer
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: info: [1721155827026] ControllerUPNPBrowser::clearAddPlayTrack
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand stop
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand stop took 9 milliseconds
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand clear
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand clear took 11 milliseconds
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.20.12:50002/m/MP3/19592.mp3"
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: error: updateQueue error: null
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 21ms
Jul 16 18:50:27 volumio volumio[863]: error: updateQueue error: null
Jul 16 18:50:27 volumio volumio[863]: error: updateQueue error: null
Jul 16 18:50:27 volumio volumio[863]: error: updateQueue error: null
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.20.12:50002/m/MP3/19592.mp3"
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 29ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 26ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 25ms
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand add "http://192.168.20.12:50002/m/MP3/19592.mp3" took 11 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand play
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 20ms
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand play took 15 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 12ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 9ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 7ms
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces state update: player
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::getState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand status
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces state update: player
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::getState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand status
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces state update: player
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::getState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand status
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces state update: player
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::getState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand status
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand status took 42 milliseconds
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces state update: player
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::getState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand status
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand status took 49 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand status took 45 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand status took 43 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand playlistinfo took 11 milliseconds
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseTrackInfo
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::servicePushState
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: 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":"Johnny","artist":"LaBrassBanda","album":"Around the World","uri":"http://192.168.20.12:50002/m/MP3/19592.mp3","trackType":"mp3"}
Jul 16 18:50:27 volumio volumio[863]: verbose: CURRENT POSITION 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState stateService play
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState currentStatus stop
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 89ms
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces state update: player
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::getState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand status
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces state update: player
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::getState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand status
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces system playlist update
Jul 16 18:50:27 volumio volumio[863]: info: Ignoring MPD Status Update
Jul 16 18:50:27 volumio volumio[863]: info:
Jul 16 18:50:27 volumio volumio[863]: ---------------------------- MPD announces state update: player
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::getState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand status
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 58ms
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand status took 54 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand playlistinfo took 47 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand playlistinfo took 47 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand playlistinfo took 46 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 29ms
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand status took 25 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 23ms
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand status took 20 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 19ms
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand status took 15 milliseconds
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseTrackInfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseTrackInfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseTrackInfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseState
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::servicePushState
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: 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":"224 Kbps","isStreaming":false,"title":"Johnny","artist":"LaBrassBanda","album":"Around the World","uri":"http://192.168.20.12:50002/m/MP3/19592.mp3","trackType":"mp3"}
Jul 16 18:50:27 volumio volumio[863]: verbose: CURRENT POSITION 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState stateService play
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState currentStatus play
Jul 16 18:50:27 volumio volumio[863]: info: Received an update from plugin. extracting info from payload
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::pushState
Jul 16 18:50:27 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::servicePushState
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Johnny","artist":"LaBrassBanda","album":"Around the World","uri":"http://192.168.20.12:50002/m/MP3/19592.mp3","trackType":"mp3"}
Jul 16 18:50:27 volumio volumio[863]: verbose: CURRENT POSITION 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState stateService play
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState currentStatus play
Jul 16 18:50:27 volumio volumio[863]: info: Received an update from plugin. extracting info from payload
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::servicePushState
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Johnny","artist":"LaBrassBanda","album":"Around the World","uri":"http://192.168.20.12:50002/m/MP3/19592.mp3","trackType":"mp3"}
Jul 16 18:50:27 volumio volumio[863]: verbose: CURRENT POSITION 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState stateService play
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState currentStatus play
Jul 16 18:50:27 volumio volumio[863]: info: Received an update from plugin. extracting info from payload
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 271ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 269ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 267ms
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand playlistinfo took 165 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand playlistinfo took 144 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand playlistinfo took 143 milliseconds
Jul 16 18:50:27 volumio volumio[863]: info: sendMpdCommand playlistinfo took 143 milliseconds
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseTrackInfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseTrackInfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseTrackInfo
Jul 16 18:50:27 volumio volumio[863]: verbose: ControllerMpd::parseTrackInfo
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::servicePushState
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Johnny","artist":"LaBrassBanda","album":"Around the World","uri":"http://192.168.20.12:50002/m/MP3/19592.mp3","trackType":"mp3"}
Jul 16 18:50:27 volumio volumio[863]: verbose: CURRENT POSITION 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState stateService play
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState currentStatus play
Jul 16 18:50:27 volumio volumio[863]: info: Received an update from plugin. extracting info from payload
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::servicePushState
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Johnny","artist":"LaBrassBanda","album":"Around the World","uri":"http://192.168.20.12:50002/m/MP3/19592.mp3","trackType":"mp3"}
Jul 16 18:50:27 volumio volumio[863]: verbose: CURRENT POSITION 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState stateService play
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState currentStatus play
Jul 16 18:50:27 volumio volumio[863]: info: Received an update from plugin. extracting info from payload
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::servicePushState
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Johnny","artist":"LaBrassBanda","album":"Around the World","uri":"http://192.168.20.12:50002/m/MP3/19592.mp3","trackType":"mp3"}
Jul 16 18:50:27 volumio volumio[863]: verbose: CURRENT POSITION 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState stateService play
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState currentStatus play
Jul 16 18:50:27 volumio volumio[863]: info: Received an update from plugin. extracting info from payload
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: ControllerMpd::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::servicePushState
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getTrack 4
Jul 16 18:50:27 volumio volumio[863]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":279,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Johnny","artist":"LaBrassBanda","album":"Around the World","uri":"http://192.168.20.12:50002/m/MP3/19592.mp3","trackType":"mp3"}
Jul 16 18:50:27 volumio volumio[863]: verbose: CURRENT POSITION 4
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState stateService play
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::syncState currentStatus play
Jul 16 18:50:27 volumio volumio[863]: info: Received an update from plugin. extracting info from payload
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::pushState
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioPushState
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 383ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 351ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 349ms
Jul 16 18:50:27 volumio volumio[863]: info: ------------------------------ 346ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] *** musicPlay ***
Jul 16 18:50:27 volumio volumio[863]: info: [GPIO_Control] Delaying: 0ms
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:27 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:27 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:27 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreCommandRouter::volumioGetQueue
Jul 16 18:50:28 volumio volumio[863]: info: CoreStateMachine::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: CorePlayQueue::getQueue
Jul 16 18:50:28 volumio volumio[863]: info: [GPIO_Control] Turning GPIO 24 On (musicPlay)
Jul 16 18:50:40 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:50:40 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:51:00 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:51:00 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:51:03 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:03 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:51:08 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:08 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:51:10 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:11 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:51:19 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:19 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:51:19 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19587
Jul 16 18:51:19 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19587 in service upnp_browser
Jul 16 18:51:20 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:51:20 volumio volumio[863]: info: CoreCommandRouter::volumioGetState
Jul 16 18:51:21 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:21 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19599
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19600
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19601
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19602
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19603
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19604
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19605
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19606
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19607
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19608
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19609
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19610
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19611
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19612
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19613
Jul 16 18:51:21 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19614
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19599 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19600 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19601 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19602 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19603 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19604 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19605 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19606 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19607 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19608 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19609 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19610 in service upnp_browser
Jul 16 18:51:21 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19611 in service upnp_browser
Jul 16 18:51:22 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19612 in service upnp_browser
Jul 16 18:51:22 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19613 in service upnp_browser
Jul 16 18:51:22 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19614 in service upnp_browser
Jul 16 18:51:23 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:23 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19616
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19624
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19617
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19618
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19625
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19619
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19626
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19620
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19621
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19622
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19623
Jul 16 18:51:23 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19615
Jul 16 18:51:23 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19616 in service upnp_browser
Jul 16 18:51:23 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19624 in service upnp_browser
Jul 16 18:51:23 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19617 in service upnp_browser
Jul 16 18:51:23 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19618 in service upnp_browser
Jul 16 18:51:23 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19625 in service upnp_browser
Jul 16 18:51:23 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19619 in service upnp_browser
Jul 16 18:51:24 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19626 in service upnp_browser
Jul 16 18:51:24 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19620 in service upnp_browser
Jul 16 18:51:24 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19621 in service upnp_browser
Jul 16 18:51:24 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19622 in service upnp_browser
Jul 16 18:51:24 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19623 in service upnp_browser
Jul 16 18:51:24 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19615 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:25 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19588
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19589
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19590
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19591
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19592
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19593
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19594
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19595
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19596
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19597
Jul 16 18:51:25 volumio volumio[863]: info: Preloading song: upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19598
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19588 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19589 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19590 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19591 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19592 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19593 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19594 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19595 in service upnp_browser
Jul 16 18:51:25 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19596 in service upnp_browser
Jul 16 18:51:26 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19597 in service upnp_browser
Jul 16 18:51:26 volumio volumio[863]: info: Exploding uri upnp/http://192.168.20.12:50001/ContentDirectory/control@22$@19598 in service upnp_browser
Jul 16 18:51:30 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:30 volumio volumio[863]: info: Preload queue cleared
Jul 16 18:51:33 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Jul 16 18:51:33 volumio volumio[863]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 16 18:51:33 volumio volumio[863]: TypeError: Cannot read property 'length' of undefined
Jul 16 18:51:33 volumio volumio[863]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Jul 16 18:51:33 volumio volumio[863]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Jul 16 18:51:33 volumio volumio[863]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Jul 16 18:51:33 volumio volumio[863]: at Parser.emit (events.js:315:20)
Jul 16 18:51:33 volumio volumio[863]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Jul 16 18:51:33 volumio volumio[863]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Jul 16 18:51:33 volumio volumio[863]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Jul 16 18:51:33 volumio volumio[863]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Jul 16 18:51:33 volumio volumio[863]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Jul 16 18:51:33 volumio volumio[863]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Jul 16 18:51:33 volumio volumio[863]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Jul 16 18:51:33 volumio volumio[863]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Jul 16 18:51:33 volumio volumio[863]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Jul 16 18:51:33 volumio volumio[863]: at IncomingMessage.emit (events.js:327:22)
Jul 16 18:51:33 volumio volumio[863]: at endReadableNT (internal/streams/readable.js:1327:12)
Jul 16 18:51:33 volumio volumio[863]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Jul 16 18:51:33 volumio volumio[863]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 16 18:51:34 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-16 18:50
Jul 16 18:51:34 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"