:4:src/mpdcli.cxx:128::MPDCLi::openconn: mpd protocol version: 0.20.0
:4:libupnpp/upnpplib.cxx:156::LibUPnP: Using IP 192.168.2.21 port 49152
:4:src/httpfs.cxx:252::Failed reading /usr/share/upmpdcli/icon.png : open/stat: errno: 2 : 
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:upnp-org:serviceId:AVTransport]
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:upnp-org:serviceId:RenderingControl]
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:upnp-org:serviceId:ConnectionManager]
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:av-openhome-org:serviceId:Info]
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:av-openhome-org:serviceId:Time]
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:av-openhome-org:serviceId:Volume]
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:av-openhome-org:serviceId:Credentials]
:4:src/execmd-fixed.cpp:469::ExecCmd::startExec: (0|1) openssl {pkey} {-in} {/var/cache/upmpdcli/ohcreds/credkey.pem} {-pubout} 
:5:src/netcon-fixed.cpp:369::Netcon::selectloop: fd 11 has 0x0 mask, erasing
:5:src/execmd-fixed.cpp:827::ExecCmd::doexec: selectloop returned 0
:4:src/execmd-fixed.cpp:993::ExecCmd::wait: got status 0x0
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:av-openhome-org:serviceId:Playlist]
:4:src/ohplaylist.cxx:111::ohPlaylist: cache restore done
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:av-openhome-org:serviceId:Radio]
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:av-openhome-org:serviceId:Product]
:4:src/ohproduct.cxx:117::OHProduct::OHProduct: sources: <SourceList>
 <Source>
  <Name>Playlist</Name>
  <Type>Playlist</Type>
  <Visible>1</Visible>
  <SystemName>Playlist</SystemName>
  </Source>
 <Source>
  <Name>Radio</Name>
  <Type>Radio</Type>
  <Visible>1</Visible>
  <SystemName>Radio</SystemName>
  </Source>
</SourceList>

:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:upnp-org:serviceId:ContentDirectory]
:5:src/mediaserver/contentdirectory.cxx:276::ContentDirectory::Internal::maybeStartSomePlugins: starting uprcl
:4:src/mediaserver/contentdirectory.cxx:59::ContentDirectory::pluginFactory: for uprcl
:4:src/mediaserver/contentdirectory.cxx:73::ContentDirectory: host 192.168.2.21 port 49152
:4:src/mediaserver/cdplugins/cmdtalk-fixed.cpp:85::CmdTalk::startCmd
:4:src/execmd-fixed.cpp:469::ExecCmd::startExec: (1|1) /usr/share/upmpdcli/cdplugins/uprcl/uprcl-app.py 
:2:src/sysvshm-fixed.cpp:105::ShmSeg::ShmSeg: shmget(3040): errno 2: No such file or directory
:5:src/mediaserver/cdplugins/plgwithslave.cxx:259::PlgWithSlave::maybeStartCmd: shm attach failed (probably ok)
:4:libupnpp/device/device.cxx:424::UpnpDevice::addService: [urn:upnp-org:serviceId:ConnectionManager]
:4:src/main.cxx:762::Entering event loop
:4:src/main.cxx:774::Renderer event loop
:4:libupnpp/device/device.cxx:383::UPNP_EVENT_SUBSCRIPTION_REQUEST: urn:upnp-org:serviceId:RenderingControl
:5:src/protocolinfo.cxx:134::read_protocolinfo data: [http-get:*:audio/L16:DLNA.ORG_PN=LPCM,http-get:*:application/flac:*,http-get:*:application/x-flac:*,http-get:*:audio/flac:*,http-get:*:audio/x-flac:*,http-get:*:audio/aac:*,http-get:*:audio/x-aiff:*,http-get:*:audio/aif:*,http-get:*:audio/aiff:*,http-get:*:audio/dff:*,http-get:*:audio/x-dff:*,http-get:*:audio/dsd:*,http-get:*:audio/x-dsd:*,http-get:*:audio/dsf:*,http-get:*:audio/x-dsf:*,http-get:*:audio/m4a:*,http-get:*:audio/x-m4a:*,http-get:*:audio/matroska:*,http-get:*:audio/x-matroska:*,http-get:*:audio/mp1:*,http-get:*:audio/mp4:*,http-get:*:audio/mpeg:*,http-get:*:audio/x-mpeg:*,http-get:*:audio/ogg:*,http-get:*:audio/vorbis:*,http-get:*:audio/x-ape:*,http-get:*:audio/ape:*,http-get:*:audio/x-monkeys-audio:*,http-get:*:audio/wav:*,http-get:*:audio/x-wav:*,http-get:*:audio/wave:*,http-get:*:audio/x-ms-wma:*,http-get:*:audio/x-ogg:*,http-get:*:audio/x-scpls:*,http-get:*:audio/x-vorbis+ogg:*,http-get:*:audio/x-vorbis:*,http-get:*:audio/x-wavpack:*,http-get:*:video/mp4:*]
:4:src/mpdcli.cxx:849::MPDCli::getQueueData
:4:src/mpdcli.cxx:834::MPDCli::getQueueSongs: 1 songs 
:4:src/ohplaylist.cxx:150::OHPlaylist::translateIdArray: current ids: 1 
:4:libupnpp/device/device.cxx:383::UPNP_EVENT_SUBSCRIPTION_REQUEST: urn:av-openhome-org:serviceId:Product
:4:libupnpp/device/device.cxx:383::UPNP_EVENT_SUBSCRIPTION_REQUEST: urn:av-openhome-org:serviceId:Credentials
:4:libupnpp/device/device.cxx:383::UPNP_EVENT_SUBSCRIPTION_REQUEST: urn:upnp-org:serviceId:RenderingControl
:4:libupnpp/device/device.cxx:383::UPNP_EVENT_SUBSCRIPTION_REQUEST: urn:upnp-org:serviceId:RenderingControl
:4:libupnpp/device/device.cxx:319::UPNP_CONTROL_ACTION_REQUEST: GetProtocolInfo. Params: <?xml version="1.0"?>
<u:GetProtocolInfo xmlns:u="urn:schemas-upnp-org:service:ConnectionManager:1"></u:GetProtocolInfo>

:4:src/conman.cxx:97::UpMpdConMan::getProtocolInfo
:4:libupnpp/device/device.cxx:319::UPNP_CONTROL_ACTION_REQUEST: Browse. Params: <?xml version="1.0"?>
<u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<ObjectID>0</ObjectID>
<BrowseFlag>BrowseDirectChildren</BrowseFlag>
<Filter>*</Filter>
<StartingIndex>0</StartingIndex>
<RequestedCount>16</RequestedCount>
<SortCriteria></SortCriteria>
</u:Browse>

:4:src/mediaserver/contentdirectory.cxx:336::ContentDirectory::actBrowse:  ObjectID 0 BrowseFlag BrowseDirectChildren Filter * StartingIndex 0 RequestedCount 16 SortCriteria 
:4:libupnpp/device/device.cxx:319::UPNP_CONTROL_ACTION_REQUEST: Browse. Params: <?xml version="1.0"?>
<u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<ObjectID>0$spotify$</ObjectID>
<BrowseFlag>BrowseDirectChildren</BrowseFlag>
<Filter>*</Filter>
<StartingIndex>0</StartingIndex>
<RequestedCount>16</RequestedCount>
<SortCriteria></SortCriteria>
</u:Browse>

:4:src/mediaserver/contentdirectory.cxx:336::ContentDirectory::actBrowse:  ObjectID 0$spotify$ BrowseFlag BrowseDirectChildren Filter * StartingIndex 0 RequestedCount 16 SortCriteria 
:4:src/mediaserver/contentdirectory.cxx:59::ContentDirectory::pluginFactory: for spotify
:4:src/mediaserver/cdplugins/cmdtalk-fixed.cpp:85::CmdTalk::startCmd
:4:src/execmd-fixed.cpp:469::ExecCmd::startExec: (1|1) /usr/share/upmpdcli/cdplugins/spotify/spotify-app.py 
:2:src/sysvshm-fixed.cpp:105::ShmSeg::ShmSeg: shmget(3040): errno 2: No such file or directory
:5:src/mediaserver/cdplugins/plgwithslave.cxx:259::PlgWithSlave::maybeStartCmd: shm attach failed (probably ok)
:5:src/mediaserver/cdplugins/plgwithslave.cxx:500::ContentCache::get: not found spotify:0$spotify$
:4:src/mediaserver/cdplugins/cmdtalk-fixed.cpp:140::CmdTalk: Got empty line
:5:src/mediaserver/cdplugins/plgwithslave.cxx:346::PlgWithSlave::results: got 4 entries 
:5:src/mediaserver/cdplugins/plgwithslave.cxx:506::ContentCache::set: spotify:0$spotify$
:5:src/mediaserver/cdplugins/plgwithslave.cxx:436::searchCacheEntryToResult: filter  start 0 cnt 16 res.size 4
:4:libupnpp/device/device.cxx:319::UPNP_CONTROL_ACTION_REQUEST: Browse. Params: <?xml version="1.0"?>
<u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<ObjectID>0$spotify$/my_music</ObjectID>
<BrowseFlag>BrowseDirectChildren</BrowseFlag>
<Filter>*</Filter>
<StartingIndex>0</StartingIndex>
<RequestedCount>16</RequestedCount>
<SortCriteria></SortCriteria>
</u:Browse>

:4:src/mediaserver/contentdirectory.cxx:336::ContentDirectory::actBrowse:  ObjectID 0$spotify$/my_music BrowseFlag BrowseDirectChildren Filter * StartingIndex 0 RequestedCount 16 SortCriteria 
:5:src/mediaserver/cdplugins/plgwithslave.cxx:500::ContentCache::get: not found spotify:0$spotify$/my_music
:4:src/mediaserver/cdplugins/cmdtalk-fixed.cpp:140::CmdTalk: Got empty line
:5:src/mediaserver/cdplugins/plgwithslave.cxx:346::PlgWithSlave::results: got 5 entries 
:5:src/mediaserver/cdplugins/plgwithslave.cxx:506::ContentCache::set: spotify:0$spotify$/my_music
:5:src/mediaserver/cdplugins/plgwithslave.cxx:436::searchCacheEntryToResult: filter  start 0 cnt 16 res.size 5
:4:libupnpp/device/device.cxx:319::UPNP_CONTROL_ACTION_REQUEST: Browse. Params: <?xml version="1.0"?>
<u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<ObjectID>0$spotify$/favourite_albums</ObjectID>
<BrowseFlag>BrowseDirectChildren</BrowseFlag>
<Filter>*</Filter>
<StartingIndex>0</StartingIndex>
<RequestedCount>16</RequestedCount>
<SortCriteria></SortCriteria>
</u:Browse>

:4:src/mediaserver/contentdirectory.cxx:336::ContentDirectory::actBrowse:  ObjectID 0$spotify$/favourite_albums BrowseFlag BrowseDirectChildren Filter * StartingIndex 0 RequestedCount 16 SortCriteria 
:5:src/mediaserver/cdplugins/plgwithslave.cxx:500::ContentCache::get: not found spotify:0$spotify$/favourite_albums
:4:src/mediaserver/cdplugins/cmdtalk-fixed.cpp:140::CmdTalk: Got empty line
:5:src/mediaserver/cdplugins/plgwithslave.cxx:346::PlgWithSlave::results: got 20 entries 
:5:src/mediaserver/cdplugins/plgwithslave.cxx:506::ContentCache::set: spotify:0$spotify$/favourite_albums
:5:src/mediaserver/cdplugins/plgwithslave.cxx:436::searchCacheEntryToResult: filter  start 0 cnt 16 res.size 20
:4:libupnpp/device/device.cxx:319::UPNP_CONTROL_ACTION_REQUEST: Browse. Params: <?xml version="1.0"?>
<u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<ObjectID>0$spotify$/favourite_albums</ObjectID>
<BrowseFlag>BrowseDirectChildren</BrowseFlag>
<Filter>*</Filter>
<StartingIndex>16</StartingIndex>
<RequestedCount>500</RequestedCount>
<SortCriteria></SortCriteria>
</u:Browse>

:4:src/mediaserver/contentdirectory.cxx:336::ContentDirectory::actBrowse:  ObjectID 0$spotify$/favourite_albums BrowseFlag BrowseDirectChildren Filter * StartingIndex 16 RequestedCount 500 SortCriteria 
:5:src/mediaserver/cdplugins/plgwithslave.cxx:496::ContentCache::get: found spotify:0$spotify$/favourite_albums
:5:src/mediaserver/cdplugins/plgwithslave.cxx:436::searchCacheEntryToResult: filter  start 16 cnt 500 res.size 20
:4:libupnpp/device/device.cxx:319::UPNP_CONTROL_ACTION_REQUEST: Browse. Params: <?xml version="1.0"?>
<u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<ObjectID>0$spotify$/album/7IXPoqtzwpyi4bcy61wEiI</ObjectID>
<BrowseFlag>BrowseDirectChildren</BrowseFlag>
<Filter>*</Filter>
<StartingIndex>0</StartingIndex>
<RequestedCount>5000</RequestedCount>
<SortCriteria></SortCriteria>
</u:Browse>

:4:src/mediaserver/contentdirectory.cxx:336::ContentDirectory::actBrowse:  ObjectID 0$spotify$/album/7IXPoqtzwpyi4bcy61wEiI BrowseFlag BrowseDirectChildren Filter * StartingIndex 0 RequestedCount 5000 SortCriteria 
:5:src/mediaserver/cdplugins/plgwithslave.cxx:500::ContentCache::get: not found spotify:0$spotify$/album/7IXPoqtzwpyi4bcy61wEiI
:4:src/mediaserver/cdplugins/cmdtalk-fixed.cpp:140::CmdTalk: Got empty line
:5:src/mediaserver/cdplugins/plgwithslave.cxx:346::PlgWithSlave::results: got 12 entries 
:5:src/mediaserver/cdplugins/plgwithslave.cxx:506::ContentCache::set: spotify:0$spotify$/album/7IXPoqtzwpyi4bcy61wEiI
:5:src/mediaserver/cdplugins/plgwithslave.cxx:436::searchCacheEntryToResult: filter  start 0 cnt 5000 res.size 12
:4:src/mediaserver/cdplugins/plgwithslave.cxx:136::PlgWithSlave::translateurl: url /spotify/track
:5:src/mediaserver/cdplugins/plgwithslave.cxx:280::PlgWithSlave::get_media_url: /spotify/track?version=1&trackId=0FA4jFJtuQjPyZWiMOpCKG
:4:src/mediaserver/cdplugins/cmdtalk-fixed.cpp:140::CmdTalk: Got empty line
:4:src/mediaserver/cdplugins/plgwithslave.cxx:304::PlgWithSlave: media url [0FA4jFJtuQjPyZWiMOpCKG]
:4:src/mediaserver/cdplugins/spotify/spotiproxy.cpp:696::SpotiFetch::SpotiFetch:
:4:src/mediaserver/cdplugins/spotify/spotiproxy.cpp:478::getSpotiProxy: creating for user xxx