INFO [2011-09-08 16:35:37.299757 | upnp] Subscription request for urn:upnp-org:serviceId:AVTransport (uuid:GMediaRender-1_0-000-000-002)
INFO [2011-09-08 16:35:37.301843 | upnp] Initial variable sync: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<TransportStatus val="OK"></TransportStatus>
<NextAVTransportURI val=""></NextAVTransportURI>
<NextAVTransportURIMetaData val=""></NextAVTransportURIMetaData>
<CurrentTrackMetaData val=""></CurrentTrackMetaData>
<RelativeCounterPosition val="2147483647"></RelativeCounterPosition>
<A_ARG_TYPE_InstanceID val="0"></A_ARG_TYPE_InstanceID>
<A_ARG_TYPE_SeekTarget val=""></A_ARG_TYPE_SeekTarget>
<PlaybackStorageMedium val="UNKNOWN"></PlaybackStorageMedium>
<RelativeTimePosition val="0:00:00"></RelativeTimePosition>
<PossibleRecordStorageMedia val="NOT_IMPLEMENTED"></PossibleRecordStorageMedia>
<CurrentPlayMode val="NORMAL"></CurrentPlayMode>
<TransportPlaySpeed val="1"></TransportPlaySpeed>
<PossiblePlaybackStorageMedia val="NETWORK,UNKNOWN"></PossiblePlaybackStorageMedia>
<AbsoluteTimePosition val="NOT_IMPLEMENTED"></AbsoluteTimePosition>
<CurrentTrack val="0"></CurrentTrack>
<CurrentTrackURI val=""></CurrentTrackURI>
<CurrentTransportActions val="PLAY"></CurrentTransportActions>
<NumberOfTracks val="0"></NumberOfTracks>
<AVTransportURI val=""></AVTransportURI>
<AbsoluteCounterPosition val="2147483647"></AbsoluteCounterPosition>
<CurrentRecordQualityMode val="NOT_IMPLEMENTED"></CurrentRecordQualityMode>
<CurrentMediaDuration val=""></CurrentMediaDuration>
<A_ARG_TYPE_SeekMode val="TRACK_NR"></A_ARG_TYPE_SeekMode>
<AVTransportURIMetaData val=""></AVTransportURIMetaData>
<RecordStorageMedium val="NOT_IMPLEMENTED"></RecordStorageMedium>
<RecordMediumWriteStatus val="NOT_IMPLEMENTED"></RecordMediumWriteStatus>
<CurrentTrackDuration val="0:00:00"></CurrentTrackDuration>
<TransportState val="STOPPED"></TransportState>
<PossibleRecordQualityModes val="NOT_IMPLEMENTED"></PossibleRecordQualityModes>
</InstanceID>
</Event>
INFO [2011-09-08 16:35:37.637760 | upnp] Subscription request for urn:upnp-org:serviceId:RenderingControl (uuid:GMediaRender-1_0-000-000-002)
INFO [2011-09-08 16:35:37.639415 | upnp] Initial variable sync: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<GreenVideoGain val="0"></GreenVideoGain>
<BlueVideoBlackLevel val="0"></BlueVideoBlackLevel>
<VerticalKeystone val="0"></VerticalKeystone>
<GreenVideoBlackLevel val="0"></GreenVideoBlackLevel>
<Volume val="100"></Volume>
<Loudness val="0"></Loudness>
<A_ARG_TYPE_InstanceID val="0"></A_ARG_TYPE_InstanceID>
<RedVideoGain val="0"></RedVideoGain>
<ColorTemperature val="0"></ColorTemperature>
<Sharpness val="0"></Sharpness>
<A_ARG_TYPE_PresetName val=""></A_ARG_TYPE_PresetName>
<RedVideoBlackLevel val="0"></RedVideoBlackLevel>
<BlueVideoGain val="0"></BlueVideoGain>
<Mute val="0"></Mute>
<A_ARG_TYPE_Channel val=""></A_ARG_TYPE_Channel>
<HorizontalKeystone val="0"></HorizontalKeystone>
<VolumeDB val="0"></VolumeDB>
<PresetNameList val=""></PresetNameList>
<Contrast val="0"></Contrast>
<Brightness val="0"></Brightness>
</InstanceID>
</Event>
INFO [2011-09-08 16:35:48.435933 | webserver] Access /upnp/rendertransportSCPD.xml (text/xml) len=15697
INFO [2011-09-08 16:35:48.436896 | webserver] attempt to open /upnp/rendertransportSCPD.xml
INFO [2011-09-08 16:35:48.478433 | upnp] Subscription request for urn:upnp-org:serviceId:AVTransport (uuid:GMediaRender-1_0-000-000-002)
INFO [2011-09-08 16:35:48.489455 | upnp] Initial variable sync: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<TransportStatus val="OK"></TransportStatus>
<NextAVTransportURI val=""></NextAVTransportURI>
<NextAVTransportURIMetaData val=""></NextAVTransportURIMetaData>
<CurrentTrackMetaData val=""></CurrentTrackMetaData>
<RelativeCounterPosition val="2147483647"></RelativeCounterPosition>
<A_ARG_TYPE_InstanceID val="0"></A_ARG_TYPE_InstanceID>
<A_ARG_TYPE_SeekTarget val=""></A_ARG_TYPE_SeekTarget>
<PlaybackStorageMedium val="UNKNOWN"></PlaybackStorageMedium>
<RelativeTimePosition val="0:00:00"></RelativeTimePosition>
<PossibleRecordStorageMedia val="NOT_IMPLEMENTED"></PossibleRecordStorageMedia>
<CurrentPlayMode val="NORMAL"></CurrentPlayMode>
<TransportPlaySpeed val="1"></TransportPlaySpeed>
<PossiblePlaybackStorageMedia val="NETWORK,UNKNOWN"></PossiblePlaybackStorageMedia>
<AbsoluteTimePosition val="NOT_IMPLEMENTED"></AbsoluteTimePosition>
<CurrentTrack val="0"></CurrentTrack>
<CurrentTrackURI val=""></CurrentTrackURI>
<CurrentTransportActions val="PLAY"></CurrentTransportActions>
<NumberOfTracks val="0"></NumberOfTracks>
<AVTransportURI val=""></AVTransportURI>
<AbsoluteCounterPosition val="2147483647"></AbsoluteCounterPosition>
<CurrentRecordQualityMode val="NOT_IMPLEMENTED"></CurrentRecordQualityMode>
<CurrentMediaDuration val=""></CurrentMediaDuration>
<A_ARG_TYPE_SeekMode val="TRACK_NR"></A_ARG_TYPE_SeekMode>
<AVTransportURIMetaData val=""></AVTransportURIMetaData>
<RecordStorageMedium val="NOT_IMPLEMENTED"></RecordStorageMedium>
<RecordMediumWriteStatus val="NOT_IMPLEMENTED"></RecordMediumWriteStatus>
<CurrentTrackDuration val="0:00:00"></CurrentTrackDuration>
<TransportState val="STOPPED"></TransportState>
<PossibleRecordQualityModes val="NOT_IMPLEMENTED"></PossibleRecordQualityModes>
</InstanceID>
</Event>
INFO [2011-09-08 16:35:48.529205 | webserver] Access /upnp/renderconnmgrSCPD.xml (text/xml) len=4783
INFO [2011-09-08 16:35:48.530674 | webserver] attempt to open /upnp/renderconnmgrSCPD.xml
INFO [2011-09-08 16:35:48.601515 | upnp] Subscription request for urn:upnp-org:serviceId:ConnectionManager (uuid:GMediaRender-1_0-000-000-002)
INFO [2011-09-08 16:35:48.603138 | upnp] Initial variable sync: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<A_ARG_TYPE_ConnectionManager val="/"></A_ARG_TYPE_ConnectionManager>
<SinkProtocolInfo val="http-get:*:audio/x-iec958:*,http-get:*:audio/x-raw-float:*,http-get:*:audio/x-raw-int:*,http-get:*:application/x-icy:*,http-get:*:application/x-id3:*,http-get:*:audio/L16;rate=44100;channels=2:*,http-get:*:audio/x-scpls:*,http-get:*:audio/x-mpeg:*,http-get:*:audio/mpeg:*,http-get:*:application/x-annodex:*,http-get:*:application/x-kate:*,http-get:*:subtitle/x-kate:*,http-get:*:text/x-cmml:*,http-get:*:video/x-smoke:*,http-get:*:video/x-dirac:*,http-get:*:audio/x-celt:*,http-get:*:audio/x-speex:*,http-get:*:audio/x-flac:*,http-get:*:audio/x-vorbis:*,http-get:*:video/x-theora:*,http-get:*:application/x-ogm-audio:*,http-get:*:application/x-ogm-video:*,http-get:*:application/x-ogm-text:*,http-get:*:application/ogg:*,http-get:*:audio/*:*,http-get:*:application/x-ogg-avi:*"></SinkProtocolInfo>
<A_ARG_TYPE_ConnectionStatus val="Unknown"></A_ARG_TYPE_ConnectionStatus>
<A_ARG_TYPE_AVTransportID val="0"></A_ARG_TYPE_AVTransportID>
<A_ARG_TYPE_Direction val="Input"></A_ARG_TYPE_Direction>
<A_ARG_TYPE_RcsID val="0"></A_ARG_TYPE_RcsID>
<A_ARG_TYPE_ProtocolInfo val=":::"></A_ARG_TYPE_ProtocolInfo>
<A_ARG_TYPE_ConnectionID val="-1"></A_ARG_TYPE_ConnectionID>
<SourceProtocolInfo val=""></SourceProtocolInfo>
<CurrentConnectionIDs val="0"></CurrentConnectionIDs>
</InstanceID>
</Event>
INFO [2011-09-08 16:35:51.644685 | webserver] Access /upnp/rendercontrolSCPD.xml (text/xml) len=13317
INFO [2011-09-08 16:35:51.646089 | webserver] attempt to open /upnp/rendercontrolSCPD.xml
INFO [2011-09-08 16:35:51.708009 | upnp] Subscription request for urn:upnp-org:serviceId:RenderingControl (uuid:GMediaRender-1_0-000-000-002)
INFO [2011-09-08 16:35:51.709804 | upnp] Initial variable sync: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<GreenVideoGain val="0"></GreenVideoGain>
<BlueVideoBlackLevel val="0"></BlueVideoBlackLevel>
<VerticalKeystone val="0"></VerticalKeystone>
<GreenVideoBlackLevel val="0"></GreenVideoBlackLevel>
<Volume val="100"></Volume>
<Loudness val="0"></Loudness>
<A_ARG_TYPE_InstanceID val="0"></A_ARG_TYPE_InstanceID>
<RedVideoGain val="0"></RedVideoGain>
<ColorTemperature val="0"></ColorTemperature>
<Sharpness val="0"></Sharpness>
<A_ARG_TYPE_PresetName val=""></A_ARG_TYPE_PresetName>
<RedVideoBlackLevel val="0"></RedVideoBlackLevel>
<BlueVideoGain val="0"></BlueVideoGain>
<Mute val="0"></Mute>
<A_ARG_TYPE_Channel val=""></A_ARG_TYPE_Channel>
<HorizontalKeystone val="0"></HorizontalKeystone>
<VolumeDB val="0"></VolumeDB>
<PresetNameList val=""></PresetNameList>
<Contrast val="0"></Contrast>
<Brightness val="0"></Brightness>
</InstanceID>
</Event>
INFO [2011-09-08 16:35:52.165195 | control] cmd_obtain_variable: CurrentVolume for instance 0
INFO [2011-09-08 16:35:52.293083 | control] cmd_obtain_variable: CurrentMute for instance 0