-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Thu 2025-07-10 11:54:50 UTC. -- Jul 10 11:53:08 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 11:53:08 volumio volumio[955]: info: Preload queue cleared Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2700.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2701.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2702.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2703.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2704.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2705.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2706.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2707.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2708.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2709.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2710.mp3?albumartistsonly=false&albumid=215&artistid=68 Jul 10 11:53:08 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2700.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:08 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2701.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:08 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2702.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:08 volumio volumio[955]: info: No valid Plugin REST Endpoint Jul 10 11:53:09 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2703.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:09 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2704.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:09 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2705.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:09 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2706.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:09 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2707.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:09 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2708.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:09 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2709.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:09 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/215/2710.mp3?albumartistsonly=false&albumid=215&artistid=68 in service upnp_browser Jul 10 11:53:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 11:53:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 11:53:16 volumio volumio[955]: info: Discovery: Getting this device information Jul 10 11:53:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 10 11:53:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 11:53:30 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 11:53:30 volumio volumio[955]: info: Preload queue cleared Jul 10 11:53:31 volumio volumio[955]: info: No valid Plugin REST Endpoint Jul 10 11:53:40 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 11:53:40 volumio volumio[955]: info: Preload queue cleared Jul 10 11:53:40 volumio volumio[955]: info: No valid Plugin REST Endpoint Jul 10 11:53:46 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 11:53:46 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 11:53:46 volumio volumio[955]: info: Discovery: Getting this device information Jul 10 11:53:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 10 11:53:46 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 11:54:04 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 11:54:04 volumio volumio[955]: info: Preload queue cleared Jul 10 11:54:04 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11429.mp3?albumartistsonly=false&albumid=970&artistid=68 Jul 10 11:54:04 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11430.mp3?albumartistsonly=false&albumid=970&artistid=68 Jul 10 11:54:04 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11431.mp3?albumartistsonly=false&albumid=970&artistid=68 Jul 10 11:54:04 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11432.mp3?albumartistsonly=false&albumid=970&artistid=68 Jul 10 11:54:04 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11433.mp3?albumartistsonly=false&albumid=970&artistid=68 Jul 10 11:54:04 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11434.mp3?albumartistsonly=false&albumid=970&artistid=68 Jul 10 11:54:04 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11435.mp3?albumartistsonly=false&albumid=970&artistid=68 Jul 10 11:54:04 volumio volumio[955]: info: Preloading song: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11436.mp3?albumartistsonly=false&albumid=970&artistid=68 Jul 10 11:54:04 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11429.mp3?albumartistsonly=false&albumid=970&artistid=68 in service upnp_browser Jul 10 11:54:04 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11430.mp3?albumartistsonly=false&albumid=970&artistid=68 in service upnp_browser Jul 10 11:54:04 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11431.mp3?albumartistsonly=false&albumid=970&artistid=68 in service upnp_browser Jul 10 11:54:04 volumio volumio[955]: info: No valid Plugin REST Endpoint Jul 10 11:54:04 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11432.mp3?albumartistsonly=false&albumid=970&artistid=68 in service upnp_browser Jul 10 11:54:05 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11433.mp3?albumartistsonly=false&albumid=970&artistid=68 in service upnp_browser Jul 10 11:54:05 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11434.mp3?albumartistsonly=false&albumid=970&artistid=68 in service upnp_browser Jul 10 11:54:05 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11435.mp3?albumartistsonly=false&albumid=970&artistid=68 in service upnp_browser Jul 10 11:54:05 volumio volumio[955]: info: Exploding uri upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/970/11436.mp3?albumartistsonly=false&albumid=970&artistid=68 in service upnp_browser Jul 10 11:54:13 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 11:54:13 volumio volumio[955]: info: Preload queue cleared Jul 10 11:54:13 volumio volumio[955]: info: No valid Plugin REST Endpoint Jul 10 11:54:14 volumio volumio[955]: info: CorePlayQueue::getTrack 7 Jul 10 11:54:14 volumio volumio[955]: info: CorePlayQueue::getTrack 8 Jul 10 11:54:14 volumio volumio[955]: info: Prefetching next song Jul 10 11:54:14 volumio volumio[955]: info: Doing Prefetch in UPNP Jul 10 11:54:14 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.245:1556/%25/d8d0ddf2d6a625fbd3c91e803e2e28f5/09%2520-%2520Il%2520ragazzo.mp3" Jul 10 11:54:14 volumio volumio[955]: info: Jul 10 11:54:14 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:14 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:14 volumio volumio[955]: info: sendMpdCommand add "http://192.168.1.245:1556/%25/d8d0ddf2d6a625fbd3c91e803e2e28f5/09%2520-%2520Il%2520ragazzo.mp3" took 4 milliseconds Jul 10 11:54:14 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 10 11:54:14 volumio volumio[955]: info: Jul 10 11:54:14 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:14 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:14 volumio volumio[955]: info: Jul 10 11:54:14 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:14 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:14 volumio volumio[955]: info: ------------------------------ 10ms Jul 10 11:54:14 volumio volumio[955]: info: sendMpdCommand consume 1 took 8 milliseconds Jul 10 11:54:14 volumio volumio[955]: info: ------------------------------ 6ms Jul 10 11:54:14 volumio volumio[955]: info: ------------------------------ 5ms Jul 10 11:54:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 11:54:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 11:54:16 volumio volumio[955]: info: Discovery: Getting this device information Jul 10 11:54:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 10 11:54:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 11:54:18 volumio volumio[955]: info: Jul 10 11:54:18 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:18 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:18 volumio volumio[955]: info: Jul 10 11:54:18 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:18 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:18 volumio volumio[955]: info: Jul 10 11:54:18 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:18 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:18 volumio volumio[955]: info: Jul 10 11:54:18 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:18 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:18 volumio volumio[955]: info: Jul 10 11:54:18 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:18 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:18 volumio volumio[955]: info: Jul 10 11:54:18 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:18 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:18 volumio volumio[955]: info: ------------------------------ 15ms Jul 10 11:54:18 volumio volumio[955]: info: sendMpdCommand status took 19 milliseconds Jul 10 11:54:18 volumio volumio[955]: info: ------------------------------ 19ms Jul 10 11:54:18 volumio volumio[955]: info: sendMpdCommand status took 18 milliseconds Jul 10 11:54:18 volumio volumio[955]: info: ------------------------------ 17ms Jul 10 11:54:18 volumio volumio[955]: info: sendMpdCommand status took 16 milliseconds Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 10 11:54:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 10 11:54:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:18 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getTrack 7 Jul 10 11:54:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":279485,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La casa di Hilde","artist":"Francesco De Gregori","album":"Alice non lo sa","uri":"http://192.168.1.245:1556/%25/a43a14bf4b101f8786b311160a8173f1/08%2520-%2520La%2520casa%2520di%2520Hilde.mp3","trackType":"mp3"} Jul 10 11:54:18 volumio volumio[955]: verbose: CURRENT POSITION 7 Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:18 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:18 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getTrack 7 Jul 10 11:54:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":279485,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La casa di Hilde","artist":"Francesco De Gregori","album":"Alice non lo sa","uri":"http://192.168.1.245:1556/%25/a43a14bf4b101f8786b311160a8173f1/08%2520-%2520La%2520casa%2520di%2520Hilde.mp3","trackType":"mp3"} Jul 10 11:54:18 volumio volumio[955]: verbose: CURRENT POSITION 7 Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:18 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:18 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getTrack 7 Jul 10 11:54:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":279485,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"La casa di Hilde","artist":"Francesco De Gregori","album":"Alice non lo sa","uri":"http://192.168.1.245:1556/%25/a43a14bf4b101f8786b311160a8173f1/08%2520-%2520La%2520casa%2520di%2520Hilde.mp3","trackType":"mp3"} Jul 10 11:54:18 volumio volumio[955]: verbose: CURRENT POSITION 7 Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:18 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:18 volumio volumio[955]: info: ------------------------------ 95ms Jul 10 11:54:18 volumio volumio[955]: info: ------------------------------ 93ms Jul 10 11:54:18 volumio volumio[955]: info: ------------------------------ 89ms Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:18 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:18 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: Preload queue cleared Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::ClearQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::stop Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 7 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::serviceStop Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 7 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::serviceStop Jul 10 11:54:19 volumio volumio[955]: info: [1752148459093] ControllerUPNPBrowser::stop Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::addQueueItems Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::addQueueItems Jul 10 11:54:19 volumio volumio[955]: info: Preload queue cleared Jul 10 11:54:19 volumio volumio[955]: info: Adding Item to queue: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11412.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Using cached record of: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11412.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Adding Item to queue: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11413.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Using cached record of: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11413.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Adding Item to queue: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11414.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Using cached record of: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11414.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Adding Item to queue: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11415.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Using cached record of: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11415.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Adding Item to queue: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11416.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Using cached record of: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11416.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Adding Item to queue: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11417.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Using cached record of: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11417.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Adding Item to queue: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11418.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Using cached record of: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11418.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Adding Item to queue: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11419.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: Using cached record of: upnp/http://192.168.1.245:1556/ContentDirectory/db32dd09-ecb5-6aa8-9d8c-e3e2e9cd2910/control.xml@musicdb://artists/68/968/11419.mp3?albumartistsonly=false&albumid=968&artistid=68 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::play index 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::stop Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::play index undefined Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: info: [1752148459122] ControllerUPNPBrowser::clearAddPlayTrack Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand stop took 35 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand stop took 8 milliseconds Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 11 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand clear took 11 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 6 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 5 milliseconds Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3" Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: 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} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: No code Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: 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} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService stop Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: No code Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 59ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 60ms Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: error: updateQueue error: null Jul 10 11:54:19 volumio volumio[955]: error: updateQueue error: null Jul 10 11:54:19 volumio volumio[955]: error: updateQueue error: null Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 69ms Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 65 milliseconds Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3" Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 12ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 9ms Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 85ms Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand add "http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3" took 18 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 17ms Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand play took 15 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 12ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 10ms Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 30 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 23 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 20 milliseconds Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 76 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 72 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 72 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 72 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 14 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 12 milliseconds Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06%2520-%2520Viva%2520l%2527Italia.mp3","artist":null,"album":null,"uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06%2520-%2520Viva%2520l%2527Italia.mp3","artist":null,"album":null,"uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:19 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06%2520-%2520Viva%2520l%2527Italia.mp3","artist":null,"album":null,"uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:19 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 161ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 169ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 168ms Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 10 11:54:19 volumio volumio[955]: info: Ignoring MPD Status Update Jul 10 11:54:19 volumio volumio[955]: info: Jul 10 11:54:19 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::getState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 94ms Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 88 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 86 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 82 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 81 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 18ms Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 16 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 14ms Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand status took 12 milliseconds Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Viva l'Italia","artist":"Francesco De Gregori","album":"Viva l'Italia","uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:19 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Viva l'Italia","artist":"Francesco De Gregori","album":"Viva l'Italia","uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:19 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Viva l'Italia","artist":"Francesco De Gregori","album":"Viva l'Italia","uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:19 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 241ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 176ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 176ms Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 190 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 188 milliseconds Jul 10 11:54:19 volumio volumio[955]: info: sendMpdCommand playlistinfo took 187 milliseconds Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Viva l'Italia","artist":"Francesco De Gregori","album":"Viva l'Italia","uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:19 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Viva l'Italia","artist":"Francesco De Gregori","album":"Viva l'Italia","uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:19 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ControllerMpd::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getTrack 5 Jul 10 11:54:19 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Viva l'Italia","artist":"Francesco De Gregori","album":"Viva l'Italia","uri":"http://192.168.1.245:1556/%25/3c222e39c904a993e0a3f7c7f8b79a3a/06%2520-%2520Viva%2520l%2527Italia.mp3","trackType":"mp3"} Jul 10 11:54:19 volumio volumio[955]: verbose: CURRENT POSITION 5 Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 10 11:54:19 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::pushState Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 334ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 255ms Jul 10 11:54:19 volumio volumio[955]: info: ------------------------------ 254ms Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 10 11:54:19 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 10 11:54:19 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 10 11:54:46 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 11:54:46 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 11:54:46 volumio volumio[955]: info: Discovery: Getting this device information Jul 10 11:54:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 10 11:54:46 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 11:54:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 11:54:47 volumio volumio[955]: info: Preload queue cleared Jul 10 11:54:49 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 11:54:49 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 11:54:49 volumio volumio[955]: TypeError: Cannot read property 'length' of undefined Jul 10 11:54:49 volumio volumio[955]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 10 11:54:49 volumio volumio[955]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 10 11:54:49 volumio volumio[955]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 10 11:54:49 volumio volumio[955]: at Parser.emit (events.js:315:20) Jul 10 11:54:49 volumio volumio[955]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 10 11:54:49 volumio volumio[955]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 10 11:54:49 volumio volumio[955]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 10 11:54:49 volumio volumio[955]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 10 11:54:49 volumio volumio[955]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 10 11:54:49 volumio volumio[955]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 10 11:54:49 volumio volumio[955]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 10 11:54:49 volumio volumio[955]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 10 11:54:49 volumio volumio[955]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 10 11:54:49 volumio volumio[955]: at IncomingMessage.emit (events.js:327:22) Jul 10 11:54:49 volumio volumio[955]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 10 11:54:49 volumio volumio[955]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 10 11:54:49 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 11:54:50 volumio sudo[16589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-10 11:53 Jul 10 11:54:50 volumio sudo[16589]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"