[storaid]

Hi, Tiki

“As I have already said, a lot has changed in 5.6.0 to make it work.”

I see..

it’s my guess.

In the previous version ,is the total track length retrieved by the UI timebar obtained from the TrackDuration value in GetPositionInfo or the MediaDuration value in GetMediaInfo ?

Starting from v5.6.0, does it completely use DIDL metadata?

[storaid]

Hi, Tiki

I monitored the UPnP renderer (ifi-audio) using the UPnP development tool and found that the same issue exists in v5.6.0 as well.
It seems that the previous version didn’t fully rely on DIDL metadata.

Could something have changed in v5.6.0?

v5.5.3

Origin: OpenSource.UPnP.HTTPSession [53760061]
Time: 10/1/2024 9:19:27 PM

NOTIFY /48e75bf1-ed45-6dbf-cc31-cadaebbf02e7/urn:upnp-org:serviceId:AVTransport HTTP/1.1
NT: upnp:event
HOST: 10.1.1.160:51862
NTS: upnp:propchange
SID: uuid:65ae5b78-7ff7-11ef-a2b0-c7d69a21891a
SEQ: 22
CONTENT-TYPE: text/xml; charset="utf-8"
Content-Length: 3826

<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
<e:property>
<LastChange>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT_RCS">
<InstanceID val="0">
<CurrentTrackURI val="http://10.1.1.160:29400/TuneID/00013979-RFH491D8236.flac"/>
<TransportState val="PLAYING"/>
<CurrentMediaDuration val="0:01:09"/>
<CurrentTrackMetaData val="<?xml version=&quot;1.0&quot; encoding=&quot;utf-8&quot;?><DIDL-Lite xmlns:dc=&quot;http://purl.org/dc/elements/1.1/&quot; xmlns:upnp=&quot;urn:schemas-upnp-org:metadata-1-0/upnp/&quot; xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&quot; xmlns:dlna=&quot;urn:schemas-dlna-org:metadata-1-0/&quot;><item restricted=&quot;1&quot;><orig>mpd</orig><dc:title>絶望を司りし時</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>西木康智</dc:creator><upnp:artist>西木康智</upnp:artist><upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album><upnp:originalTrackNumber>03</upnp:originalTrackNumber><res duration=&quot;0:00:00&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; protocolInfo=&quot;http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000&quot;>http://10.1.1.160:29400/TuneID/00013979-RFH491D8236.flac</res></item></DIDL-Lite>"/>
<CurrentTransportActions val="Next,Previous,Pause,Stop,Seek"/>
<AVTransportURI val="http://10.1.1.160:29400/TuneID/00013979-RFH491D8236.flac"/>
<AbsoluteTimePosition val="0:00:01"/>
<CurrentTrackDuration val="0:01:09"/>
<RelativeTimePosition val="0:00:01"/>
<AVTransportURIMetaData val="<?xml version=&quot;1.0&quot; encoding=&quot;utf-8&quot;?><DIDL-Lite xmlns:dc=&quot;http://purl.org/dc/elements/1.1/&quot; xmlns:upnp=&quot;urn:schemas-upnp-org:metadata-1-0/upnp/&quot; xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&quot; xmlns:dlna=&quot;urn:schemas-dlna-org:metadata-1-0/&quot;><item restricted=&quot;1&quot;><orig>mpd</orig><dc:title>絶望を司りし時</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>西木康智</dc:creator><upnp:artist>西木康智</upnp:artist><upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album><upnp:originalTrackNumber>03</upnp:originalTrackNumber><res duration=&quot;0:00:00&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; protocolInfo=&quot;http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000&quot;>http://10.1.1.160:29400/TuneID/00013979-RFH491D8236.flac</res></item></DIDL-Lite>"/>
<NextAVTransportURI val="http://10.1.1.160:29400/TuneID/00013981-RFHE79A322F.flac"/>
<PlaybackStorageMedium val="HDD"/>
</InstanceID>
</Event>
</LastChange>
</e:property>
</e:propertyset>

[storaid]

possible solutions:

1. Increase Delay: Adding a delay between SetAVTransportURI and GetPositionInfo requests to ensure the Renderer’s internal state is fully updated.

2. Retry Mechanism: Implementing a retry mechanism to re-fetch metadata if the initial retrieval returns incorrect data.

maybe it wouldn’t accidentally retrieve old incorrect data from the buffer/cache.

[storaid]

Hi, Tiki

Here is ifi-audio response

Thank you for getting in touch regarding your ZEN Stream. I don’t know exactly why this would occur, unfortunately.  It could be that the metadata containing the current position is temporarily stored in the buffer/cache when it is called for and other times it is not stored at all, then when it is called upon it retrieves the old info which is wrong. I do not know, unfortunately, however, your testing is clear and you have given us some great information so that I can pass this straight on to our development team.

it seems the metadata inconsistency from the upnp renderer might be due to the metadata being temporarily stored in the buffer/cache, leading to retrieval of old or incorrect information during GetPositionInfo calls.

[storaid]

Here is the GPT4o reply I ask..

When the SetAVTransportURI command is sent, the Renderer may need some time to complete the internal state updates. If the GetPositionInfo request is made before this process is fully completed, the DIDL data retrieved may be incomplete or incorrect.

Adding a delay between sending the SetAVTransportURI command and making the GetPositionInfo request might help. For example, adding a delay of about 500 milliseconds to 1 second to ensure that the state change is fully processed.

 

[storaid]

Hi, Tiki

I have re-checked the UPnP log step by step, and I think the difference is here.

[v5.6.0]-“void” issue

2024/09/30 19:32:28,111: UL17933: T03810: UPnP_P: [SOAP Request]: SOAP request: [Play ] finished.
2024/09/30 19:32:28,111: UL17935: T03810: UPnP_P: PlayImpl: Waiting for state change in 10000 ms.
2024/09/30 19:32:28,631: UL17937: T03810: UPnP_P: PlayImpl: Device event was not detected. SyncActive turns to on.
2024/09/30 19:32:28,632: UL17939: T03810: UPnP_P: PlayImpl: QuerySOAP: Getting volume from device.
2024/09/30 19:32:28,632: UL17941: T03810: UPnP_P: [SOAP Request]: SOAP request: [GetVolume ] to: 10.1.1.96 [http://10.1.1.96:49152/ctl/RenderingControl]
2024/09/30 19:32:28,637: UL17943: T03810: UPnP_P: [SOAP Request]: SOAP request: [GetVolume ] finished.
2024/09/30 19:32:28,637: UL17945: T03810: UPnP_P: PlayImpl: QuerySOAP: -> Volume: 100
2024/09/30 19:32:28,637: UL17947: T03810: UPnP_P: PlayImpl: QuerySOAP: Getting TransportInfo from device.
2024/09/30 19:32:28,637: UL17949: T03810: UPnP_P: [SOAP Request]: SOAP request: [GetTransportInfo ] to: 10.1.1.96 [http://10.1.1.96:49152/ctl/AVTransport]
2024/09/30 19:32:28,666: UL17951: T03810: UPnP_P: [SOAP Request]: SOAP request: [GetTransportInfo ] finished.
2024/09/30 19:32:28,666: UL17953: T03810: UPnP_P: PlayImpl: QuerySOAP: -> TransportStatus: OK
2024/09/30 19:32:28,666: UL17955: T03810: UPnP_P: PlayImpl: QuerySOAP: -> TransportState: PLAYING
2024/09/30 19:32:28,666: UL17957: T03810: UPnP_P: [Proc:TrState ] <POL>: PlayerUPnP UPState changed [STOPPED ] to [PLAYING ]
2024/09/30 19:32:28,666: UL17959: T03810: UPnP_P: [Proc:TrState ] <POL>: PlayerUPnP PLStatus changed [PlayStatus::Stop ] to [PlayStatus::Playing ]
2024/09/30 19:32:28,666: UL17961: T03810: UPnP_P: [Proc:TrState ] <POL>: PlayerStatus: Playing
2024/09/30 19:32:28,666: UL17963: T03810: UPnP_P: [Proc:TrState ] <POL>: PLTTrace assigned: 05
2024/09/30 19:32:28,666: UL17965: T03810: UPnP_P: [Proc:TrState ] <POL>: Post PlayerNotify: 000005 [Start]
2024/09/30 19:32:28,666: UL17967: T03810: UPnP_P: [Proc:TrState ] <POL>: Post PlayerNotify: 000005 [Started]
2024/09/30 19:32:28,666: UL17969: T03810: UPnP_P: [Proc:TrState ] <POL>: State : PLAYING
2024/09/30 19:32:28,666: UL17971: T03810: UPnP_P: PlayImpl: QuerySOAP: Getting PositionInfo from device.
2024/09/30 19:32:28,666: UL17973: T03810: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] to: 10.1.1.96 [http://10.1.1.96:49152/ctl/AVTransport]
2024/09/30 19:32:28,697: UL17975: T03810: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] finished.
2024/09/30 19:32:28,697: UL17977: T03810: UPnP_P: PlayImpl: QuerySOAP: -> RelTime: 0:00:00
2024/09/30 19:32:28,697: UL17979: T03810: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:00 -> 0.0 / 0.0 sec
2024/09/30 19:32:28,697: UL17981: T03810: UPnP_P: PlayImpl: QuerySOAP: -> TrackURI: http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac
2024/09/30 19:32:28,697: UL17983: T03810: UPnP_P: PlayImpl: QuerySOAP: -> TrackMetaData: <?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/el (...)
2024/09/30 19:32:28,697: UL17985: T03810: UPnP_P: [Proc:UriAndMeta] <POL>: DIDL/res: [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/30 19:32:28,697: UL17987: T03810: UPnP_P: [Proc:UriAndMeta] <POL>: - duration:[0:00:00] nrAudioChannels:[2] sampleFrequency:[44100] protocolInfo:[http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000]
2024/09/30 19:32:28,697: UL17989: T03810: UPnP_P: [Proc:UriAndMeta] <POL>: New URI found: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/30 19:32:28,698: UL17991: T03810: UPnP_P: [Proc:UriAndMeta] <POL>: UseExDecd mark is pending.
2024/09/30 19:32:28,698: UL17993: T03810: UPnP_P: [Proc:UriAndMeta] <POL>: Track: [辺獄の深淵]
2024/09/30 19:32:28,698: UL17995: T03810: UPnP_P: [Proc:UriAndMeta] <POL>: UseExDecd mark not found in URI: [DecodeEx:Normal] (FILE)
2024/09/30 19:32:28,698: UL17997: T049e0: UPnP_P: [UPP_MetaUPThread]: MyURI: Yes [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/30 19:32:28,698: UL17999: T049e0: UPnP_P: [ResolveTune]: URI: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/30 19:32:28,698: UL18001: T049e0: UPnP_P: [ResolveTune]: This is mine.

[v5.6.0]-normal

2024/09/30 19:34:16,988: UL18829: T03810: UPnP_P: [SOAP Request]: SOAP request: [Play ] finished.
2024/09/30 19:34:16,988: UL18831: T03810: UPnP_P: PlayImpl: Waiting for state change in 10000 ms.
2024/09/30 19:34:17,374: UL18833: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: DIDL/res: [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/30 19:34:17,374: UL18835: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: - protocolInfo:[http-get:*:audio/flac:*] duration:[0:03:25.822] bitsPerSample:[16]
2024/09/30 19:34:17,374: UL18837: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: - bitrate:[881753] sampleFrequency:[44100] nrAudioChannels:[2] DecodeEx:[Normal]
2024/09/30 19:34:17,374: UL18839: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: Metadata was updated on URI: [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/30 19:34:17,374: UL18841: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: -- ReceivedMetadata -- {
2024/09/30 19:34:17,374: UL18843: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
2024/09/30 19:34:17,374: UL18845: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <item>
2024/09/30 19:34:17,374: UL18847: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <res protocolInfo="http-get:*:audio/flac:*" duration="0:03:25.822" bitrate="881753" bitsPerSample="16" nrAudioChannels="2" sampleFrequency="44100" DecodeEx="Normal">http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac</res>
2024/09/30 19:34:17,374: UL18849: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <dc:title>辺獄の深淵</dc:title>
2024/09/30 19:34:17,374: UL18851: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <dc:date>2023</dc:date>
2024/09/30 19:34:17,374: UL18853: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <dc:creator>西木康智</dc:creator>
2024/09/30 19:34:17,374: UL18855: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <dc:publisher>SQUARE ENIX MUSIC</dc:publisher>
2024/09/30 19:34:17,374: UL18857: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
2024/09/30 19:34:17,374: UL18859: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <upnp:genre>Soundtrack</upnp:genre>
2024/09/30 19:34:17,374: UL18861: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <upnp:artist>西木康智</upnp:artist>
2024/09/30 19:34:17,374: UL18863: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <upnp:originalTrackNumber>02</upnp:originalTrackNumber>
2024/09/30 19:34:17,374: UL18865: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <upnp:albumArtURI>http://10.1.1.160:29400/Image/05625.a5a33f89-dc3e-4c1d-9a489069227fea69.jpg</upnp:albumArtURI>
2024/09/30 19:34:17,374: UL18867: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: <upnp:class>object.item.audioItem.musicTrack</upnp:class>
2024/09/30 19:34:17,374: UL18869: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: </item>
2024/09/30 19:34:17,374: UL18871: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: </DIDL-Lite>
2024/09/30 19:34:17,374: UL18873: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: } -- ReceivedMetadata --
2024/09/30 19:34:17,374: UL18875: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New tag: [X_CONTEXT_TYPE ]: [Music]
2024/09/30 19:34:17,377: UL18877: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New tag: [X_ENCODER_TYPE ]: [FLAC]
2024/09/30 19:34:17,377: UL18879: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New tag: [GENRE ]: [Soundtrack]
2024/09/30 19:34:17,377: UL18881: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New tag: [X_BITS ]: [16]
2024/09/30 19:34:17,377: UL18883: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: Upd tag: [X_DURATION ]:
2024/09/30 19:34:17,377: UL18885: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: Old: [0.000]
2024/09/30 19:34:17,377: UL18887: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New: [205.822]
2024/09/30 19:34:17,377: UL18889: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New tag: [DATE ]: [2023]
2024/09/30 19:34:17,377: UL18891: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New tag: [X_BITRATE ]: [881753]
2024/09/30 19:34:17,377: UL18893: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New tag: [ORGANIZATION ]: [SQUARE ENIX MUSIC]
2024/09/30 19:34:17,377: UL18895: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: New tag: [X_IMAGEFILE ]: [http://10.1.1.160:29400/Image/ (...)]
2024/09/30 19:34:17,377: UL18897: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: Updated metadata found.
2024/09/30 19:34:17,377: UL18899: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: UseExDecd mark is pending.
2024/09/30 19:34:17,378: UL18901: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: Track: [辺獄の深淵]
2024/09/30 19:34:17,378: UL18903: T04978: UPnP_P: [Proc:UriAndMeta] <POL>: UseExDecd mark not found in URI: [DecodeEx:Normal] (FILE)
2024/09/30 19:34:17,378: UL18905: T049e0: UPnP_P: [UPP_MetaUPThread]: MyURI: Yes [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/30 19:34:17,378: UL18907: T049e0: UPnP_P: [ResolveTune]: URI: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/30 19:34:17,378: UL18909: T049e0: UPnP_P: [ResolveTune]: This is mine.

[v5.5.3]- no details

2024/09/30 19:35:35,105: UL01748: T05384: UPnP_P: [SOAP Request]: SOAP request: [Play ] to: 10.1.1.96 [http://10.1.1.96:49152/ctl/AVTransport]
2024/09/30 19:35:35,136: UL01749: T044d4: HTTPServer: [10.1.1.96 :41926]: Socket accepted.
2024/09/30 19:35:35,137: UL01750: T08684: HTTPServer: [10.1.1.96 :41926]: Processing [GET] /TuneID/00013977-RFHF825DADC.flac
2024/09/30 19:35:35,137: UL01751: T08684: HTTPServer: [10.1.1.96 :41926]: Tune: 00013977-RFHF825DADC.flac ->
2024/09/30 19:35:35,137: UL01752: T08684: HTTPServer: [10.1.1.96 :41926]: \\10.1.1.127\music\OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2\DISC 3\Track_02.flac
2024/09/30 19:35:35,137: UL01754: T08684: HTTPServer: [10.1.1.96 :41926]: Begin monitoring playback.
2024/09/30 19:35:35,137: UL01756: T06f64: UPnP_P: Post PlayerNotify: 7005 [SpecUpdate]
2024/09/30 19:35:35,138: UL01758: T06f64: UPnP_P: Proc PlayerNotify: 7005 [SpecUpdate]
2024/09/30 19:35:35,146: UL01760: T05384: UPnP_P: [SOAP Request]: SOAP request: [Play ] finished.
2024/09/30 19:35:35,146: UL01762: T05384: UPnP_P: PlayImpl: Post PlayerNotify: 7006 [SpecUpdate]
2024/09/30 19:35:35,146: UL01764: T05384: UPnP_P: PlayImpl: Player is UnderControl.
2024/09/30 19:35:35,146: UL01766: T05384: UPnP_P: PlayImpl: Post PlayerNotify: 7007 [SpecUpdate]
2024/09/30 19:35:35,146: UL01768: T05384: UPnP_P: PlayImpl: Waiting for state change in 10000 ms.
2024/09/30 19:35:35,147: UL01770: T06f64: UPnP_P: Proc PlayerNotify: 7006 [SpecUpdate]
2024/09/30 19:35:35,154: UL01772: T06f64: UPnP_P: Proc PlayerNotify: 7007 [SpecUpdate]
2024/09/30 19:35:35,167: UL01773: T04510: --> 10.1.1.96 :29500 EVENT /SR_AVTransport.notify: uuid:15abc318-7f20-11ef-8099-935d6bcf76b6
2024/09/30 19:35:35,171: UL01775: T04510: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/09/30 19:35:35,171: UL01777: T04510: Subscriber: - [AVTransport]: Device notification: Node found: [TransportState ] : [PLAYING]
2024/09/30 19:35:35,171: UL01779: T04510: UPnP_P: [Proc:TrState ] <EVT>: PlayerUPnP UPState changed [STOPPED ] to [PLAYING ]
2024/09/30 19:35:35,171: UL01781: T04510: UPnP_P: [Proc:TrState ] <EVT>: PlayerUPnP PLStatus changed [PlayStatus::Stop ] to [PlayStatus::Playing ]
2024/09/30 19:35:35,171: UL01783: T04510: UPnP_P: [Proc:TrState ] <EVT>: PlayerStatus: Playing
2024/09/30 19:35:35,171: UL01785: T04510: UPnP_P: [Proc:TrState ] <EVT>: PLTTrace assigned: 01
2024/09/30 19:35:35,171: UL01787: T04510: UPnP_P: [Proc:TrState ] <EVT>: Post PlayerNotify: 0001 [Start]
2024/09/30 19:35:35,171: UL01789: T04510: UPnP_P: [Proc:TrState ] <EVT>: Post PlayerNotify: 0001 [Started]
2024/09/30 19:35:35,171: UL01791: T04510: UPnP_P: [Proc:TrState ] <EVT>: State : PLAYING
2024/09/30 19:35:35,171: UL01793: T04510: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackMetaData ] : [<DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http:/ (...)]
2024/09/30 19:35:35,176: UL01795: T05384: UPnP_P: PlayImpl: State change detected.
2024/09/30 19:35:35,176: UL01797: T05384: UPnP_P: PlayImpl: Waiting for state change has done in 30 ms.
2024/09/30 19:35:35,176: UL01799: T05384: UPnP_P: PlayImpl: Waiting for state change before seek in 10000 ms.

From checking these UPnP logs, I think the problem is still likely that the metadata obtained through SUBSCRIBE Event or GetPositionInfo is inconsistent with the original metadata sent during the SetAVTransportURI call.

Wireshark can at best capture and analyze the packets, but I don’t know exactly what behaviors occur during these processes.

However, there’s only so much I can do.

[storaid]

Hi,

I have reached out to IFI Audio support to inquire about the issues with the UPnP about returning inconsistent DIDL metadata.

And, waiting for their response.

But don’t expect their efficiency(SLOW).

I’m continuing my investigation to understand what might be causing the inconsistent DIDL metadata. Orz

Thanks

[storaid]

in v.5.6.0, the following is a part of the uPnP process flow with “void” issue:

tool: wireshark

CASE:

[SetAVTransportURI]

<?xml version="1.0" encoding="UTF-8"?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
<s:Body>
<u:SetAVTransportURI xmlns:u="urn:schemas-upnp-org:service:AVTransport:1">
<InstanceID>0</InstanceID>
<CurrentURI>http://10.1.1.160:29400/TuneID/00013987-RFH97B535E9.flac</CurrentURI>
<CurrentURIMetaData>
<DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"
xmlns:dc="http://purl.org/dc/elements/1.1/"
xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/"
xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
<item>
<res protocolInfo="http-get:*:audio/flac:*" duration="0:04:28.516" bitrate="642211"
bitsPerSample="16" nrAudioChannels="2" sampleFrequency="44100"
DecodeEx="Normal">http://10.1.1.160:29400/TuneID/00013987-RFH97B535E9.flac</res>
<dc:title>光の覇</dc:title>
<dc:date>2023</dc:date>
<dc:creator>西木康智</dc:creator>
<dc:publisher>SQUARE ENIX MUSIC</dc:publisher>
<upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
<upnp:genre>Soundtrack</upnp:genre>
<upnp:artist>西木康智</upnp:artist>
<upnp:originalTrackNumber>06</upnp:originalTrackNumber>
<upnp:albumArtURI>http://10.1.1.160:29400/Image/05651.5b8ee7b5-01a3-4416-97076a1fe6cd6134.jpg</upnp:albumArtURI>
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
</item>
</DIDL-Lite>
</CurrentURIMetaData>
</u:SetAVTransportURI>
</s:Body>
</s:Envelope>

|
[RenderingControl:1#GetVolume]
|
[AVTransport:1#GetTransportInfo]
|
[AVTransport:1#Play]
|
(GET /TuneID/00013987-RFH97B535E9.flac HTTP/1.1\r\n)
|
[AVTransport:1#GetPositionInfo]
|<return>
(HTTP/1.1 200 OK)
incorrectly received data

<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:GetPositionInfoResponse xmlns:u="urn:schemas-upnp-org:service:AVTransport:1">
<Track>1</Track>
<TrackDuration>0:00:00</TrackDuration>
<TrackMetaData>
<?xml version="1.0" encoding="utf-8"?>
<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/"
xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"
xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/"
xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/">
<item restricted="1">
<strong><orig>mpd</orig></strong>
<dc:title>光の覇</dc:title>
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
<dc:creator>西木康智</dc:creator>
<upnp:artist>西木康智</upnp:artist>
<upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
<upnp:originalTrackNumber>06</upnp:originalTrackNumber>
<res duration="<strong>0:00:00</strong>" sampleFrequency="44100" nrAudioChannels="2"
protocolInfo="<strong>http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000</strong>">
[audio src="http://10.1.1.160:29400/TuneID/00013987-RFH97B535E9.flac" /]
</res>
</item>
</DIDL-Lite>
</TrackMetaData>
<TrackURI>http://10.1.1.160:29400/TuneID/00013987-RFH97B535E9.flac</TrackURI>
<RelTime>0:00:00</RelTime>
<AbsTime>0:00:00</AbsTime>
<RelCount>0</RelCount>
<AbsCount>0</AbsCount>
</u:GetPositionInfoResponse>
</s:Body>
</s:Envelope>

The normal received data format is here if no “void” issue occurs.

<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:GetPositionInfoResponse xmlns:u="urn:schemas-upnp-org:service:AVTransport:1">
<Track>1</Track>
<TrackDuration>0:04:29</TrackDuration>
<TrackMetaData>
<DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
<item>
<res<strong> protocolInfo="http-get:*:audio/flac:*" duration="0:04:28.516"</strong> bitrate="642211" bitsPerSample="16" nrAudioChannels="2" sampleFrequency="44100" DecodeEx="Normal">http://10.1.1.160:29400/TuneID/00013987-RFH97B535E9.flac</res>
<dc:title>守る者の誓い</dc:title>
<dc:date>2023</dc:date>
<dc:creator>西木康智</dc:creator>
<dc:publisher>SQUARE ENIX MUSIC</dc:publisher>
<upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
<upnp:genre>Soundtrack</upnp:genre>
<upnp:artist>西木康智</upnp:artist>
<upnp:originalTrackNumber>06</upnp:originalTrackNumber>
<upnp:albumArtURI>http://10.1.1.160:29400/Image/05651.5b8ee7b5-01a3-4416-97076a1fe6cd6134.jpg</upnp:albumArtURI>
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
</item>
</DIDL-Lite>
</TrackMetaData>
<TrackURI>http://10.1.1.160:29400/TuneID/00013987-RFH97B535E9.flac</TrackURI>
<RelTime>0:00:05</RelTime>
<AbsTime>0:00:05</AbsTime>
<RelCount>0</RelCount>
<AbsCount>0</AbsCount>
</u:GetPositionInfoResponse>
</s:Body>
</s:Envelope>

[storaid]

Hi,

“I’m sorry, but I don’t remeber all. But as I said above, there are many changes. What I am interested in is whether or not there is a problem with the current TuneBrowser operation.”

It’s possible.

How did you get this information??

call GetPositionInfo HTTP API?

2024/09/29 23:44:17,286: UL07322: T06470: Subscriber: [Event]: Received UPnP EVENT: [AVTransport]
2024/09/29 23:44:17,286: UL07324: T06470: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackURI ] : [http://10.1.1.160:29400/TuneID/00005330-RFH2FFDC25A.flac_WPRX?Subsong=2&DecodeEx (...)]
2024/09/29 23:44:17,286: UL07326: T06470: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackMetaData ] : [<?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/el (...)]
2024/09/29 23:44:17,286: UL07328: T06470: UPnP_P: [Proc:UriAndMeta] <EVT>: DIDL/res: [http://10.1.1.160:29400/TuneID/00005330-RFH2FFDC25A.flac_WPRX?Subsong=2&DecodeEx=WavProxy]
2024/09/29 23:44:17,286: UL07330: T06470: UPnP_P: [Proc:UriAndMeta] <EVT>: - duration:[0:00:00] nrAudioChannels:[2] sampleFrequency:[44100] protocolInfo:[http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000]
2024/09/29 23:44:17,286: UL07332: T06470: UPnP_P: [Proc:UriAndMeta] <EVT>: New URI found: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00005330-RFH2FFDC25A.flac_WPRX?Subsong=2&DecodeEx=WavProxy]
2024/09/29 23:44:17,286: UL07334: T06470: UPnP_P: [Proc:UriAndMeta] <EVT>: UseExDecd mark is pending.
2024/09/29 23:44:17,286: UL07336: T06470: UPnP_P: [Proc:UriAndMeta] <EVT>: Track: [Insonnia]
2024/09/29 23:44:17,287: UL07338: T06470: UPnP_P: [Proc:UriAndMeta] <EVT>: UseExDecd mark found in URI: [DecodeEx:WavProxy] (WPRX)
2024/09/29 23:44:17,287: UL07340: T06470: UPnP_P: [Proc:UriAndMeta] <EVT>: Post PlayerNotify: 444478 [SpecUpdate]

[storaid]

Hi,

“In this case, it seems that your device is recognized flac as “audio/mpeg”. Wouldn’t this be a hint?”

This issue seems to be related to the entire UPnP processing flow, which makes it difficult to pinpoint the problem.

The following is normal metadata from NOTIFY(when the renderer is getting good work):

Track: 1
TrackDuration: 0:05:15
TrackMetaData: <DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
<item>
<res protocolInfo="http-get:*:audio/wav:*" duration="0:05:14.547" bitsPerSample="24" nrAudioChannels="2" sampleFrequency="44100" DecodeEx="WavProxy">http://10.1.1.160:29400/TuneID/00005329-RFH8454B1D9.flac_WPRX?Subsong=1&amp;DecodeEx=WavProxy</res>
<dc:title>KARMA</dc:title>
<dc:date>2011</dc:date>
<dc:creator>KOKIA</dc:creator>
<upnp:album>KOKIA - pieces</upnp:album>
<upnp:genre>REM DISCID B10E380D_</upnp:genre>
<upnp:artist>KOKIA</upnp:artist>
<upnp:originalTrackNumber>01</upnp:originalTrackNumber>
<upnp:albumArtURI>http://10.1.1.160:29400/Image/02401.b317751f-2eea-47ca-8d3a3a1bdf66c788.jpg</upnp:albumArtURI>
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
</item>
</DIDL-Lite>
TrackURI: http://10.1.1.160:29400/TuneID/00005329-RFH8454B1D9.flac_WPRX?Subsong=1&amp;DecodeEx=WavProxy
RelTime: 0:01:17
AbsTime: 0:01:17
RelCount: 0
AbsCount: 0

Did you make any changes to the whole UPnP process flow?

I mean the communication between the Server and the Renderer.

[storaid]

Hello, Tiki,
It’s a bit difficult to check this issue.
From my packet capture in Wireshark, I found that if the NOTIFY packets are sent later than expected, it might cause the MetaData retrieved by GetPositionInfo to be incorrect.
Additionally, the content of the NOTIFY itself is also incorrect, as shown below:

<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0">
<e:property>
<Metadata><?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/"><item restricted="1"><orig>mpd</orig><dc:title>Road to glory ï½for Dragon Nest White Versionï½</dc:title><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:creator>KOKIA</dc:creator><upnp:artist>KOKIA</upnp:artist><upnp:album>KOKIA - pieces</upnp:album><upnp:originalTrackNumber>10</upnp:originalTrackNumber><res duration="0:00:00" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000">http://10.1.1.160:29400/TuneID/00005338-RFH5959803E.flac_WPRX?Subsong=10&amp;DecodeEx=WavProxy</res></item></DIDL-Lite></Metadata>
</e:property>
<e:property>
<Uri>http://10.1.1.160:29400/TuneID/00005338-RFH5959803E.flac_WPRX?Subsong=10&DecodeEx=WavProxy</Uri>
</e:property>
<e:property>
<TrackCount>91</TrackCount>
</e:property>
</e:propertyset>

However, I don’t see this issue in v5.5.3.
It’s hard to determine the source of the issue! Orz

[storaid]

Hi, Tiki

“the answer to the first question below is that duration=”0:00:00″.I do not know why the device does so.”

Yeap, that’s a problem.

I’m not sure what you changed something behavior for this in v5.6.0.

but in the previous version, there is no issue such as this by checking packets using the sniffer tool.

the renderer device is working well.

in v5.6.0, there may be something wrong I don’t know.

another error(will trigger the renderer crash)

2024/09/29 17:00:34,882.800: T09704: UPnP_P: PlayImpl: RelTimeEvent: No
2024/09/29 17:00:34,882.810: T09704: UPnP_P: PlayImpl: SyncActive: No
2024/09/29 17:00:34,882.820: T09704: UPnP_P: PlayImpl: Post [Play]
2024/09/29 17:00:34,882.832: T09704: UPnP_P: PlayImpl: CommandSOAP: AVTransport:Play
2024/09/29 17:00:34,883.278: T09704: UPnP_P: [SOAP Request]: SOAP request: [Play ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/AVTransport]
2024/09/29 17:00:34,916.126: T04818: UPnP_P: Post PlayerNotify: 444416 [SpecUpdate]
2024/09/29 17:00:34,916.621: T04818: UPnP_P: Proc PlayerNotify: 444416 [SpecUpdate]
2024/09/29 17:00:34,926.750: T09704: UPnP_P: [SOAP Request]: SOAP request: [Play ] finished.
2024/09/29 17:00:34,926.855: T09704: UPnP_P: PlayImpl: Waiting for state change in 10000 ms.
2024/09/29 17:00:35,446.687: T09704: UPnP_P: PlayImpl: Device event was not detected. SyncActive turns to on.
2024/09/29 17:00:35,446.740: T09704: UPnP_P: PlayImpl: QuerySOAP: Getting volume from device.
2024/09/29 17:00:35,447.074: T09704: UPnP_P: [SOAP Request]: SOAP request: [GetVolume ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/RenderingControl]
2024/09/29 17:00:35,450.802: T09704: UPnP_P: [SOAP Request]: SOAP request: [GetVolume ] finished.
2024/09/29 17:00:35,450.973: T09704: UPnP_P: PlayImpl: QuerySOAP: -> Volume: 100
2024/09/29 17:00:35,451.072: T09704: UPnP_P: PlayImpl: QuerySOAP: Getting TransportInfo from device.
2024/09/29 17:00:35,451.935: T09704: UPnP_P: [SOAP Request]: SOAP request: [GetTransportInfo ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/AVTransport]
2024/09/29 17:00:35,477.191: T09704: UPnP_P: [SOAP Request]: SOAP request: [GetTransportInfo ] finished.
2024/09/29 17:00:35,477.310: T09704: UPnP_P: PlayImpl: QuerySOAP: -> TransportStatus: OK
2024/09/29 17:00:35,477.323: T09704: UPnP_P: PlayImpl: QuerySOAP: -> TransportState: PLAYING
2024/09/29 17:00:35,477.335: T09704: UPnP_P: [Proc:TrState ] <POL>: PlayerUPnP UPState changed [STOPPED ] to [PLAYING ]
2024/09/29 17:00:35,477.350: T09704: UPnP_P: [Proc:TrState ] <POL>: PlayerUPnP PLStatus changed [PlayStatus::Stop ] to [PlayStatus::Playing ]
2024/09/29 17:00:35,477.365: T09704: UPnP_P: [Proc:TrState ] <POL>: PlayerStatus: Playing
2024/09/29 17:00:35,477.378: T09704: UPnP_P: [Proc:TrState ] <POL>: PLTTrace assigned: 02
2024/09/29 17:00:35,477.390: T09704: UPnP_P: [Proc:TrState ] <POL>: Post PlayerNotify: 000002 [Start]
2024/09/29 17:00:35,477.400: T09704: UPnP_P: [Proc:TrState ] <POL>: Post PlayerNotify: 000002 [Started]
2024/09/29 17:00:35,477.407: T09704: UPnP_P: [Proc:TrState ] <POL>: State : PLAYING
2024/09/29 17:00:35,477.421: T09704: UPnP_P: PlayImpl: QuerySOAP: Getting PositionInfo from device.
2024/09/29 17:00:35,477.773: T09704: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/AVTransport]
2024/09/29 17:00:35,479.406: T04818: UPnP_P: Proc PlayerNotify: 000002 [Start]
2024/09/29 17:00:35,486.609: T04818: UPnP_P: Proc PlayerNotify: 000002 [Started]
2024/09/29 17:00:35,504.350: T09704: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] finished.
2024/09/29 17:00:35,504.463: T09704: UPnP_P: PlayImpl: QuerySOAP: -> RelTime: 0:00:00
2024/09/29 17:00:35,504.482: T09704: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:00 -> 0.0 / 217.5 sec
2024/09/29 17:00:35,504.489: T09704: UPnP_P: PlayImpl: QuerySOAP: -> TrackURI: http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac
2024/09/29 17:00:35,504.496: T09704: UPnP_P: PlayImpl: QuerySOAP: -> TrackMetaData: <?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/el (...)
2024/09/29 17:00:35,504.706: T09704: UPnP_P: [Proc:UriAndMeta] <POL>: DIDL/res: [http://10.1.1.160:29400/TuneID/00005330-RFH2FFDC25A.flac_WPRX?Subsong=2&DecodeEx=WavProxy]
2024/09/29 17:00:35,504.712: T09704: UPnP_P: [Proc:UriAndMeta] <POL>: - duration:[0:00:00] nrAudioChannels:[2] sampleFrequency:[44100] protocolInfo:[http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000]
2024/09/29 17:00:35,504.732: T09704: UPnP_P: [Proc:UriAndMeta] <POL>: New URI found: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/29 17:00:35,504.736: T09704: UPnP_P: [Proc:UriAndMeta] <POL>: UseExDecd mark is pending.
2024/09/29 17:00:35,504.741: T09704: UPnP_P: [Proc:UriAndMeta] <POL>: Track: [Insonnia]
2024/09/29 17:00:35,505.126: T09704: UPnP_P: [Proc:UriAndMeta] <POL>: UseExDecd mark not found in URI: [DecodeEx:Normal] (FILE)
2024/09/29 17:00:35,505.157: T09f00: UPnP_P: [UPP_MetaUPThread]: MyURI: Yes [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/29 17:00:35,505.326: T09f00: UPnP_P: [ResolveTune]: URI: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/29 17:00:35,505.363: T09f00: UPnP_P: [ResolveTune]: This is mine.
2024/09/29 17:00:35,505.373: T09f00: UPnP_P: [ResolveTune]: Mine:Yes Managed:Yes Recorded:Yes Local file: [\\10.1.1.127\music\OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2\DISC 3\Track_02.flac]
2024/09/29 17:00:35,565.521: T09704: UPnP_P: PlayImpl: State change detected.
2024/09/29 17:00:35,565.541: T09704: UPnP_P: PlayImpl: Waiting for state change has done in 639 ms.
2024/09/29 17:00:35,565.553: T09704: UPnP_P: PlayImpl: No seek is required.
2024/09/29 17:00:35,565.561: T09704: UPnP_P: PlayImpl: Setting seek value to void.
2024/09/29 17:00:35,565.568: T09704: UPnP_P: PlayImpl: Preparing next tune by SetNextAVTransportURI.
2024/09/29 17:00:35,565.579: T09704: UPnP_P: PlayImpl: Next tune sequence position 45 -> 46.
2024/09/29 17:00:35,565.591: T09704: UPnP_P: PlayImpl: [IFi] is supporting SetNextAVTransportURI.
2024/09/29 17:00:35,565.610: T09704: UPnP_P: PlayImpl: Procedure has been done.
2024/09/29 17:00:35,565.633: T0222c: UPnP_P: [UPP_TransCThread]: Doing SetAVTransportURI: AVTType::NextTune
2024/09/29 17:00:35,565.648: T09704: UPnP_P: Fade in.
2024/09/29 17:00:35,565.660: T0222c: UPnP_P: [UPP_TransCThread]: Checking ProxyStream for file: [\\10.1.1.127\music\OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2\DISC 3\Track_03.flac]
2024/09/29 17:00:35,565.666: T09704: UPnP_P: Clear fade control.
2024/09/29 17:00:35,565.687: T09704: UPnP_P: Post PlayerNotify: 444417 [Update]
2024/09/29 17:00:35,565.695: T0222c: UPnP_P: CanApplyWPRX=No : WavProxyLimitedUse(Yes) && (DSD==No || Subsong==No || HiRes==No || CDDA==No ) || !WavProxyLimitedUse(Yes)
2024/09/29 17:00:35,565.706: T0222c: UPnP_P: WavProxy_OK=No : UseWavProxy==UseExDecd::IfN && MIME_OK==Yes (audio/wav) && CanApplyWPRX(No )
2024/09/29 17:00:35,565.717: T0222c: UPnP_P: AudioL16_OK=No : UseAudioL16==UseExDecd::IfN && MIME_OK==No (audio/L16)
2024/09/29 17:00:35,565.726: T0222c: UPnP_P: WavProxy_Req=No : WavProxy_OK==No && (UseWavProxy(UseExDecd::IfN)==UseExDecd::IfP || Tag==No (USE_WAV_PROXY))
2024/09/29 17:00:35,565.732: T0222c: UPnP_P: AudioL16_Req=No : AudioL16_OK==No && (UseAudioL16(UseExDecd::IfN)==UseExDecd::IfP || Tag==No (USE_AUDIO_L16))
2024/09/29 17:00:35,565.744: T0222c: UPnP_P: ProxyStream=No : MIME_NG(No ) (audio/flac) || EncType_NG(No ) (FLAC) || Subsong(No ) || CDDA(No ) || WavProxy_Req(No ) || AudioL16_Req(No ) || ExceedSR(No ) || ExceedBit(No ) || GenToFixedDrive(No ) || UseProxyStream(Auto)==Always
2024/09/29 17:00:35,566.308: T0222c: UPnP_P: [UPP_TransCThread]: File: 10.1.1.127 [\\10.1.1.127\music\OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2\DISC 3\Track_03.flac]
2024/09/29 17:00:35,566.318: T0222c: UPnP_P: [UPP_TransCThread]: URI: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00013979-RFH491D8236.flac]
2024/09/29 17:00:35,566.323: T0222c: UPnP_P: [UPP_TransCThread]: -- CreatedMetadata -- {
2024/09/29 17:00:35,566.334: T0222c: UPnP_P: [UPP_TransCThread]: <DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
2024/09/29 17:00:35,566.340: T0222c: UPnP_P: [UPP_TransCThread]: <item>
2024/09/29 17:00:35,566.345: T0222c: UPnP_P: [UPP_TransCThread]: <res protocolInfo="http-get:*:audio/flac:*" duration="0:01:08.638" bitrate="905107" bitsPerSample="16" nrAudioChannels="2" sampleFrequency="44100" DecodeEx="Normal">http://10.1.1.160:29400/TuneID/00013979-RFH491D8236.flac</res>
2024/09/29 17:00:35,566.351: T0222c: UPnP_P: [UPP_TransCThread]: <dc:title>絶望を司りし時</dc:title>
2024/09/29 17:00:35,566.355: T0222c: UPnP_P: [UPP_TransCThread]: <dc:date>2023</dc:date>
2024/09/29 17:00:35,566.359: T0222c: UPnP_P: [UPP_TransCThread]: <dc:creator>西木康智</dc:creator>
2024/09/29 17:00:35,566.364: T0222c: UPnP_P: [UPP_TransCThread]: <dc:publisher>SQUARE ENIX MUSIC</dc:publisher>
2024/09/29 17:00:35,566.368: T0222c: UPnP_P: [UPP_TransCThread]: <upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
2024/09/29 17:00:35,566.373: T0222c: UPnP_P: [UPP_TransCThread]: <upnp:genre>Soundtrack</upnp:genre>
2024/09/29 17:00:35,566.378: T0222c: UPnP_P: [UPP_TransCThread]: <upnp:artist>西木康智</upnp:artist>
2024/09/29 17:00:35,566.382: T0222c: UPnP_P: [UPP_TransCThread]: <upnp:originalTrackNumber>03</upnp:originalTrackNumber>
2024/09/29 17:00:35,566.387: T0222c: UPnP_P: [UPP_TransCThread]: <upnp:albumArtURI>http://10.1.1.160:29400/Image/05649.aad5f8a2-706a-4092-b442f063c78c9c6b.jpg</upnp:albumArtURI>
2024/09/29 17:00:35,566.392: T0222c: UPnP_P: [UPP_TransCThread]: <upnp:class>object.item.audioItem.musicTrack</upnp:class>
2024/09/29 17:00:35,566.396: T0222c: UPnP_P: [UPP_TransCThread]: </item>
2024/09/29 17:00:35,566.401: T0222c: UPnP_P: [UPP_TransCThread]: </DIDL-Lite>
2024/09/29 17:00:35,566.411: T0222c: UPnP_P: [UPP_TransCThread]: } -- CreatedMetadata --
2024/09/29 17:00:35,566.430: T0222c: UPnP_P: [SetNextAVTransportURI]: CommandSOAP: AVTransport:SetNextAVTransportURI
2024/09/29 17:00:35,566.630: T0222c: UPnP_P: [SOAP Request]: SOAP request: [SetNextAVTransportURI ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/AVTransport]
2024/09/29 17:00:35,572.427: T04818: UPnP_P: Proc PlayerNotify: 444417 [Update]
2024/09/29 17:00:36,178.752: T0222c: UPnP_P: [SOAP Request]: SOAP request: [SetNextAVTransportURI ] finished.
2024/09/29 17:00:36,178.862: T0222c: UPnP_P: [SetNextAVTransportURI]: [IFi] accepted next tune by SetNextAVTransportURI: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00013979-RFH491D8236.flac]
2024/09/29 17:00:36,236.003: T09834: Subscriber: [Event]: Device event is detected. SyncActive turns to off.
2024/09/29 17:00:36,236.084: T09834: Subscriber: [Event]: Received UPnP EVENT: [AVTransport]
2024/09/29 17:00:36,236.117: T09834: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackURI ] : [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/29 17:00:36,236.125: T09834: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackMetaData ] : [<?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/el (...)]
2024/09/29 17:00:36,236.299: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: DIDL/res: [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/29 17:00:36,236.306: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: - duration:[0:00:00] nrAudioChannels:[2] sampleFrequency:[44100] protocolInfo:[http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000]
2024/09/29 17:00:36,236.316: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Metadata was updated on URI: [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/29 17:00:36,236.321: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: -- ReceivedMetadata -- {
2024/09/29 17:00:36,236.338: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <?xml version="1.0" encoding="utf-8"?>
2024/09/29 17:00:36,236.344: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/">
2024/09/29 17:00:36,236.349: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <item restricted="1">
2024/09/29 17:00:36,236.352: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <orig>mpd</orig>
2024/09/29 17:00:36,236.356: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <dc:title>辺獄の深淵</dc:title>
2024/09/29 17:00:36,236.361: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <upnp:class>object.item.audioItem.musicTrack</upnp:class>
2024/09/29 17:00:36,236.365: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <dc:creator>西木康智</dc:creator>
2024/09/29 17:00:36,236.368: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <upnp:artist>西木康智</upnp:artist>
2024/09/29 17:00:36,236.373: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
2024/09/29 17:00:36,236.377: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <upnp:originalTrackNumber>02</upnp:originalTrackNumber>
2024/09/29 17:00:36,236.382: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: <res duration="0:00:00" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000">http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac</res>
2024/09/29 17:00:36,236.388: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: </item>
2024/09/29 17:00:36,236.393: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: </DIDL-Lite>
2024/09/29 17:00:36,236.398: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: } -- ReceivedMetadata --
2024/09/29 17:00:36,236.403: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Upd tag: [TITLE ]:
2024/09/29 17:00:36,236.409: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Old: [Insonnia]
2024/09/29 17:00:36,236.413: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: New: [辺獄の深淵]
2024/09/29 17:00:36,236.419: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Upd tag: [ARTIST ]:
2024/09/29 17:00:36,236.423: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Old: [KOKIA]
2024/09/29 17:00:36,236.427: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: New: [西木康智]
2024/09/29 17:00:36,236.431: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Upd tag: [ALBUM ]:
2024/09/29 17:00:36,236.436: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Old: [KOKIA - pieces]
2024/09/29 17:00:36,236.442: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: New: [OCTOPATH TRAVELER - CHAMPIONS (...)]
2024/09/29 17:00:36,236.450: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Updated metadata found.
2024/09/29 17:00:36,236.454: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: Track: [辺獄の深淵]
2024/09/29 17:00:36,236.729: T09f00: UPnP_P: [UPP_MetaUPThread]: MyURI: Yes [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/29 17:00:36,236.741: T09834: UPnP_P: [Proc:UriAndMeta] <EVT>: UseExDecd mark not found in URI: [DecodeEx:Normal] (FILE)
2024/09/29 17:00:36,236.754: T09834: Subscriber: - [AVTransport]: Device notification: Node found: [TransportState ] : [PLAYING]
2024/09/29 17:00:36,236.869: T09f00: UPnP_P: [ResolveTune]: URI: 10.1.1.160 [http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac]
2024/09/29 17:00:36,236.906: T09f00: UPnP_P: [ResolveTune]: This is mine.
2024/09/29 17:00:36,236.915: T09f00: UPnP_P: [ResolveTune]: Mine:Yes Managed:Yes Recorded:Yes Local file: [\\10.1.1.127\music\OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2\DISC 3\Track_02.flac]
2024/09/29 17:00:36,318.229: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: Getting PositionInfo from device.
2024/09/29 17:00:36,318.550: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/AVTransport]
2024/09/29 17:00:36,631.119: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] finished.
2024/09/29 17:00:36,631.232: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> RelTime: 0:00:00
2024/09/29 17:00:36,631.248: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:00 -> 0.0 / 0.0 sec
2024/09/29 17:00:36,631.254: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> TrackURI: http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac
2024/09/29 17:00:36,631.261: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> TrackMetaData: <?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/el (...)
2024/09/29 17:00:37,317.177: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: Getting PositionInfo from device.
2024/09/29 17:00:37,317.532: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/AVTransport]
2024/09/29 17:00:37,337.205: T093fc: Subscriber: [Event]: Received UPnP EVENT: [AVTransport]
2024/09/29 17:00:37,337.230: T093fc: Subscriber: - [AVTransport]: Device notification: Node found: [RelativeTimePosition ] : [0:00:01]
2024/09/29 17:00:37,337.247: T093fc: UPnP_P: [Proc:RelTime ] <EVT>: Elapsed: 0:00:01 -> 1.0 / 0.0 sec (Void)
2024/09/29 17:00:37,348.749: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] finished.
2024/09/29 17:00:37,348.890: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> RelTime: 0:00:01
2024/09/29 17:00:37,348.910: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:01 -> 1.0 / 0.0 sec (Void)
2024/09/29 17:00:37,348.918: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> TrackURI: http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac
2024/09/29 17:00:37,348.924: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> TrackMetaData: <?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/el (...)
2024/09/29 17:00:38,315.453: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: Getting PositionInfo from device.
2024/09/29 17:00:38,315.849: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/AVTransport]
2024/09/29 17:00:38,353.682: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] finished.
2024/09/29 17:00:38,353.791: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> RelTime: 0:00:02
2024/09/29 17:00:38,353.813: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:02 -> 2.0 / 0.0 sec (Void)
2024/09/29 17:00:38,353.821: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> TrackURI: http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac
2024/09/29 17:00:38,353.828: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> TrackMetaData: <?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/el (...)
2024/09/29 17:00:39,313.938: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: Getting PositionInfo from device.
2024/09/29 17:00:39,314.285: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/AVTransport]
2024/09/29 17:00:39,353.899: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] finished.
2024/09/29 17:00:39,354.043: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> RelTime: 0:00:03
2024/09/29 17:00:39,354.061: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:03 -> 3.0 / 0.0 sec (Void)
2024/09/29 17:00:39,354.068: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> TrackURI: http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac
2024/09/29 17:00:39,354.083: T02a4c: UPnP_P: [UPP_WorkerThread]: QuerySOAP: -> TrackMetaData: <?xml version="1.0" encoding="utf-8"?><DIDL-Lite xmlns:dc="http://purl.org/dc/el (...)
2024/09/29 17:00:40,314.035: T02a4c: UPnP_P: [UPP_WorkerThread]: Polling SOAP log turns to off.
2024/09/29 17:00:40,353.875: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:04 -> 4.0 / 0.0 sec (Void)
2024/09/29 17:00:41,354.234: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:05 -> 5.0 / 0.0 sec (Void) (per 5 seconds)
2024/09/29 17:00:46,339.559: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:10 -> 10.0 / 0.0 sec (Void) (per 5 seconds)
2024/09/29 17:00:51,336.639: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:15 -> 15.0 / 0.0 sec (Void) (per 5 seconds)
2024/09/29 17:00:56,332.467: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:20 -> 20.0 / 0.0 sec (Void) (per 5 seconds)
2024/09/29 17:01:01,325.072: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:25 -> 25.0 / 0.0 sec (Void) (per 5 seconds)
2024/09/29 17:01:06,325.639: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:30 -> 30.0 / 0.0 sec (Void) (per 10 seconds)
2024/09/29 17:01:16,315.528: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:40 -> 40.0 / 0.0 sec (Void) (per 10 seconds)
2024/09/29 17:01:26,302.414: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:50 -> 50.0 / 0.0 sec (Void) (per 10 seconds)
2024/09/29 17:01:36,292.182: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:01:00 -> 60.0 / 0.0 sec (Void) (per 10 seconds)
2024/09/29 17:01:46,287.018: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:01:10 -> 70.0 / 0.0 sec (Void) (per 10 seconds)
2024/09/29 17:01:56,270.233: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:01:20 -> 80.0 / 0.0 sec (Void) (per 10 seconds)
2024/09/29 17:02:06,260.853: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:01:30 -> 90.0 / 0.0 sec (Void) (per 10 seconds)
2024/09/29 17:02:16,254.792: T02a4c: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:01:40 -> 100.0 / 0.0 sec (Void) (per 10 seconds)
2024/09/29 17:02:26,224.950: T02a4c: Error: UPnP_P: [SOAP Request]: Cannot connect to server: 10.1.1.96 :49153
2024/09/29 17:02:26,224.970: T02a4c: Error: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] was failed.
2024/09/29 17:02:26,224.977: T02a4c: Error: UPnP_P: [SOAP Request]: SOAP request detected error: [ 4] (TFXFWTimeout)
2024/09/29 17:02:26,225.065: T02a4c: UPnP_P: [UPP_WorkerThread]: UnsubscribeAllEvent.
2024/09/29 17:02:26,225.101: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/OHProduct]
2024/09/29 17:02:29,239.598: T02a4c: Error: Subscriber: [UPP_WorkerThread]: Cannot connect to server: 10.1.1.96 :49153
2024/09/29 17:02:29,239.716: T02a4c: Subscriber: [UPP_WorkerThread]: - Server seems dead: 10.1.1.96
2024/09/29 17:02:29,239.736: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/OHInfo]
2024/09/29 17:02:29,239.963: T02a4c: Subscriber: [UPP_WorkerThread]: - Server deemed dead: 10.1.1.96
2024/09/29 17:02:29,239.979: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/OHVolume]
2024/09/29 17:02:29,240.145: T02a4c: Subscriber: [UPP_WorkerThread]: - Server deemed dead: 10.1.1.96
2024/09/29 17:02:29,240.158: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/OHPlaylist]
2024/09/29 17:02:29,240.280: T02a4c: Subscriber: [UPP_WorkerThread]: - Server deemed dead: 10.1.1.96
2024/09/29 17:02:29,240.290: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/OHTime]
2024/09/29 17:02:29,240.401: T02a4c: Subscriber: [UPP_WorkerThread]: - Server deemed dead: 10.1.1.96
2024/09/29 17:02:29,240.410: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/OHRadio]
2024/09/29 17:02:29,240.536: T02a4c: Subscriber: [UPP_WorkerThread]: - Server deemed dead: 10.1.1.96
2024/09/29 17:02:29,240.545: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/AVTransport]
2024/09/29 17:02:29,240.655: T02a4c: Subscriber: [UPP_WorkerThread]: - Server deemed dead: 10.1.1.96
2024/09/29 17:02:29,240.666: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/ConnectionManager]
2024/09/29 17:02:29,240.769: T02a4c: Subscriber: [UPP_WorkerThread]: - Server deemed dead: 10.1.1.96
2024/09/29 17:02:29,240.777: T02a4c: Subscriber: [UPP_WorkerThread]: UnsubscribeEvent: 10.1.1.96 [http://10.1.1.96:49153/evt/RenderingControl]
2024/09/29 17:02:29,240.880: T02a4c: Subscriber: [UPP_WorkerThread]: - Server deemed dead: 10.1.1.96
2024/09/29 17:02:29,240.906: T02a4c: UPnP_P: [UPP_WorkerThread]: Phase 1.
2024/09/29 17:02:29,741.005: T02a4c: UPnP_P: [UPP_InitDevice1] 2: Initialize UPnP info of the Player.
2024/09/29 17:02:29,741.056: T02a4c: UPnP_P: [UPP_InitDevice1] - UPnP is initialized.
2024/09/29 17:02:29,741.079: T02a4c: UPnP_P: [UPP_InitDevice1] - UPnP DeviceInfo: USN:[uuid:188a2caf-c6b7-7fa8-5a9f-cadaebbf02e7::urn:schemas-upnp-org:device:MediaRenderer:1]
2024/09/29 17:02:29,741.094: T02a4c: UPnP_P: [UPP_InitDevice1] - Location: http://10.1.1.96:49153/description.xml
2024/09/29 17:02:29,741.109: T02a4c: UPnP_P: [UPP_InitDevice1] - Name : IFi
2024/09/29 17:02:29,741.125: T02a4c: UPnP_P: [UPP_InitDevice1] - Model : iFi audio
2024/09/29 17:02:29,741.143: T02a4c: UPnP_P: [UPP_InitDevice1] - UPnP Service found: [urn:schemas-upnp-org:service:ConnectionManager:1]
2024/09/29 17:02:29,741.156: T02a4c: UPnP_P: [UPP_InitDevice1] QuerySOAP: Getting ProtocolInfo.
2024/09/29 17:02:29,741.913: T02a4c: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo ] to: 10.1.1.96 [http://10.1.1.96:49153/ctl/ConnectionManager]
2024/09/29 17:02:32,758.807: T02a4c: Error: UPnP_P: [SOAP Request]: Cannot connect to server: 10.1.1.96 :49153
2024/09/29 17:02:32,758.827: T02a4c: Error: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo ] was failed.
2024/09/29 17:02:32,758.836: T02a4c: Error: UPnP_P: [SOAP Request]: SOAP request detected error: [ 4] (TFXFWTimeout)
2024/09/29 17:02:32,758.884: T02a4c: Error: UPnP_P: [UPP_InitDevice1] GetProtocolInfo failed.
Message: Error: Initializing player failed.
Version: 1773

If you need any information from me, please don’t hesitate to let me know.

[storaid]

v5.6.0(without “void” issue)

<DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
<item>
<res protocolInfo="http-get:*:audio/flac:*" duration="0:03:25.822" bitrate="881753" bitsPerSample="16" nrAudioChannels="2" sampleFrequency="44100" DecodeEx="Normal">http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac</res>
<dc:title>曲名</dc:title>
<dc:date>2023</dc:date>
<dc:creator>西木康治</dc:creator>
<dc:publisher>SQUARE ENIX MUSIC</dc:publisher>
<upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
<upnp:genre>Soundtrack</upnp:genre>
<upnp:artist>西木康治</upnp:artist>
<upnp:originalTrackNumber>02</upnp:originalTrackNumber>
<upnp:albumArtURI>http://10.1.1.160:29400/Image/05625.a5a33f89-dc3e-4c1d-9a489069227fea69.jpg</upnp:albumArtURI>
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
</item>
</DIDL-Lite>

[storaid]

TrackMetaData from GetPositionInfoResponse differences??

Tool: whireshark

v5.5.3

<DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
<item>
<res protocolInfo="http-get:*:audio/flac:*" duration="0:03:25.822" bitrate="881753" bitsPerSample="16" nrAudioChannels="2" sampleFrequency="44100" DecodeEx="Normal">http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac</res>
<dc:title>曲名</dc:title>
<dc:date>2023</dc:date>
<dc:creator>西木康治</dc:creator>
<dc:publisher>SQUARE ENIX MUSIC</dc:publisher>
<upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
<upnp:genre>Soundtrack</upnp:genre>
<upnp:artist>西木康治</upnp:artist>
<upnp:originalTrackNumber>02</upnp:originalTrackNumber>
<upnp:albumArtURI>http://10.1.1.160:29400/Image/05625.a5a33f89-dc3e-4c1d-9a489069227fea69.jpg</upnp:albumArtURI>
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
</item>
</DIDL-Lite>

 

v5.6.0(if the Void issue happened)

<?xml version="1.0" encoding="utf-8"?>
<DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/">
<item restricted="1">
<orig>mpd</orig>
<dc:title>曲名</dc:title>
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
<dc:creator>西木康治</dc:creator>
<upnp:artist>西木康治</upnp:artist>
<upnp:album>OCTOPATH TRAVELER - CHAMPIONS OF THE CONTINENT ORIGINAL SOUNDTRACK vol.2</upnp:album>
<upnp:genre>Soundtrack</upnp:genre>
<upnp:originalTrackNumber>2</upnp:originalTrackNumber>
<res duration="0:00:00" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000">http://10.1.1.160:29400/TuneID/00013977-RFHF825DADC.flac</res>
</item>
</DIDL-Lite>

duration=”0:00:00″??? 0_0

[storaid]

Hi,

“I have added logs of received DIDL/res info in 1773.”

Check log

thanks…

Attachments:

1. 0240928-204456592-UL01387-T092bc-.txt

[storaid]

Hello, Tiki

about this part, did you make any changes?

I captured the packets using Wireshark, and I don’t see any differences.

TuneBrowser is still using GetPositionInfo to retrieve information.

[storaid]

Hi,

“I have checked your attached log file. There is no duration info in log. Probably, the duration info that TuneBrowser can recognize did not come in.”

I tested previous or earlier versions for a while.

all are working well except v5.6.0

[storaid]

weird…

test device: ifi zen stream

there is no “void” issue in v5.5.3 or older…

 

[storaid]

Hi, Tiki

Here is the log(“void” happened) attached.

you can check it!

Thanks

Attachments:

1. 20240926-193207634-UL01591-T09210.txt

[storaid]

Hi, Tiki

I found that this issue might be caused by the UPnP device.

I may need to contact the manufacturer about this issue…

However, what does the “(Void)” mean when it appears?

“UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:00:15 -> 15.0 / 0.0 sec (Void) (per 5 seconds)”

Once “(Void)” appears, the timebar’s progress functionality seems to start malfunctioning.

Thanks

[storaid]

Hello, Tiki

My point is that the UPnP Status View and Dataflow View are not shown as selected in the docking window menu.

You can notice that these two views do appear in the UI, but they are not selected in the menu.

 

[storaid]

Hello, Tiki,
This is quite a strange issue.

After resetting the view settings, it seems that the previously selected Window Viewer can be saved.

However, the selected items in the docking window menu do not match what is actually displayed on the screen.

[storaid]

Hello, Tiki

Thank you for pointing that out.

Could you suggest some example?

Here is my suggestion

Disable MIME Types => Excluded MIME Types

Disable EncoderType => Excluded Encoding Types

and, about description:

Specify the Excluded Encoding Types for file encoding formats, such as DFF/DST, that should be transcoded on-the-fly via ProxyStream.

very clear.

Thanks

[storaid]

Hello, Tiki

Case 1:

open UPnP Status View w/o any change and restart app.

and then no UPnP Status View shown

Case 2:

open UPnP Status View and enable autohide

and check View > Docking window

the UPnP Status View selection has disappeared…

 

[storaid]

Hello, Tiki

Now I can quickly copy those metadata…

Thanks…

[storaid]

For Denon speaker???

[storaid]

Hello, Tiki

Very thanks for your enhancements…

it’s working well…

Thanks

[storaid]

Hello, Tiki

Thanks for your changes.

It’s working well.

Thanks

PS: “Disable EncoderType” this title is a little confusing for me. Providing a more detailed description may be good.

[storaid]

Hello, Tiki,
I’ve realized that gain(preamp) is not a good approach.

It changes the signal level, specifically the voltage amplitude, which is different from adjusting the volume. When I tested increasing the preamp to +20dB, it resulted in clipping in the soundbar’s speakers.

I think keeping the maximum gain at +10dB(prev. default).
Can the volume signal strength be adjusted to increase volume without relying on gain?

Thanks

[storaid]

“When you change the preamp value, does it change the volume you hear? ”

Yeah, it does..

but still, the volume is too low with the SONY Soundbar…

Like this digital sound device, it’s easily affected by DSP tuning.

Thanks

[storaid]

Hi Tiki,

I’ve tried the preamp gain function that you mentioned, but the volume is still too low.

I tested it with a SONY Soundbar, and at 20% volume, the sound is acceptable when watching YouTube.

However, even with the preamp boosting the volume by +10dB in TuneBrowser, it’s still not enough.

Thanks

[storaid]

Thank you, Tiki..

[storaid]

Hello, Tiki

When sending a file to the UPnP device as a native file, TuneBrowser is not checking contents of the file. Only the file extension is used to determine the file type. So it is difficult to determine if a DFF file is compressed at the time of transmission.

I thought it would be possible to check the encoding format to determine if it is DST before sending the file?

Maybe it’s my misunderstanding. 🙁

 

[storaid]

Hi, Tiki

I noticed that most streamers cannot support compressed DST (DSD) files.

(Cheaper streamers are limited by hardware performance, as decompressing DST files requires significant system resources)

Even some expensive streamers seem unable to support the DST file format.

Is it possible to provide an option flag in the individual renderer settings of External UPnP Renderer settings to allow or disallow sending those DSD format files to the streamer?

Here is my thought…

[DSD Support]
Auto => Unlimited, maybe few streamers are able to handle the compression DSD format files
Limited => Only uncompression format
No => on-the-fly transcoding will be performed before sending

Thanks…

[storaid]

Hello, Tiki

TuneBrowser works as an OpenHome Player.

However, it is not an OpenHome Controler. TuneBrowser does not support external OpenHome Players.

Thanks for your answer…

I will continue to communicate with Auralic, hoping they can make some improvements.

[storaid]

Do you mean the TuneBrowser does not support oNet Media Renderer?

[storaid]

Hello, Tiki

I have contacted AuraLic.

They said they do not support UPnP. (I don’t know why they are so reluctant to implement standard UPnP)

They only support OpenHome, which is compatible with UPnP.

I’m not sure if TuneBrowser can create an OpenHome Renderer.

I tried using BubbleUPnP to create an OpenHome Renderer for testing…

But TuneBrowser couldn’t find this Renderer…

 

And, I also tested the Synology Audio Station. There is the same problem, too.

[storaid]

Here is the device spy capture:

it’s not nested…

But I’m not sure if the UPnP implementation is incomplete?!

[storaid]

UPnP Log attaached

Attachments:

1. log1.txt

[storaid]

Hello, Tiki,

May I ask a question?

Have you tested whether your streamer device(s) can process the DSD/DST compressed format?

 

[storaid]

Hello, Tiki

I’m currently in contact with the vendor and waiting for a response.

I have noticed that almost all DST files cannot be played back properly on the streamer unless on-the-fly transcoding is enabled.

I don’t understand the difference.

[storaid]

Hello, Tiki

here is my log

2024/06/13 19:27:50,664: UL19161: T00848: UPnP_P: PlayImpl: Doing SetAVTransportURI: AVTType::CurrTune
2024/06/13 19:27:50,664: UL19163: T00848: UPnP_P: PlayImpl: Checking ProxyStream for file: [\\10.1.1.127\music\[Hi-Res]SS REFERENCE - SACD柳沢功力氏選曲\01 - Carlos Kleiber, Bayerisches Staatsorchester - Dell'invito trascorsa è già l'ora from La traviata.dff]
2024/06/13 19:27:50,664: UL19165: T00848: UPnP_P: CanApplyWPRX=Yes : WavProxyLimitedUse(Yes) && (DSD==Yes || Subsong==No || HiRes==Yes) || !WavProxyLimitedUse(Yes)
2024/06/13 19:27:50,664: UL19167: T00848: UPnP_P: WavProxy_OK=Yes : UseWavProxy==UseExDecd::IfN && MIME_OK==Yes (audio/wav) && CanApplyWPRX(Yes)
2024/06/13 19:27:50,664: UL19169: T00848: UPnP_P: AudioL16_OK=No : UseAudioL16==UseExDecd::IfN && MIME_OK==No (audio/L16)
2024/06/13 19:27:50,664: UL19171: T00848: UPnP_P: WavProxy_Req=No : WavProxy_OK==Yes && (UseWavProxy(UseExDecd::IfN)==UseExDecd::IfP || Tag==No (USE_WAV_PROXY))
2024/06/13 19:27:50,664: UL19173: T00848: UPnP_P: AudioL16_Req=No : AudioL16_OK==No && (UseAudioL16(UseExDecd::IfN)==UseExDecd::IfP || Tag==No (USE_AUDIO_L16))
2024/06/13 19:27:50,664: UL19175: T00848: UPnP_P: ProxyStream=No : MIME_NG(No ) (audio/dff) || Subsong(No ) || WavProxy_Req(No ) || AudioL16_Req(No ) || GenToFixedDrive(No ) || UseProxyStream(Auto)==Always
2024/06/13 19:27:50,664: UL19177: T00848: UPnP_P: [SetAVTransportURI]: File: 10.1.1.127 [\\10.1.1.127\music\[Hi-Res]SS REFERENCE - SACD柳沢功力氏選曲\01 - Carlos Kleiber, Bayerisches Staatsorchester - Dell'invito trascorsa è già l'ora from La traviata.dff]
2024/06/13 19:27:50,664: UL19179: T00848: UPnP_P: [SetAVTransportURI]: URI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:50,664: UL19181: T00848: UPnP_P: [SetAVTransportURI]: <DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
2024/06/13 19:27:50,664: UL19183: T00848: UPnP_P: [SetAVTransportURI]: <item>
2024/06/13 19:27:50,664: UL19185: T00848: UPnP_P: [SetAVTransportURI]: <res protocolInfo="http-get:*:audio/dff:*" duration="0:04:38.147" bitrate="2313066" bitsPerSample="1" nrAudioChannels="2" sampleFrequency="2822400" DecodeEx="Normal">http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff</res>
2024/06/13 19:27:50,664: UL19187: T00848: UPnP_P: [SetAVTransportURI]: <dc:title>Dell'invito trascorsa è già l'ora from "La traviata"</dc:title>
2024/06/13 19:27:50,664: UL19189: T00848: UPnP_P: [SetAVTransportURI]: <dc:date>2008</dc:date>
2024/06/13 19:27:50,664: UL19191: T00848: UPnP_P: [SetAVTransportURI]: <dc:creator>Carlos Kleiber, Bayerisches Staatsorchester</dc:creator>
2024/06/13 19:27:50,664: UL19193: T00848: UPnP_P: [SetAVTransportURI]: <upnp:album>SS REFERENCE SA-CD / Yanagisawa Selection</upnp:album>
2024/06/13 19:27:50,664: UL19195: T00848: UPnP_P: [SetAVTransportURI]: <upnp:genre>Classical</upnp:genre>
2024/06/13 19:27:50,664: UL19197: T00848: UPnP_P: [SetAVTransportURI]: <upnp:artist>Carlos Kleiber, Bayerisches Staatsorchester</upnp:artist>
2024/06/13 19:27:50,664: UL19199: T00848: UPnP_P: [SetAVTransportURI]: <upnp:originalTrackNumber>1</upnp:originalTrackNumber>
2024/06/13 19:27:50,664: UL19201: T00848: UPnP_P: [SetAVTransportURI]: <upnp:albumArtURI>http://10.1.1.156:29400/Image/18719.3822d653-2af5-4868-a45b2ac5f645da6a.jpg</upnp:albumArtURI>
2024/06/13 19:27:50,664: UL19203: T00848: UPnP_P: [SetAVTransportURI]: <upnp:class>object.item.audioItem.musicTrack</upnp:class>
2024/06/13 19:27:50,664: UL19205: T00848: UPnP_P: [SetAVTransportURI]: </item>
2024/06/13 19:27:50,664: UL19207: T00848: UPnP_P: [SetAVTransportURI]: </DIDL-Lite>
2024/06/13 19:27:50,664: UL19209: T00848: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] to: 10.1.1.194 [http://10.1.1.194:60006/upnp/control/renderer_dvc/AVTransport]
2024/06/13 19:27:50,715: UL19210: T07af4: --> 10.1.1.194 :29500 EVENT /SR_AVTransport.notify: uuid:e93d98e0-2977-11ef-8348-93855cba8f9e
2024/06/13 19:27:50,715: UL19212: T07af4: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/06/13 19:27:50,715: UL19214: T07af4: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackURI ] : [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:50,715: UL19216: T07af4: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackMetaData ] : [<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http:/ (...)]
2024/06/13 19:27:50,715: UL19218: T07af4: UPnP_P: [Proc:UriAndMeta] <EVT>: New URI found: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:50,715: UL19220: T07af4: UPnP_P: [Proc:UriAndMeta] <EVT>: UseExDecd mark is pending.
2024/06/13 19:27:50,715: UL19222: T07af4: UPnP_P: [Proc:UriAndMeta] <EVT>: Track: [Dell'invito trascorsa è già l'ora from "La traviata"]
2024/06/13 19:27:50,716: UL19224: T07af4: UPnP_P: [Proc:UriAndMeta] <EVT>: UseExDecd mark not found in URI: [DecodeEx:Normal] (FILE)
2024/06/13 19:27:50,716: UL19226: T04204: UPnP_P: [ResolveTune]: URI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:50,716: UL19228: T04204: UPnP_P: [ResolveTune]: This is mine.
2024/06/13 19:27:50,716: UL19230: T04204: UPnP_P: [ResolveTune]: Mine:Yes Managed:Yes Recorded:Yes Local file: [\\10.1.1.127\music\[Hi-Res]SS REFERENCE - SACD柳沢功力氏選曲\01 - Carlos Kleiber, Bayerisches Staatsorchester - Dell'invito trascorsa è già l'ora from La traviata.dff]
2024/06/13 19:27:50,720: UL19232: T00848: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] finished.
2024/06/13 19:27:50,720: UL19234: T00848: UPnP_P: [SetAVTransportURI]: [Denon Home 350] accepted current tune by SetAVTransportURI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:50,720: UL19236: T00848: UPnP_P: PlayImpl: Resubscribe all events.
2024/06/13 19:27:50,720: UL19238: T00848: UPnP_P: PlayImpl: Unsubscribe all events.
2024/06/13 19:27:50,720: UL19240: T00848: Subscriber: PlayImpl: UnsubscribeEvent: 10.1.1.194 [http://10.1.1.194:60006/upnp/event/renderer_dvc/AVTransport]
2024/06/13 19:27:50,735: UL19242: T00848: Subscriber: PlayImpl: UnsubscribeEvent: 10.1.1.194 [http://10.1.1.194:60006/upnp/event/renderer_dvc/ConnectionManager]
2024/06/13 19:27:50,749: UL19244: T00848: Subscriber: PlayImpl: UnsubscribeEvent: 10.1.1.194 [http://10.1.1.194:60006/upnp/event/renderer_dvc/RenderingControl]
2024/06/13 19:27:50,761: UL19246: T00848: UPnP_P: PlayImpl: End Subscribe server.
2024/06/13 19:27:50,816: UL19258: T06b98: Subscriber: End processing.
2024/06/13 19:27:50,816: UL19262: T00848: UPnP_P: PlayImpl: Begin Subscribe server.
2024/06/13 19:27:50,817: UL19266: T05ba0: Subscriber: Begin processing.
2024/06/13 19:27:50,817: UL19268: T00848: UPnP_P: PlayImpl: Subscribe all events.
2024/06/13 19:27:50,817: UL19270: T00848: Subscriber: PlayImpl: SubscribeEvent: 10.1.1.194 [http://10.1.1.194:60006/upnp/event/renderer_dvc/AVTransport]
2024/06/13 19:27:50,831: UL19272: T00848: Subscriber: PlayImpl: SubscribeEvent: 10.1.1.194 [http://10.1.1.194:60006/upnp/event/renderer_dvc/ConnectionManager]
2024/06/13 19:27:50,842: UL19273: T01a6c: --> EVENT /SR_AVTransport.notify: uuid:fa80750a-2977-11ef-8348-93855cba8f9e
2024/06/13 19:27:50,842: UL19275: T01a6c: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/06/13 19:27:50,842: UL19277: T01a6c: Subscriber: - [AVTransport]: Device notification: Node found: [TransportStatus ] : [OK]
2024/06/13 19:27:50,842: UL19279: T01a6c: Subscriber: - [AVTransport]: Device notification: Node found: [TransportState ] : [STOPPED]
2024/06/13 19:27:50,842: UL19281: T01a6c: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackURI ] : [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:50,842: UL19283: T01a6c: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackMetaData ] : [<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http:/ (...)]
2024/06/13 19:27:50,842: UL19285: T01a6c: UPnP_P: [Proc:UriAndMeta] <EVT>: Track(Stay): [Dell'invito trascorsa è già l'ora from "La traviata"]
2024/06/13 19:27:50,846: UL19288: T00848: Subscriber: PlayImpl: SubscribeEvent: 10.1.1.194 [http://10.1.1.194:60006/upnp/event/renderer_dvc/RenderingControl]
2024/06/13 19:27:50,855: UL19289: T06a74: --> EVENT /SR_ConnectionManager.notify: uuid:fa827382-2977-11ef-8348-93855cba8f9e
2024/06/13 19:27:50,865: UL19291: T00848: UPnP_P: PlayImpl: Resubscribe all events has done.
2024/06/13 19:27:50,876: UL19292: T01a6c: --> EVENT /SR_RenderingControl.notify: uuid:fa859e0e-2977-11ef-8348-93855cba8f9e
2024/06/13 19:27:50,876: UL19294: T01a6c: Subscriber: [Event]: Received EVENT: [RenderingControl]
2024/06/13 19:27:50,876: UL19296: T01a6c: Subscriber: - [RenderingControl]: Device notification: Node found: [Volume ] : [39]
2024/06/13 19:27:50,894: UL19298: T00848: UPnP_P: PlayImpl: RelTimeEvent: No
2024/06/13 19:27:50,894: UL19300: T00848: UPnP_P: PlayImpl: SyncActive: No
2024/06/13 19:27:50,894: UL19302: T00848: UPnP_P: PlayImpl: Post Play.
2024/06/13 19:27:50,895: UL19304: T00848: UPnP_P: [SOAP Request]: SOAP request: [Play ] to: 10.1.1.194 [http://10.1.1.194:60006/upnp/control/renderer_dvc/AVTransport]
2024/06/13 19:27:50,928: UL19306: T00848: UPnP_P: [SOAP Request]: SOAP request: [Play ] finished.
2024/06/13 19:27:50,928: UL19308: T00848: UPnP_P: PlayImpl: Waiting for state change in 10000 ms.
2024/06/13 19:27:50,937: UL19309: T06a74: --> EVENT /SR_AVTransport.notify: uuid:fa80750a-2977-11ef-8348-93855cba8f9e
2024/06/13 19:27:50,937: UL19311: T06a74: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/06/13 19:27:50,937: UL19313: T06a74: Subscriber: - [AVTransport]: Device notification: Node found: [TransportState ] : [TRANSITIONING]
2024/06/13 19:27:50,937: UL19315: T06a74: UPnP_P: [Proc:TrState ] <EVT>: PlayerUPnP UPState changed [STOPPED ] to [TRANSITIONING ]
2024/06/13 19:27:50,937: UL19317: T06a74: UPnP_P: [Proc:TrState ] <EVT>: State : TRANSITIONING
2024/06/13 19:27:50,940: UL19318: T08a98: HTTPServer: [10.1.1.194 :58119]: Socket accepted.
2024/06/13 19:27:50,941: UL19319: T03548: HTTPServer: [10.1.1.194 :58119]: Processing [GET] /TuneID/00003241-RFH40DFCB58.dff
2024/06/13 19:27:50,941: UL19320: T03548: HTTPServer: [10.1.1.194 :58119]: Tune: 00003241-RFH40DFCB58.dff ->
2024/06/13 19:27:50,941: UL19321: T03548: HTTPServer: [10.1.1.194 :58119]: \\10.1.1.127\music\[Hi-Res]SS REFERENCE - SACD柳沢功力氏選曲\01 - Carlos Kleiber, Bayerisches Staatsorchester - Dell'invito trascorsa è già l'ora from La traviata.dff
2024/06/13 19:27:50,949: UL19323: T036a4: UPnP_P: Post PlayerNotify: 7085 [SpecUpdate]
2024/06/13 19:27:50,949: UL19325: T036a4: UPnP_P: Proc PlayerNotify: 7085 [SpecUpdate]
2024/06/13 19:27:50,950: UL19326: T03548: HTTPServer: [10.1.1.194 :58119]: Begin monitoring playback.
2024/06/13 19:27:50,958: UL19328: T00848: UPnP_P: PlayImpl: State change detected.
2024/06/13 19:27:50,958: UL19330: T00848: UPnP_P: PlayImpl: Waiting for state change has done in 30 ms.
2024/06/13 19:27:50,958: UL19332: T00848: UPnP_P: PlayImpl: Waiting for state change before seek in 10000 ms.
2024/06/13 19:27:51,231: UL19336: T01a6c: --> EVENT /SR_AVTransport.notify: uuid:fa80750a-2977-11ef-8348-93855cba8f9e
2024/06/13 19:27:51,231: UL19338: T01a6c: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/06/13 19:27:51,232: UL19340: T01a6c: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackURI ] : [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:51,232: UL19342: T01a6c: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackMetaData ] : [<DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http:/ (...)]
2024/06/13 19:27:51,232: UL19344: T01a6c: UPnP_P: [Proc:UriAndMeta] <EVT>: Updated metadata: [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:51,232: UL19346: T01a6c: UPnP_P: [Proc:UriAndMeta] <EVT>: Upd tag: [ARTIST ]:
2024/06/13 19:27:51,232: UL19348: T01a6c: UPnP_P: [Proc:UriAndMeta] <EVT>: Old: [Carlos Kleiber, Bayerisches St (...)]
2024/06/13 19:27:51,232: UL19350: T01a6c: UPnP_P: [Proc:UriAndMeta] <EVT>: New: [Carlos Kleiber, Bayerisches St (...)]
2024/06/13 19:27:51,232: UL19352: T01a6c: UPnP_P: [Proc:UriAndMeta] <EVT>: Updated metadata found.
2024/06/13 19:27:51,232: UL19354: T01a6c: UPnP_P: [Proc:UriAndMeta] <EVT>: Track: [Dell'invito trascorsa è già l'ora from "La traviata"]
2024/06/13 19:27:51,232: UL19356: T01a6c: UPnP_P: [Proc:UriAndMeta] <EVT>: UseExDecd mark not found in URI: [DecodeEx:Normal] (FILE)
2024/06/13 19:27:51,232: UL19358: T04204: UPnP_P: [ResolveTune]: URI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00003241-RFH40DFCB58.dff]
2024/06/13 19:27:51,232: UL19360: T04204: UPnP_P: [ResolveTune]: This is mine.
2024/06/13 19:27:51,232: UL19362: T04204: UPnP_P: [ResolveTune]: Mine:Yes Managed:Yes Recorded:Yes Local file: [\\10.1.1.127\music\[Hi-Res]SS REFERENCE - SACD柳沢功力氏選曲\01 - Carlos Kleiber, Bayerisches Staatsorchester - Dell'invito trascorsa è già l'ora from La traviata.dff]
2024/06/13 19:27:59,604: UL19687: T03548: HTTPServer: [10.1.1.194 :58119]: Detected playback threshold: TX:40894464 TL:40336306 / 80672612 bytes
2024/06/13 19:28:01,182: UL19753: T00848: UPnP_P: PlayImpl: Detected timeout while waiting for event.
2024/06/13 19:28:01,182: UL19755: T00848: UPnP_P: PlayImpl: Waiting for state change has done.
2024/06/13 19:28:01,182: UL19757: T00848: UPnP_P: PlayImpl: Seek is required to 0.0 sec.
2024/06/13 19:28:01,182: UL19759: T00848: UPnP_P: PlayImpl: Seek to 0.0 sec by [REL_TIME].
2024/06/13 19:28:01,182: UL19761: T00848: UPnP_P: PlayImpl: Setting seek value to void.
2024/06/13 19:28:01,182: UL19763: T00848: UPnP_P: PlayImpl: Preparing next tune by SetNextAVTransportURI.
2024/06/13 19:28:01,182: UL19765: T00848: UPnP_P: PlayImpl: Next tune sequence position 0 -> 1.
2024/06/13 19:28:01,182: UL19767: T00848: UPnP_P: PlayImpl: [Denon Home 350] is supporting SetNextAVTransportURI.
2024/06/13 19:28:01,182: UL19769: T00848: UPnP_P: PlayImpl: Procedure has been done.
2024/06/13 19:28:01,182: UL19771: T00848: UPnP_P: Post PlayerNotify: 7086 [Update]
2024/06/13 19:28:01,182: UL19773: T08afc: UPnP_P: [UPP_TransCThread]: Doing SetAVTransportURI: AVTType::NextTune
2024/06/13 19:28:01,182: UL19775: T08afc: UPnP_P: [UPP_TransCThread]: Checking ProxyStream for file: [\\10.1.1.127\music\[Hi-Res]SS REFERENCE - SACD柳沢功力氏選曲\02 - Carlos Kleiber, Bayerisches Staatsorchester - Libiamo ne'lieti calici (Brindisi) from La traviata.dff]
2024/06/13 19:28:01,183: UL19777: T08afc: UPnP_P: CanApplyWPRX=Yes : WavProxyLimitedUse(Yes) && (DSD==Yes || Subsong==No || HiRes==Yes) || !WavProxyLimitedUse(Yes)
2024/06/13 19:28:01,183: UL19779: T08afc: UPnP_P: WavProxy_OK=Yes : UseWavProxy==UseExDecd::IfN && MIME_OK==Yes (audio/wav) && CanApplyWPRX(Yes)
2024/06/13 19:28:01,183: UL19781: T08afc: UPnP_P: AudioL16_OK=No : UseAudioL16==UseExDecd::IfN && MIME_OK==No (audio/L16)
2024/06/13 19:28:01,183: UL19783: T08afc: UPnP_P: WavProxy_Req=No : WavProxy_OK==Yes && (UseWavProxy(UseExDecd::IfN)==UseExDecd::IfP || Tag==No (USE_WAV_PROXY))
2024/06/13 19:28:01,183: UL19785: T08afc: UPnP_P: AudioL16_Req=No : AudioL16_OK==No && (UseAudioL16(UseExDecd::IfN)==UseExDecd::IfP || Tag==No (USE_AUDIO_L16))
2024/06/13 19:28:01,183: UL19787: T08afc: UPnP_P: ProxyStream=No : MIME_NG(No ) (audio/dff) || Subsong(No ) || WavProxy_Req(No ) || AudioL16_Req(No ) || GenToFixedDrive(No ) || UseProxyStream(Auto)==Always

[storaid]

I’m learning the part as you mentioned.

It’s a little bit complicated.

[storaid]

Hello, Tiki

you are right…

it’s COMPOSER or ARRANGER

 

I noticed that the Artist title is displayed above the Album title.

But if the Album Artist is not empty, that area will show it.

Can I change the order of display for that?
or manually add an area to display the Artist title or change the display format?

Thanks

[storaid]

weird…

Some albums can display the artist’s title normally, while others can not.

test file(not artist shown):

https://1drv.ms/u/s!AvA1navurakAib1qK_a2PH2L-8CnIg?e=uYBueP

[storaid]

Hi,

I did test it several times with my WiiM Pro Plus.

This issue was improved I think.

Thanks

[storaid]

Tested device: Wiim Pro Plus

Logging

2024/05/15 00:34:30,896: UL08955: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetVolume ] to: 10.1.1.134 [http://10.1.1.134:49152/upnp/control/rendercontrol1]
2024/05/15 00:34:30,912: UL08957: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetVolume ] finished.
2024/05/15 00:34:30,912: UL08959: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetTransportInfo ] to: 10.1.1.134 [http://10.1.1.134:49152/upnp/control/rendertransport1]
2024/05/15 00:34:30,922: UL08961: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetTransportInfo ] finished.
2024/05/15 00:34:30,922: UL08963: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] to: 10.1.1.134 [http://10.1.1.134:49152/upnp/control/rendertransport1]
2024/05/15 00:34:30,933: UL08965: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] finished.
2024/05/15 00:34:30,933: UL08967: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed time 00:02:59 reached to target time.
2024/05/15 00:34:31,894: UL08969: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetVolume ] to: 10.1.1.134 [http://10.1.1.134:49152/upnp/control/rendercontrol1]
2024/05/15 00:34:31,907: UL08971: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetVolume ] finished.
2024/05/15 00:34:31,907: UL08973: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetTransportInfo ] to: 10.1.1.134 [http://10.1.1.134:49152/upnp/control/rendertransport1]
2024/05/15 00:34:31,921: UL08975: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetTransportInfo ] finished.
2024/05/15 00:34:31,922: UL08977: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] to: 10.1.1.134 [http://10.1.1.134:49152/upnp/control/rendertransport1]
2024/05/15 00:34:31,934: UL08979: T04bec: UPnP_P: [SOAP Request]: SOAP request: [GetPositionInfo ] finished.
2024/05/15 00:34:31,934: UL08981: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:00 -> 180.0 / 196.7 sec (per 10 seconds)
2024/05/15 00:34:32,893: UL08983: T04bec: UPnP_P: [UPP_WorkerThread]: Polling SOAP log turns to off.
2024/05/15 00:34:38,938: UL09007: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:07 -> 187.0 / 196.7 sec
2024/05/15 00:34:39,922: UL09012: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:08 -> 188.0 / 196.7 sec
2024/05/15 00:34:40,922: UL09018: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:09 -> 189.0 / 196.7 sec
2024/05/15 00:34:41,919: UL09020: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:09 -> 189.0 / 196.7 sec
2024/05/15 00:34:42,926: UL09024: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:10 -> 190.0 / 196.7 sec
2024/05/15 00:34:43,924: UL09028: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:11 -> 191.0 / 196.7 sec
2024/05/15 00:34:44,550: UL09029: T08548: --> 10.1.1.15 :51177 SSDP NOTIFY: ssdp:alive upnp:rootdevice
2024/05/15 00:34:44,551: UL09030: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b0
2024/05/15 00:34:44,551: UL09031: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:device:WANConnectionDevice:2
2024/05/15 00:34:44,551: UL09032: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b2
2024/05/15 00:34:44,552: UL09033: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:device:WANDevice:2
2024/05/15 00:34:44,552: UL09034: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b1
2024/05/15 00:34:44,621: UL09035: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANIPConnection:2
2024/05/15 00:34:44,621: UL09036: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:DeviceProtection:1
2024/05/15 00:34:44,622: UL09037: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANIPv6FirewallControl:1
2024/05/15 00:34:44,622: UL09038: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1
2024/05/15 00:34:44,622: UL09039: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANPPPConnection:1
2024/05/15 00:34:44,630: UL09040: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:Layer3Forwarding:1
2024/05/15 00:34:44,885: UL09041: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:Layer3Forwarding:1
2024/05/15 00:34:44,885: UL09042: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANPPPConnection:1
2024/05/15 00:34:44,888: UL09043: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1
2024/05/15 00:34:44,890: UL09044: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANIPv6FirewallControl:1
2024/05/15 00:34:44,890: UL09045: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:DeviceProtection:1
2024/05/15 00:34:44,894: UL09046: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANIPConnection:2
2024/05/15 00:34:44,895: UL09047: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b1
2024/05/15 00:34:44,897: UL09048: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:device:WANDevice:2
2024/05/15 00:34:44,899: UL09049: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b2
2024/05/15 00:34:44,901: UL09050: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:device:WANConnectionDevice:2
2024/05/15 00:34:44,903: UL09051: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b0
2024/05/15 00:34:44,908: UL09052: T08548: --> SSDP NOTIFY: ssdp:alive upnp:rootdevice
2024/05/15 00:34:44,955: UL09054: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:13 -> 193.0 / 196.7 sec
2024/05/15 00:34:45,926: UL09056: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:14 -> 194.0 / 196.7 sec
2024/05/15 00:34:46,917: UL09058: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:14 -> 194.0 / 196.7 sec
2024/05/15 00:34:47,925: UL09060: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:16 -> 196.0 / 196.7 sec
2024/05/15 00:34:48,925: UL09062: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:16 -> 196.0 / 196.7 sec
2024/05/15 00:34:49,926: UL09064: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:18 -> 198.0 / 196.7 sec
2024/05/15 00:34:50,910: UL09066: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:18 -> 198.0 / 196.7 sec
2024/05/15 00:34:51,912: UL09070: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:20 -> 200.0 / 196.7 sec
2024/05/15 00:34:52,898: UL09076: T04bec: UPnP_P: [Proc:TrState ] <POL>: PlayerUPnP UPState changed [PLAYING ] to [STOPPED ]
2024/05/15 00:34:52,898: UL09078: T04bec: UPnP_P: [Proc:TrState ] <POL>: PlayerUPnP PLStatus changed [PlayStatus::Playing ] to [PlayStatus::Stop ]
2024/05/15 00:34:52,898: UL09080: T04bec: UPnP_P: [Proc:TrState ] <POL>: Stop detected.
2024/05/15 00:34:52,898: UL09082: T04bec: UPnP_P: [Proc:TrState ] <POL>: Next tune sequence position 33 -> -1.
2024/05/15 00:34:52,898: UL09084: T04bec: UPnP_P: [Proc:TrState ] <POL>: Player is UnderControl. Next tune not found. Accept stop playback.
2024/05/15 00:34:52,898: UL09086: T04bec: UPnP_P: [Proc:TrState ] <POL>: PlayerPlayStatus: [Playing] -> [Stop]
2024/05/15 00:34:52,898: UL09088: T04bec: UPnP_P: [Proc:TrState ] <POL>: Post PlayerNotify: 0006 [PlayStatus]
2024/05/15 00:34:52,898: UL09090: T04bec: UPnP_P: [Proc:TrState ] <POL>: PlayerStatus: Stop
2024/05/15 00:34:52,898: UL09092: T04bec: UPnP_P: [Proc:TrState ] <POL>: Post PlayerNotify: 0006 [Stopped]
2024/05/15 00:34:52,898: UL09094: T04bec: UPnP_P: [Proc:TrState ] <POL>: PLTTrace released(P): PLTIdx:06
2024/05/15 00:34:52,898: UL09096: T04bec: UPnP_P: [Proc:TrState ] <POL>: State : STOPPED
2024/05/15 00:34:52,900: UL09098: T01e40: UPnP_P: Proc PlayerNotify: 0006 [PlayStatus]
2024/05/15 00:34:52,901: UL09100: T01e40: UPnP_P: Next tune sequence position 33 -> -1.
2024/05/15 00:34:52,901: UL09102: T01e40: UPnP_P: Current tune: [\\10.1.1.127\music\[Hi-Res]TVアニメ「無職転生 ～異世界行ったら本気だす～」オリジナル・サウンドトラック2／藤澤慶昌\01.冒険.flac:0]
2024/05/15 00:34:52,902: UL09104: T01e40: UPnP_P: Post PlayerNotify: 7068 [Update]
2024/05/15 00:34:52,902: UL09106: T01e40: UPnP_P: Current tune adjusted to sequence position 0.
2024/05/15 00:34:52,902: UL09108: T01e40: UPnP_P: GUI part detected tune change: [\\10.1.1.127\music\[Hi-Res]TVアニメ「無職転生 ～異世界行ったら本気だす～」オリジナル・サウンドトラック2／藤澤慶昌\01.冒険.flac:0]
2024/05/15 00:34:52,910: UL09116: T01e40: UPnP_P: Proc PlayerNotify: 0006 [Stopped]
2024/05/15 00:34:52,911: UL09118: T01e40: UPnP_P: PLTTrace released(G): PLTIdx:06
2024/05/15 00:34:52,911: UL09120: T04bec: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:16 -> 196.0 / 196.7 sec <- 0.0 (Not playing)
2024/05/15 00:34:52,923: UL09122: T01e40: UPnP_P: Proc PlayerNotify: 7068 [Update]

Tested device: Denon Home 350

Logging

2024/05/15 00:41:10,829: UL12428: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:07 -> 187.0 / 196.0 sec
2024/05/15 00:41:11,806: UL12430: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:08 -> 188.0 / 196.0 sec
2024/05/15 00:41:12,807: UL12432: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:09 -> 189.0 / 196.0 sec
2024/05/15 00:41:13,813: UL12434: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:10 -> 190.0 / 196.0 sec
2024/05/15 00:41:14,621: UL12435: T08548: --> 10.1.1.15 :51177 SSDP NOTIFY: ssdp:alive upnp:rootdevice
2024/05/15 00:41:14,624: UL12436: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b0
2024/05/15 00:41:14,635: UL12437: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:device:WANConnectionDevice:2
2024/05/15 00:41:14,635: UL12438: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b2
2024/05/15 00:41:14,636: UL12439: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:device:WANDevice:2
2024/05/15 00:41:14,636: UL12440: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b1
2024/05/15 00:41:14,636: UL12441: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANIPConnection:2
2024/05/15 00:41:14,636: UL12442: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:DeviceProtection:1
2024/05/15 00:41:14,646: UL12443: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANIPv6FirewallControl:1
2024/05/15 00:41:14,646: UL12444: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1
2024/05/15 00:41:14,646: UL12445: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANPPPConnection:1
2024/05/15 00:41:14,646: UL12446: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:Layer3Forwarding:1
2024/05/15 00:41:14,813: UL12448: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:11 -> 191.0 / 196.0 sec
2024/05/15 00:41:14,941: UL12449: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:Layer3Forwarding:1
2024/05/15 00:41:14,942: UL12450: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANPPPConnection:1
2024/05/15 00:41:14,942: UL12451: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANCommonInterfaceConfig:1
2024/05/15 00:41:14,943: UL12452: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANIPv6FirewallControl:1
2024/05/15 00:41:14,943: UL12453: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:DeviceProtection:1
2024/05/15 00:41:14,944: UL12454: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:WANIPConnection:2
2024/05/15 00:41:14,944: UL12455: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b1
2024/05/15 00:41:14,944: UL12456: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:device:WANDevice:2
2024/05/15 00:41:14,945: UL12457: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b2
2024/05/15 00:41:14,945: UL12458: T08548: --> SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:device:WANConnectionDevice:2
2024/05/15 00:41:14,945: UL12459: T08548: --> SSDP NOTIFY: ssdp:alive uuid:f785870d-aeaf-49d0-b247-406d43dd12b0
2024/05/15 00:41:14,946: UL12460: T08548: --> SSDP NOTIFY: ssdp:alive upnp:rootdevice
2024/05/15 00:41:15,802: UL12462: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:12 -> 192.0 / 196.0 sec
2024/05/15 00:41:16,799: UL12464: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:13 -> 193.0 / 196.0 sec
2024/05/15 00:41:17,801: UL12466: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:14 -> 194.0 / 196.0 sec
2024/05/15 00:41:18,805: UL12468: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:15 -> 195.0 / 196.0 sec
2024/05/15 00:41:19,800: UL12470: T012d0: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 00:03:16 -> 196.0 / 196.0 sec
2024/05/15 00:41:20,102: UL12471: T01e70: --> 10.1.1.192 :29500 EVENT /SR_AVTransport.notify: uuid:5564cd4c-1210-11ef-b336-f9c729d35445
2024/05/15 00:41:20,102: UL12473: T01e70: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/05/15 00:41:20,102: UL12475: T01e70: Subscriber: - [AVTransport]: Device notification: Node found: [TransportState ] : [STOPPED]
2024/05/15 00:41:20,102: UL12477: T01e70: UPnP_P: [Proc:TrState ] <EVT>: PlayerUPnP UPState changed [PLAYING ] to [STOPPED ]
2024/05/15 00:41:20,102: UL12479: T01e70: UPnP_P: [Proc:TrState ] <EVT>: PlayerUPnP PLStatus changed [PlayStatus::Playing ] to [PlayStatus::Stop ]
2024/05/15 00:41:20,102: UL12481: T01e70: UPnP_P: [Proc:TrState ] <EVT>: Stop detected.
2024/05/15 00:41:20,102: UL12483: T01e70: UPnP_P: [Proc:TrState ] <EVT>: Next tune sequence position 33 -> -1.
2024/05/15 00:41:20,103: UL12485: T01e70: UPnP_P: [Proc:TrState ] <EVT>: Player is UnderControl. Next tune not found. Accept stop playback.
2024/05/15 00:41:20,103: UL12487: T01e70: UPnP_P: [Proc:TrState ] <EVT>: PlayerPlayStatus: [Playing] -> [Stop]
2024/05/15 00:41:20,103: UL12489: T01e70: UPnP_P: [Proc:TrState ] <EVT>: Post PlayerNotify: 0010 [PlayStatus]
2024/05/15 00:41:20,103: UL12491: T01e70: UPnP_P: [Proc:TrState ] <EVT>: PlayerStatus: Stop
2024/05/15 00:41:20,103: UL12493: T01e70: UPnP_P: [Proc:TrState ] <EVT>: Post PlayerNotify: 0010 [Stopped]
2024/05/15 00:41:20,103: UL12495: T01e70: UPnP_P: [Proc:TrState ] <EVT>: PLTTrace released(P): PLTIdx:10
2024/05/15 00:41:20,103: UL12497: T01e70: UPnP_P: [Proc:TrState ] <EVT>: State : STOPPED
2024/05/15 00:41:20,108: UL12499: T01e40: UPnP_P: Proc PlayerNotify: 0010 [PlayStatus]
2024/05/15 00:41:20,109: UL12501: T01e40: UPnP_P: Next tune sequence position 33 -> -1.
2024/05/15 00:41:20,110: UL12503: T01e40: UPnP_P: Current tune: [\\10.1.1.127\music\[Hi-Res]TVアニメ「無職転生 ～異世界行ったら本気だす～」オリジナル・サウンドトラック2／藤澤慶昌\01.冒険.flac:0]
2024/05/15 00:41:20,111: UL12505: T01e40: UPnP_P: Post PlayerNotify: 7105 [Update]
2024/05/15 00:41:20,111: UL12507: T01e40: UPnP_P: Current tune adjusted to sequence position 0.
2024/05/15 00:41:20,112: UL12509: T01e40: UPnP_P: GUI part detected tune change: [\\10.1.1.127\music\[Hi-Res]TVアニメ「無職転生 ～異世界行ったら本気だす～」オリジナル・サウンドトラック2／藤澤慶昌\01.冒険.flac:0]
2024/05/15 00:41:20,121: UL12517: T01e40: UPnP_P: Proc PlayerNotify: 0010 [Stopped]
2024/05/15 00:41:20,121: UL12519: T01e40: UPnP_P: PLTTrace released(G): PLTIdx:10
2024/05/15 00:41:20,129: UL12521: T01e40: UPnP_P: Proc PlayerNotify: 7105 [Update]

interesting. no bar-rendered issue on the Denon Home device

Tested device: iFi Zen Streamer

Logging

2024/05/15 00:50:19,247: UL17084: T08490: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:03:15 -> 195.0 / 196.7 sec
2024/05/15 00:50:19,543: UL17085: T01600: --> EVENT /SR_Info.notify: uuid:401126ce-1211-11ef-8388-8982e1f85184
2024/05/15 00:50:19,544: UL17086: T078ec: --> EVENT /SR_Time.notify: uuid:4015f6ea-1211-11ef-8388-8982e1f85184
2024/05/15 00:50:20,239: UL17088: T08490: UPnP_P: [Proc:RelTime ] <POL>: Elapsed: 0:03:16 -> 196.0 / 196.7 sec
2024/05/15 00:50:20,701: UL17089: T08548: --> 10.1.1.96 :39593 SSDP M-SEARCH: urn:schemas-upnp-org:device:MediaServer:1
2024/05/15 00:50:20,801: UL17090: T08548: <-- SSDP M-SEARCH: urn:schemas-upnp-org:device:MediaServer:1
2024/05/15 00:50:20,825: UL17091: T08f30: --> 10.1.1.96 :47298 DEVICE UPnP Media Server: GET /Device.xml
2024/05/15 00:50:20,825: UL17092: T08f30: <-- DEVICE UPnP Media Server: Device desc
2024/05/15 00:50:21,037: UL17093: T02e90: --> 10.1.1.96 :29500 EVENT /SR_Playlist.notify: uuid:40130d72-1211-11ef-8388-8982e1f85184
2024/05/15 00:50:21,037: UL17094: T07a24: --> EVENT /SR_Info.notify: uuid:401126ce-1211-11ef-8388-8982e1f85184
2024/05/15 00:50:21,041: UL17095: T08948: --> EVENT /SR_AVTransport.notify: uuid:4017e324-1211-11ef-8388-8982e1f85184
2024/05/15 00:50:21,041: UL17097: T08948: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/05/15 00:50:21,041: UL17098: T02bcc: --> EVENT /SR_Time.notify: uuid:4015f6ea-1211-11ef-8388-8982e1f85184
2024/05/15 00:50:21,041: UL17100: T08948: Subscriber: - [AVTransport]: Device notification: Node found: [TransportState ] : [STOPPED]
2024/05/15 00:50:21,041: UL17102: T08948: UPnP_P: [Proc:TrState ] <EVT>: PlayerUPnP UPState changed [PLAYING ] to [STOPPED ]
2024/05/15 00:50:21,041: UL17104: T08948: UPnP_P: [Proc:TrState ] <EVT>: PlayerUPnP PLStatus changed [PlayStatus::Playing ] to [PlayStatus::Stop ]
2024/05/15 00:50:21,041: UL17106: T08948: UPnP_P: [Proc:TrState ] <EVT>: Stop detected.
2024/05/15 00:50:21,041: UL17108: T08948: UPnP_P: [Proc:TrState ] <EVT>: Next tune sequence position 33 -> -1.
2024/05/15 00:50:21,041: UL17110: T08948: UPnP_P: [Proc:TrState ] <EVT>: Player is UnderControl. Next tune not found. Accept stop playback.
2024/05/15 00:50:21,041: UL17112: T08948: UPnP_P: [Proc:TrState ] <EVT>: PlayerPlayStatus: [Playing] -> [Stop]
2024/05/15 00:50:21,041: UL17114: T08948: UPnP_P: [Proc:TrState ] <EVT>: Post PlayerNotify: 0015 [PlayStatus]
2024/05/15 00:50:21,041: UL17116: T08948: UPnP_P: [Proc:TrState ] <EVT>: PlayerStatus: Stop
2024/05/15 00:50:21,041: UL17118: T08948: UPnP_P: [Proc:TrState ] <EVT>: Post PlayerNotify: 0015 [Stopped]
2024/05/15 00:50:21,041: UL17120: T08948: UPnP_P: [Proc:TrState ] <EVT>: PLTTrace released(P): PLTIdx:15
2024/05/15 00:50:21,041: UL17122: T08948: UPnP_P: [Proc:TrState ] <EVT>: State : STOPPED
2024/05/15 00:50:21,041: UL17124: T08948: Subscriber: - [AVTransport]: Device notification: Node found: [RelativeTimePosition ] : [0:00:00]
2024/05/15 00:50:21,041: UL17126: T08948: Subscriber: - [AVTransport]: Device notification: Node found: [CurrentTrackMetaData ] : []
2024/05/15 00:50:21,044: UL17128: T01e40: UPnP_P: Proc PlayerNotify: 0015 [PlayStatus]
2024/05/15 00:50:21,045: UL17130: T01e40: UPnP_P: Next tune sequence position 33 -> -1.
2024/05/15 00:50:21,046: UL17132: T01e40: UPnP_P: Current tune: [\\10.1.1.127\music\[Hi-Res]TVアニメ「無職転生 ～異世界行ったら本気だす～」オリジナル・サウンドトラック2／藤澤慶昌\01.冒険.flac:0]
2024/05/15 00:50:21,047: UL17134: T01e40: UPnP_P: Post PlayerNotify: 7185 [Update]
2024/05/15 00:50:21,048: UL17136: T01e40: UPnP_P: Current tune adjusted to sequence position 0.
2024/05/15 00:50:21,049: UL17138: T01e40: UPnP_P: GUI part detected tune change: [\\10.1.1.127\music\[Hi-Res]TVアニメ「無職転生 ～異世界行ったら本気だす～」オリジナル・サウンドトラック2／藤澤慶昌\01.冒険.flac:0]
2024/05/15 00:50:21,058: UL17146: T01e40: UPnP_P: Proc PlayerNotify: 0015 [Stopped]
2024/05/15 00:50:21,058: UL17148: T01e40: UPnP_P: PLTTrace released(G): PLTIdx:15
2024/05/15 00:50:21,062: UL17150: T01e40: UPnP_P: Proc PlayerNotify: 7185 [Update]
2024/05/15 00:50:21,099: UL17151: T025e0: --> EVENT /SR_Radio.notify: uuid:4015371e-1211-11ef-8388-8982e1f85184
2024/05/15 00:50:22,058: UL17152: T01600: --> EVENT /SR_Playlist.notify: uuid:40130d72-1211-11ef-8388-8982e1f85184
2024/05/15 00:50:29,440: UL17153: T08548: --> 10.1.1.192 :52115 SSDP NOTIFY: ssdp:alive upnp:rootdevice
2024/05/15 00:50:29,440: UL17154: T08548: --> 10.1.1.192 :50989 SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:AVTransport:1
2024/05/15 00:50:29,441: UL17155: T08548: --> 10.1.1.192 :52115 SSDP NOTIFY: ssdp:alive uuid:2d4f627f-e893-1a71-0080-00a96f05b956
2024/05/15 00:50:29,441: UL17156: T08548: --> 10.1.1.192 :40884 SSDP NOTIFY: ssdp:alive urn:schemas-upnp-org:service:RenderingControl:1
2024/05/15 00:50:29,441: UL17157: T08548: --> 10.1.1.192 :52115 SSDP NOTIFY: ssdp:alive urn:schemas-denon-com:device:AiosDevice:1
2024/05/15 00:50:29,441: UL17158: T08548: --> 10.1.1.192 :35173 SSDP NOTIFY: ssdp:alive urn:schemas-denon-com:service:ErrorHandler:1
2024/05/15 00:50:29,442: UL17159: T08548: --> 10.1.1.192 :41325 SSDP NOTIFY: ssdp:alive uuid:7ea90380-9dfa-14fd-0080-00a96f05b956
2024/05/15 00:50:29,442: UL17160: T08548: UPnP_M: *** Processing USNID_17 by NOTIFY from: 10.1.1.192 : [uuid:7ea90380-9dfa-14fd-0080-00a96f05b956::urn:schemas-upnp-org:device:MediaRenderer:1]

I’m not sure, but…

tested many times with this device, and no bar-rendered issue happened.

seems like this issue only occurs on the Wiim Pro Plus device.

and, setting the Seek Mode as “ABS_TIME” can not resolve this issue.

[storaid]

Hi, Tiki

“Hmm, perhaps, you are using external player like UPnP renderer, and I think the player reported inconsistent time position.”

yeap, UPnP renderer

it’s not easy to determine and reproduce this issue.

the following are the steps that I tried and spent time on:

Step 1: play the last track in the album

Step 2: on playing status, move your mouse to 1st track and keep focus

Step 3: waiting for the last track playback completed

then, possibly you will see the incorrect length bar-rendered issue at track1.

[storaid]

Hello, Tiki

To be honest, I just happened to stumble upon this.

I spent some time trying to reproduce it.

In the end, I found some clues.

It seems that by using the F3 button, the currently playing album title will be highlighted.

It shouldn’t be a UI issue I think.

 

[storaid]

Hello,

Tested it..

it’s working as I expected

but I have a question

1. what’s the borderless highlighted title outside of focus?

Thanks

[storaid]

Hello, Tiki

confirmed

thank you.

[storaid]

Hi,

I tested it with Denon Home

now, in this version, default additional MIME tags can be sent without ProxyStream..

Thanks

[storaid]

Hello, Tiki

very thks

I can see this information in the player viewer…

Thank you for your improvements.

[storaid]

Hello, Tiki

Tested another case.

“audio/dsf; audio/dff” these tags are default with the Denon Home device on the application.

And, I added a new tag on purpose.

ok, very interesting.

2024/05/05 21:40:43,202: UL06574: T09aac: UPnP_P: [UPP_WorkerThread]: 1: Initialize UPnP info of the Player.
2024/05/05 21:40:43,202: UL06576: T09aac: UPnP_P: [UPP_WorkerThread]: - UPnP is initialized.
2024/05/05 21:40:43,202: UL06578: T09aac: UPnP_P: [UPP_WorkerThread]: - UPnP DeviceInfo: USN:[uuid:7ea90380-9dfa-14fd-0080-00a96f05b956::urn:schemas-upnp-org:device:MediaRenderer:1]
2024/05/05 21:40:43,202: UL06580: T09aac: UPnP_P: [UPP_WorkerThread]: - Location: http://10.1.1.192:60006/upnp/desc/aios_device/aios_device.xml
2024/05/05 21:40:43,202: UL06582: T09aac: UPnP_P: [UPP_WorkerThread]: - Name : Denon Home 350
2024/05/05 21:40:43,202: UL06584: T09aac: UPnP_P: [UPP_WorkerThread]: - Model : Denon Home 350
2024/05/05 21:40:43,202: UL06586: T09aac: UPnP_P: [UPP_WorkerThread]: - UPnP Service found: [urn:schemas-upnp-org:service:ConnectionManager:1]
2024/05/05 21:40:43,202: UL06588: T09aac: UPnP_P: [UPP_WorkerThread]: - Call GetProtocolInfo.
2024/05/05 21:40:43,203: UL06590: T09aac: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/ConnectionManager]
2024/05/05 21:40:43,230: UL06592: T09aac: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo ] finished.
2024/05/05 21:40:43,231: UL06594: T09aac: UPnP_P: [UPP_WorkerThread]: - Supported protocols: 13
2024/05/05 21:40:43,231: UL06596: T09aac: UPnP_P: [UPP_WorkerThread]: audio/mpeg <- http-get:*:audio/mpeg:*
2024/05/05 21:40:43,231: UL06598: T09aac: UPnP_P: [UPP_WorkerThread]: audio/vnd.dlna.adts <- http-get:*:audio/vnd.dlna.adts:*
2024/05/05 21:40:43,231: UL06600: T09aac: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:*
2024/05/05 21:40:43,231: UL06602: T09aac: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:*
2024/05/05 21:40:43,231: UL06604: T09aac: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=48000;channels=2 <- http-get:*:audio/L16;rate=48000;channels=2:*
2024/05/05 21:40:43,231: UL06606: T09aac: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=48000;channels=1 <- http-get:*:audio/L16;rate=48000;channels=1:*
2024/05/05 21:40:43,231: UL06608: T09aac: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=44100;channels=2 <- http-get:*:audio/L16;rate=44100;channels=2:*
2024/05/05 21:40:43,231: UL06610: T09aac: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=44100;channels=1 <- http-get:*:audio/L16;rate=44100;channels=1:*
2024/05/05 21:40:43,231: UL06612: T09aac: UPnP_P: [UPP_WorkerThread]: audio/wav <- http-get:*:audio/wav:*
2024/05/05 21:40:43,231: UL06614: T09aac: UPnP_P: [UPP_WorkerThread]: audio/mp3 <- http-get:*:audio/mp3:*
2024/05/05 21:40:43,231: UL06616: T09aac: UPnP_P: [UPP_WorkerThread]: audio/flac <- http-get:*:audio/flac:*
2024/05/05 21:40:43,231: UL06618: T09aac: UPnP_P: [UPP_WorkerThread]: audio/aac <- http-get:*:audio/aac:*
2024/05/05 21:40:43,231: UL06620: T09aac: UPnP_P: [UPP_WorkerThread]: audio/m4a <- http-get:*:audio/m4a:*
2024/05/05 21:40:43,231: UL06622: T09aac: UPnP_P: [UPP_WorkerThread]: - Additional protocols:
2024/05/05 21:40:43,231: UL06624: T09aac: UPnP_P: [UPP_WorkerThread]: audio/dsf
2024/05/05 21:40:43,231: UL06626: T09aac: UPnP_P: [UPP_WorkerThread]: audio/dff
2024/05/05 21:40:43,231: UL06628: T09aac: UPnP_P: [UPP_WorkerThread]: audio/tta
2024/05/05 21:40:43,231: UL06630: T09aac: UPnP_P: [UPP_WorkerThread]: - GetProtocolInfo completed.
2024/05/05 21:40:43,231: UL06632: T09aac: UPnP_P: [UPP_WorkerThread]: - UPnP Service found: [urn:schemas-upnp-org:service:RenderingControl:1]
2024/05/05 21:40:43,231: UL06634: T09aac: UPnP_P: [UPP_WorkerThread]: - Player has been enabled.
2024/05/05 21:40:43,231: UL06636: T09aac: UPnP_P: [UPP_WorkerThread]: Post PlayerNotify: 7035 [SpecUpdate]
2024/05/05 21:40:43,231: UL06638: T09aac: UPnP_P: [UPP_WorkerThread]: UPnP DeviceInfo is ready. Initialize device.
2024/05/05 21:40:43,231: UL06640: T08d1c: UPnP_P: Stage: [InitializeDevice]
2024/05/05 21:40:43,231: UL06642: T08d1c: UPnP_P: Initialize device.

well, we can see these MIME tags attached in the log after the changes.

[storaid]

Hello, Tiki

I tested another brand device:

Wiim Pro Plus

2024/05/05 20:21:26,494: UL04232: T08370: UPnP_P: [UPP_WorkerThread]: 1: Initialize UPnP info of the Player.
2024/05/05 20:21:26,494: UL04234: T08370: UPnP_P: [UPP_WorkerThread]: - UPnP is initialized.
2024/05/05 20:21:26,494: UL04236: T08370: UPnP_P: [UPP_WorkerThread]: - UPnP DeviceInfo: USN:[uuid:FF98FCDE-C5BF-3F37-7DF2-1725FF98FCDE::urn:schemas-upnp-org:device:MediaRenderer:1]
2024/05/05 20:21:26,495: UL04238: T08370: UPnP_P: [UPP_WorkerThread]: - Location: http://10.1.1.134:49152/description.xml
2024/05/05 20:21:26,495: UL04240: T08370: UPnP_P: [UPP_WorkerThread]: - Name : WiiM Pro Plus-D2FC
2024/05/05 20:21:26,495: UL04242: T08370: UPnP_P: [UPP_WorkerThread]: - Model : WiiM Pro Plus Receiver
2024/05/05 20:21:26,495: UL04244: T08370: UPnP_P: [UPP_WorkerThread]: - UPnP Service found: [urn:schemas-upnp-org:service:ConnectionManager:1]
2024/05/05 20:21:26,495: UL04246: T08370: UPnP_P: [UPP_WorkerThread]: - Call GetProtocolInfo.
2024/05/05 20:21:26,495: UL04248: T08370: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo ] to: 10.1.1.134 [http://10.1.1.134:49152/upnp/control/renderconnmgr1]
2024/05/05 20:21:26,513: UL04250: T08370: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo ] finished.
2024/05/05 20:21:26,513: UL04252: T08370: UPnP_P: [UPP_WorkerThread]: - Supported protocols: 20
2024/05/05 20:21:26,513: UL04254: T08370: UPnP_P: [UPP_WorkerThread]: audio/wav <- http-get:*:audio/wav:DLNA.ORG_PN=LPCM
2024/05/05 20:21:26,513: UL04256: T08370: UPnP_P: [UPP_WorkerThread]: audio/x-wav <- http-get:*:audio/x-wav:DLNA.ORG_PN=LPCM
2024/05/05 20:21:26,513: UL04258: T08370: UPnP_P: [UPP_WorkerThread]: audio/mpeg <- http-get:*:audio/mpeg:DLNA.ORG_PN=MP3
2024/05/05 20:21:26,513: UL04260: T08370: UPnP_P: [UPP_WorkerThread]: audio/mpeg <- http-get:*:audio/mpeg:DLNA.ORG_PN=MP3X
2024/05/05 20:21:26,513: UL04262: T08370: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMABASE
2024/05/05 20:21:26,513: UL04264: T08370: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMAFULL
2024/05/05 20:21:26,513: UL04266: T08370: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMAPRO
2024/05/05 20:21:26,513: UL04268: T08370: UPnP_P: [UPP_WorkerThread]: audio/mpeg <- http-get:*:audio/mpeg:DLNA.ORG_PN=MP2_MPS
2024/05/05 20:21:26,513: UL04270: T08370: UPnP_P: [UPP_WorkerThread]: audio/mp3 <- http-get:*:audio/mp3:*
2024/05/05 20:21:26,513: UL04272: T08370: UPnP_P: [UPP_WorkerThread]: audio/wma <- http-get:*:audio/wma:*
2024/05/05 20:21:26,513: UL04274: T08370: UPnP_P: [UPP_WorkerThread]: audio/mpeg <- http-get:*:audio/mpeg:*
2024/05/05 20:21:26,513: UL04276: T08370: UPnP_P: [UPP_WorkerThread]: audio/vnd.dlna.adts <- http-get:*:audio/vnd.dlna.adts:DLNA.ORG_PN=AAC_ADTS
2024/05/05 20:21:26,513: UL04278: T08370: UPnP_P: [UPP_WorkerThread]: audio/vnd.dlna.adts <- http-get:*:audio/vnd.dlna.adts:DLNA.ORG_PN=AAC_ADTS_320
2024/05/05 20:21:26,513: UL04280: T08370: UPnP_P: [UPP_WorkerThread]: audio/m4a <- http-get:*:audio/m4a:DLNA.ORG_PN=AAC_ISO
2024/05/05 20:21:26,513: UL04282: T08370: UPnP_P: [UPP_WorkerThread]: audio/aac <- http-get:*:audio/aac:DLNA.ORG_PN=AAC_ISO
2024/05/05 20:21:26,513: UL04284: T08370: UPnP_P: [UPP_WorkerThread]: audio/ac3 <- http-get:*:audio/ac3:DLNA.ORG_PN=AC3
2024/05/05 20:21:26,513: UL04286: T08370: UPnP_P: [UPP_WorkerThread]: audio/ogg <- http-get:*:audio/ogg:*
2024/05/05 20:21:26,513: UL04288: T08370: UPnP_P: [UPP_WorkerThread]: audio/ape <- http-get:*:audio/ape:*
2024/05/05 20:21:26,513: UL04290: T08370: UPnP_P: [UPP_WorkerThread]: audio/x-ape <- http-get:*:audio/x-ape:*
2024/05/05 20:21:26,513: UL04292: T08370: UPnP_P: [UPP_WorkerThread]: audio/flac <- http-get:*:audio/flac:*
2024/05/05 20:21:26,517: UL04294: T08370: UPnP_P: [UPP_WorkerThread]: - Additional protocols:
2024/05/05 20:21:26,517: UL04296: T08370: UPnP_P: [UPP_WorkerThread]: audio/dsf
2024/05/05 20:21:26,517: UL04298: T08370: UPnP_P: [UPP_WorkerThread]: audio/dff
2024/05/05 20:21:26,517: UL04300: T08370: UPnP_P: [UPP_WorkerThread]: - GetProtocolInfo completed.
2024/05/05 20:21:26,517: UL04302: T08370: UPnP_P: [UPP_WorkerThread]: - UPnP Service found: [urn:schemas-upnp-org:service:RenderingControl:1]
2024/05/05 20:21:26,517: UL04304: T08370: UPnP_P: [UPP_WorkerThread]: - Player has been enabled.
2024/05/05 20:21:26,517: UL04306: T08370: UPnP_P: [UPP_WorkerThread]: Post PlayerNotify: 7027 [SpecUpdate]
2024/05/05 20:21:26,517: UL04308: T08370: UPnP_P: [UPP_WorkerThread]: UPnP DeviceInfo is ready. Initialize device.
2024/05/05 20:21:26,518: UL04310: T0803c: UPnP_P: Stage: [InitializeDevice]
2024/05/05 20:21:26,518: UL04312: T0803c: UPnP_P: Initialize device.

these optional MIME tags can be attached to the above device, but the Denon device can’t.

so strange…Orz

 

[storaid]

v5.5.0:

2024/05/05 00:56:24,516: UL03731: T09528: UPnP_P: [UPP_WorkerThread]: 1: Initialize UPnP info of the Player.
2024/05/05 00:56:24,516: UL03733: T09528: UPnP_P: [UPP_WorkerThread]: - UPnP is initialized.
2024/05/05 00:56:24,516: UL03735: T09528: UPnP_P: [UPP_WorkerThread]: - UPnP DeviceInfo: USN:[uuid:7ea90380-9dfa-14fd-0080-00a96f05b956::urn:schemas-upnp-org:device:MediaRenderer:1]
2024/05/05 00:56:24,516: UL03737: T09528: UPnP_P: [UPP_WorkerThread]: - Location: http://10.1.1.192:60006/upnp/desc/aios_device/aios_device.xml
2024/05/05 00:56:24,516: UL03739: T09528: UPnP_P: [UPP_WorkerThread]: - Name : Denon Home 350
2024/05/05 00:56:24,516: UL03741: T09528: UPnP_P: [UPP_WorkerThread]: - Model : Denon Home 350
2024/05/05 00:56:24,516: UL03743: T09528: UPnP_P: [UPP_WorkerThread]: - UPnP Service found: [urn:schemas-upnp-org:service:ConnectionManager:1]
2024/05/05 00:56:24,516: UL03745: T09528: UPnP_P: [UPP_WorkerThread]: - Call GetProtocolInfo.
2024/05/05 00:56:24,516: UL03747: T09528: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo ] to: [http://10.1.1.192:60006/upnp/control/renderer_dvc/ConnectionManager]
2024/05/05 00:56:24,534: UL03749: T09528: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo ] finished.
2024/05/05 00:56:24,534: UL03751: T09528: UPnP_P: [UPP_WorkerThread]: - Supported protocols: 13
2024/05/05 00:56:24,534: UL03753: T09528: UPnP_P: [UPP_WorkerThread]: audio/mpeg <- http-get:*:audio/mpeg:*
2024/05/05 00:56:24,534: UL03755: T09528: UPnP_P: [UPP_WorkerThread]: audio/vnd.dlna.adts <- http-get:*:audio/vnd.dlna.adts:*
2024/05/05 00:56:24,534: UL03757: T09528: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:*
2024/05/05 00:56:24,534: UL03759: T09528: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:*
2024/05/05 00:56:24,534: UL03761: T09528: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=48000;channels=2 <- http-get:*:audio/L16;rate=48000;channels=2:*
2024/05/05 00:56:24,534: UL03763: T09528: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=48000;channels=1 <- http-get:*:audio/L16;rate=48000;channels=1:*
2024/05/05 00:56:24,535: UL03765: T09528: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=44100;channels=2 <- http-get:*:audio/L16;rate=44100;channels=2:*
2024/05/05 00:56:24,535: UL03767: T09528: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=44100;channels=1 <- http-get:*:audio/L16;rate=44100;channels=1:*
2024/05/05 00:56:24,535: UL03769: T09528: UPnP_P: [UPP_WorkerThread]: audio/wav <- http-get:*:audio/wav:*
2024/05/05 00:56:24,535: UL03771: T09528: UPnP_P: [UPP_WorkerThread]: audio/mp3 <- http-get:*:audio/mp3:*
2024/05/05 00:56:24,535: UL03773: T09528: UPnP_P: [UPP_WorkerThread]: audio/flac <- http-get:*:audio/flac:*
2024/05/05 00:56:24,535: UL03775: T09528: UPnP_P: [UPP_WorkerThread]: audio/aac <- http-get:*:audio/aac:*
2024/05/05 00:56:24,535: UL03777: T09528: UPnP_P: [UPP_WorkerThread]: audio/m4a <- http-get:*:audio/m4a:*
2024/05/05 00:56:24,535: UL03779: T09528: UPnP_P: [UPP_WorkerThread]: - GetProtocolInfo completed.
2024/05/05 00:56:24,535: UL03781: T09528: UPnP_P: [UPP_WorkerThread]: - UPnP Service found: [urn:schemas-upnp-org:service:RenderingControl:1]
2024/05/05 00:56:24,535: UL03783: T09528: UPnP_P: [UPP_WorkerThread]: - Player has been enabled.
2024/05/05 00:56:24,535: UL03785: T09528: UPnP_P: [UPP_WorkerThread]: Post PlayerNotify: 7025 [SpecUpdate]
2024/05/05 00:56:24,535: UL03787: T09528: UPnP_P: [UPP_WorkerThread]: UPnP DeviceInfo is ready. Initialize device.
2024/05/05 00:56:24,535: UL03789: T04d88: UPnP_P: Stage: [InitializeDevice]

v5.4.6:

2024/05/05 04:18:05,305: UL01963: T00a1c: UPnP_P: [UPP_WorkerThread]: 1: Initialize UPnP info of the Player.
2024/05/05 04:18:05,305: UL01965: T00a1c: UPnP_P: [UPP_WorkerThread]: - UPnP is initialized.
2024/05/05 04:18:05,305: UL01967: T00a1c: UPnP_P: [UPP_WorkerThread]: - UPnP DeviceInfo: USN:[uuid:7ea90380-9dfa-14fd-0080-00a96f05b956::urn:schemas-upnp-org:device:MediaRenderer:1]
2024/05/05 04:18:05,305: UL01969: T00a1c: UPnP_P: [UPP_WorkerThread]: - Location: http://10.1.1.192:60006/upnp/desc/aios_device/aios_device.xml
2024/05/05 04:18:05,305: UL01971: T00a1c: UPnP_P: [UPP_WorkerThread]: - Name : Denon Home 350
2024/05/05 04:18:05,305: UL01973: T00a1c: UPnP_P: [UPP_WorkerThread]: - Model : Denon Home 350
2024/05/05 04:18:05,305: UL01975: T00a1c: UPnP_P: [UPP_WorkerThread]: - UPnP Service found: [urn:schemas-upnp-org:service:ConnectionManager:1]
2024/05/05 04:18:05,305: UL01977: T00a1c: UPnP_P: [UPP_WorkerThread]: - Call GetProtocolInfo.
2024/05/05 04:18:05,306: UL01979: T00a1c: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo] to [http://10.1.1.192:60006/upnp/control/renderer_dvc/ConnectionManager]
2024/05/05 04:18:05,326: UL01981: T00a1c: UPnP_P: [SOAP Request]: SOAP request: [GetProtocolInfo] finished.
2024/05/05 04:18:05,326: UL01983: T00a1c: UPnP_P: [UPP_WorkerThread]: - Supported protocols: 13
2024/05/05 04:18:05,326: UL01985: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/mpeg <- http-get:*:audio/mpeg:*
2024/05/05 04:18:05,326: UL01987: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/vnd.dlna.adts <- http-get:*:audio/vnd.dlna.adts:*
2024/05/05 04:18:05,326: UL01989: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:*
2024/05/05 04:18:05,326: UL01991: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/x-ms-wma <- http-get:*:audio/x-ms-wma:*
2024/05/05 04:18:05,326: UL01993: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=48000;channels=2 <- http-get:*:audio/L16;rate=48000;channels=2:*
2024/05/05 04:18:05,326: UL01995: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=48000;channels=1 <- http-get:*:audio/L16;rate=48000;channels=1:*
2024/05/05 04:18:05,326: UL01997: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=44100;channels=2 <- http-get:*:audio/L16;rate=44100;channels=2:*
2024/05/05 04:18:05,326: UL01999: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/L16;rate=44100;channels=1 <- http-get:*:audio/L16;rate=44100;channels=1:*
2024/05/05 04:18:05,326: UL02001: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/wav <- http-get:*:audio/wav:*
2024/05/05 04:18:05,326: UL02003: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/mp3 <- http-get:*:audio/mp3:*
2024/05/05 04:18:05,326: UL02005: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/flac <- http-get:*:audio/flac:*
2024/05/05 04:18:05,326: UL02007: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/aac <- http-get:*:audio/aac:*
2024/05/05 04:18:05,326: UL02009: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/m4a <- http-get:*:audio/m4a:*
2024/05/05 04:18:05,326: UL02011: T00a1c: UPnP_P: [UPP_WorkerThread]: - Additional protocols:
2024/05/05 04:18:05,326: UL02013: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/dsf
2024/05/05 04:18:05,326: UL02015: T00a1c: UPnP_P: [UPP_WorkerThread]: audio/dff
2024/05/05 04:18:05,326: UL02017: T00a1c: UPnP_P: [UPP_WorkerThread]: - GetProtocolInfo completed.
2024/05/05 04:18:05,326: UL02019: T00a1c: UPnP_P: [UPP_WorkerThread]: - UPnP Service found: [urn:schemas-upnp-org:service:RenderingControl:1]
2024/05/05 04:18:05,326: UL02021: T00a1c: UPnP_P: [UPP_WorkerThread]: - Player has been enabled.
2024/05/05 04:18:05,326: UL02023: T00a1c: UPnP_P: [UPP_WorkerThread]: Post PlayerNotify: 7016 [SpecUpdate]
2024/05/05 04:18:05,326: UL02025: T00a1c: UPnP_P: [UPP_WorkerThread]: UPnP DeviceInfo is ready. Initialize device.
2024/05/05 04:18:05,326: UL02027: T0673c: UPnP_P: Stage: [InitializeDevice]

take a look at the UPnP log

well, no Additional protocols attached in v5.5.0

[storaid]

I don’t find any information about “additional MIME types” settings in the UPnP log

force-sending additional MIME tags is not working?

[storaid]

Hello, Tiki

At this time, the node is not selected. It is just highlighted.

I think that is why the query is not selected.

I think this UI issue can be improved.

Here is my simple idea.

Maybe the Treeview dropdown menu was temporarily disabled if there is no selection existed, like the following:

[storaid]

other F3 button:

 

[storaid]

“NoteBurner Apple Music Converter” it’s just a music stream converter, like the Sidify(Spotify) Music Converter.

These tools have nothing to do with TuneBrowser.

About Sidify:

https://www.sidify.com/en1/

Spotify での音楽を TuneBrowser で再生する方法:

https://www.sidify.jp/guide/play-spotify-music-on-tunebrowser.html

[storaid]

Hello, Tiki

thank you for your improvements.

and, a suggestion:

could you provide English translation for the F3 button?

[storaid]

Hello, Tiki

well, let me explain

It will show only one playing item in the Treeview after clicking Player View and pressing F3, right?

see pic, thanks

After pressing F3 and clicking the right arrow icon from the parent node to expand one Treeview, one inconvenience is that there are many album items in the TreeView, possibly more than 500 or 1000 albums, which may take time to scroll and find that playing item.

I don’t know if there is a better way to quickly move to the item currently being played after expanding the TreeView.

Did I do something wrong?

[storaid]

Hello, Tiki

For step 2, as mentioned earlier, have any hotkeys or buttons to automatically scroll to the currently playing album item in the Treeview?

[storaid]

Hello, Tiki

I’ve tried what you explained.

It’s indeed similar but it’s a bit inconvenient in terms of operation.

I find it requires more steps to achieve the desired result.

step 1: click on the expand arrow next to the parent node.

step 2: scroll the Treeview and search for the playing album.

step 3: click on the playing album item to display the album content in the Library viewer

a little inconvenient (maybe if >500 albums) but these steps can achieve my result as I expected.

[storaid]

Hi, Tiki

”

I’m not sure I understand the point being made in question.

The Tree View can be scrolled. Scrolling allows you to view other nodes.”

I believe the issue I raised is the same as the description of the ref link.

サブフォルダがないものにも△マークが付くようになった

[storaid]

Hello, Tiki

UI issue report

incorrect expand arrow marked to list treeview shown

see pic

thanks

1. normal case: click the “expand arrow” icon to expand

2. issue case:

a. select “parent item”

b. double-click the “parent item” to expand

[storaid]

if no such feature is available, here is my idea.

thanks

[storaid]

Hello, Tiki

this problem has disappeared in the new version.

Thanks

[storaid]

Hello, Tiki

very thanks for your improvements…

it’s very useful.

and, I found a maybe bug? I am not sure.

the expanded treeview is not matched with the relative tree query item after filtering.

see pic

thanks.

[storaid]

Hello, Tiki

“Perhaps the file name in CUE and the actual file names differ in upper/lower case (“MACROSS PLUS ORIGINAL SOUNDTRACK I.APE”). So it seems that referenced file was registered as a different file.

I will consider whether it can be improved.”

Your inference is right.

As the above picture, “MACROSS PLUS ORIGINAL SOUNDTRACK I.ape” this letter case of the actual file’s extension is not matched with CUE content.

Attachments:

1. 

[storaid]

ok

thanks

[storaid]

ok

respect your opinion

thanks for your help.

[storaid]

Hello, Tiki

I have a question

What circumstances allow the “Convert on-the-fly to audio/L16 format” function to work?

Thanks

[storaid]

Thanks, Tiki

I can see the gain message was recorded in the log

2024/04/23 20:58:24,024: UL10852: T00ae0: HTTPServer: [10.1.1.134 :54980]: Socket accepted.
2024/04/23 20:58:24,024: UL10853: T06258: HTTPServer: [10.1.1.134 :54980]: Processing [GET] /TuneID/00007582-RFHE1EEFF5C.dsf_WPRX?DecodeEx=WavProxy
2024/04/23 20:58:24,024: UL10854: T06258: HTTPServer: [10.1.1.134 :54980]: Tune: 00007582-RFHE1EEFF5C.dsf_WPRX ->
2024/04/23 20:58:24,024: UL10855: T06258: HTTPServer: [10.1.1.134 :54980]: \\10.1.1.127\music\[Hi-Res]NieR - Automata Vinyl [A+B Side]\NierAutomata\Weight of the World.dsf
2024/04/23 20:58:24,030: UL10856: T06258: HTTPServer: [10.1.1.134 :54980]: Source: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00007582-RFHE1EEFF5C.dsf_WPRX?DecodeEx=WavProxy]
2024/04/23 20:58:24,030: UL10857: T06258: HTTPServer: [10.1.1.134 :54980]: This is mine.
2024/04/23 20:58:24,030: UL10858: T06258: HTTPServer: [10.1.1.134 :54980]: Mine:Yes Managed:Yes Recorded:Yes Local file: [\\10.1.1.127\music\[Hi-Res]NieR - Automata Vinyl [A+B Side]\NierAutomata\Weight of the World.dsf]
2024/04/23 20:58:24,034: UL10859: T06258: HTTPServer: [10.1.1.134 :54980]: DecodeEx:WavProxy
2024/04/23 20:58:24,034: UL10860: T06258: HTTPServer: [10.1.1.134 :54980]: DecoderParam:
2024/04/23 20:58:24,034: UL10861: T06258: HTTPServer: [10.1.1.134 :54980]: RSMP: No
2024/04/23 20:58:24,034: UL10862: T06258: HTTPServer: [10.1.1.134 :54980]: DMIX: No
2024/04/23 20:58:24,034: UL10863: T06258: HTTPServer: [10.1.1.134 :54980]: DSD : Yes
2024/04/23 20:58:24,034: UL10864: T06258: HTTPServer: [10.1.1.134 :54980]: - xPCM: Auto
2024/04/23 20:58:24,034: UL10865: T06258: HTTPServer: [10.1.1.134 :54980]: - Gain: 6
2024/04/23 20:58:24,086: UL10867: T01a9c: --> EVENT /SR_AVTransport.notify: uuid:0eb808be-1dd2-11b2-b622-f293cc1e7d64
2024/04/23 20:58:24,086: UL10869: T01a9c: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/04/23 20:58:24,086: UL10871: T01a9c: Subscriber: - [AVTransport]: Device notification: Node found: [TransportState ] : [TRANSITIONING]
2024/04/23 20:58:24,086: UL10873: T01a9c: UPnP_P: [Proc:TrState ] <EVT>: PlayerUPnP UPState changed [STOPPED ] to [TRANSITIONING ]

[storaid]

Hi, Tiki

Tested it..

it’s working as I expected.

and, is it possible to make some improvements?

my test:

I did these steps:

1. select one item from the list treeview before the filter

2. use keywords typed to filter and show previous expanded treeview and no previous selection shown

3. clear the filter and show

I will see that there is no expanded view shown.

[storaid]

Or provide a more detail description, like the following:

Convert on-the-fly to Wave format
Convert on-the-fly to Wave format and send.

When set to ‘Yes’ here, it will depend on your audio source. Alternatively, if set to ‘If possible’, it will only apply to Hi-Res audio source.

 

Thanks

[storaid]

Hello, Tiki

I don’t understand your usage environment. I recommended setting “Convert on-the-fly to Wave format” to “If possible”.

Here are my another tests

Device: WiiM Pro Plus(16/24/32, up to 768KHz)

audio/wav <- http-get:*:audio/wav:DLNA.ORG_PN=LPCM
audio/x-wav <- http-get:*:audio/x-wav:DLNA.ORG_PN=LPCM
audio/mpeg <- http-get:*:audio/mpeg:DLNA.ORG_PN=MP3
audio/mpeg <- http-get:*:audio/mpeg:DLNA.ORG_PN=MP3X
audio/x-ms-wma <- http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMABASE
audio/x-ms-wma <- http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMAFULL
audio/x-ms-wma <- http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMAPRO
audio/mpeg <- http-get:*:audio/mpeg:DLNA.ORG_PN=MP2_MPS
audio/mp3 <- http-get:*:audio/mp3:*
audio/wma <- http-get:*:audio/wma:*
audio/mpeg <- http-get:*:audio/mpeg:*
audio/vnd.dlna.adts <- http-get:*:audio/vnd.dlna.adts:DLNA.ORG_PN=AAC_ADTS
audio/vnd.dlna.adts <- http-get:*:audio/vnd.dlna.adts:DLNA.ORG_PN=AAC_ADTS_320
audio/m4a <- http-get:*:audio/m4a:DLNA.ORG_PN=AAC_ISO
audio/aac <- http-get:*:audio/aac:DLNA.ORG_PN=AAC_ISO
audio/ac3 <- http-get:*:audio/ac3:DLNA.ORG_PN=AC3
audio/ogg <- http-get:*:audio/ogg:*
audio/ape <- http-get:*:audio/ape:*
audio/x-ape <- http-get:*:audio/x-ape:*
audio/flac <- http-get:*:audio/flac:*

Convert on-the-fly to Wave format: Yes

Test 1:

source: flac, 16/44.1

result: no transcoding, working

Test 2:

source: flac, 24/48

result: no transcoding, working

Test 3:

source: flac, 24/96

result: no transcoding, working

Test 4:

source: flac, 24/192

result: no transcoding, working

Convert on-the-fly to Wave format: Is possible

Test 1:

source: flac, 16/44.1

result: no transcoding, working

Test 2:

source: flac, 24/48

result: transcoding, working

Test 3:

source: flac, 24/96

result: transcoding, working

Test 4:

source: flac, 24/192

result: transcoding, working

 

These tests indicate that there may be confusion regarding the options ‘If possible’ and ‘Yes’ in that setting.

In my opinion,

“Yes”: This option may lead people to believe that ProxyStream will always remain active, but that is not the case.

“If possible”: This option may lead people to believe that the application will determine whether ProxyStream is active based on the audio source. In reality, ProxyStream is only active for Hi-Res sources.

This is my perspective.

If I’m correct, it’s my personal opinion that changing “Yes” to “Yes (dependent on source)” and “If possible” to “Yes (except for CD Quality)” would be more precise.

You can think about it. Thanks.

“I know what you hope. But it’s going to take a lot of my time to do it.”

okay

[storaid]

Hello, Tiki

“No. Like CD quality source is not applied ProxyStream.”

Are you saying that ProxyStream will not function in the 16-bit/44.1 kHz source?

My test shows that ProxyStream always keeps working when playing Hi-Res audio (24-bit or higher).

It seems that setting that option to ‘Yes’ will depend on the situation.

During Hi-Res audio playback, ProxyStream functionality may not always work in my some tests.

It will depend on the circumstances.

IMO, there is confusion regarding the options ‘If possible’ and ‘Yes’ in that setting.

If I’m right, it’s my personal opinion, perhaps “Yes => Yes(depend on source)” and “If possible => Yes(except CD Quality)” would be better.

“When playing PCM (Wav) over UPnP, the application does not know the performance limits of the device.
Now you are talking about bit depth limits, but in the future, someone may also be talking about sample rate limits.”

Here’s what I think.

I think that adding a bit-depth dropdown menu item in the UPnP device settings, such as Limited Bit-depth (16/24/32, default: No), would improve ProxyStream’s ability to detect the source’s bit-depth(e.g.: 24b/96k flac file) and determine whether it should be active.

This would allow the application to check if the audio file’s bit-depth exceeds the set value.

If it does, ProxyStream would be triggered (when the item is set to 16, 24, or 32); otherwise, it would remain “default”.

This setting is only used for checking. (Of course, the ProxyStream of bit-depth depends on the source would be better. If the bit-depth item has been set in the device settings, then the bit-depth of transcoding will not exceed that item value, and it’s max value.)

For some active speakers or h/w music systems(support UPnP), such as very expensive DEVIALET or B&W, the bit depth they can support is mostly limited.

Sometimes, that bit-depth setting may be helpful for them.

Thanks

[storaid]

Hi, Tiki

‘If possible’ has a positive meaning. Whenever possible, ProxyStream will be used.

Ok,

That will result in ProxyStream always working. it’s right?

Is it possible to add the bit-depth limitation setting, such as Limited Bit-depth (16/24/32, default: No), to the UPnP device configuration?

I think this would be helpful for on-the-fly transcoding to detect bit-depth.

[storaid]

well, I’m confused…

It seems that setting this option to ‘If possible’ will result in ProxyStream always working.

Source: flac/24bits@96KHz

2024/04/20 10:21:40,996: UL11332: T06e54: UPnP_P: PlayImpl: Doing SetAVTransportURI: AVTType::CurrTune
2024/04/20 10:21:40,996: UL11334: T06e54: UPnP_P: PlayImpl: Checking ProxyStream for file: [\\10.1.1.127\music\[Hi-Res]TVアニメ「Re：ゼロから始める異世界生活」サウンドトラック／末廣健一郎\1-0008002799.flac]
2024/04/20 10:21:40,996: UL11336: T06e54: UPnP_P: WPLimitied=Yes : WavProxyLimitedUse==Yes && (DSD==No || Subsong==No || HiRes==Yes)
2024/04/20 10:21:40,996: UL11338: T06e54: UPnP_P: WavProxy_OK=Yes : UseWavProxy==ExDecd::IfP && MIME_OK==Yes (audio/wav) && WPLimitied==Yes
2024/04/20 10:21:40,996: UL11340: T06e54: UPnP_P: AudioL16_OK=No : UseAudioL16==ExDecd::Yes && MIME_OK==No (audio/L16)
2024/04/20 10:21:40,996: UL11342: T06e54: UPnP_P: WavProxy_Req=Yes : WavProxy_OK==Yes && (UseWavProxy(ExDecd::IfP)==ExDecd::IfP || Tag==No (USE_WAV_PROXY))
2024/04/20 10:21:40,996: UL11344: T06e54: UPnP_P: AudioL16_Req=No : AudioL16_OK==No && (UseAudioL16(ExDecd::Yes)==ExDecd::IfP || Tag==No (USE_AUDIO_L16))
2024/04/20 10:21:40,996: UL11346: T06e54: UPnP_P: ProxyStream=Yes : MIME_NG==No (audio/flac) || Subsong==No || WavProxy_Req==Yes || AudioL16_Req==No || GenToFixedDrive==No || UseProxyStream==ProxyStrm::Auto
2024/04/20 10:21:40,996: UL11348: T06e54: UPnP_P: PrxType=WavProxy : WavProxy_Req==Yes AudioL16_Req==No WavProxy_OK==Yes AudioL16_OK==No
2024/04/20 10:21:40,996: UL11350: T06e54: UPnP_P: ProxyStream is [WavProxy] (WPRX)
2024/04/20 10:21:40,997: UL11352: T06e54: UPnP_P: [SetAVTransportURI]: File: 10.1.1.127 [\\10.1.1.127\music\[Hi-Res]TVアニメ「Re：ゼロから始める異世界生活」サウンドトラック／末廣健一郎\1-0008002799.flac]
2024/04/20 10:21:40,997: UL11354: T06e54: UPnP_P: [SetAVTransportURI]: URI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00016517-RFH8BCFA511.flac_WPRX?DecodeEx=WavProxy]
2024/04/20 10:21:40,997: UL11356: T06e54: UPnP_P: [SetAVTransportURI]: <DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
2024/04/20 10:21:40,997: UL11358: T06e54: UPnP_P: [SetAVTransportURI]: <item>
2024/04/20 10:21:40,997: UL11360: T06e54: UPnP_P: [SetAVTransportURI]: <res protocolInfo="http-get:*:audio/wav:*" duration="0:03:49.183" bitsPerSample="24" nrAudioChannels="2" sampleFrequency="96000" DecodeEx="WavProxy">http://10.1.1.156:29400/TuneID/00016517-RFH8BCFA511.flac_WPRX?DecodeEx=WavProxy</res>
2024/04/20 10:21:40,997: UL11362: T06e54: UPnP_P: [SetAVTransportURI]: <dc:title>愛と漆黒の輪舞曲 - MainTheme -</dc:title>
2024/04/20 10:21:40,997: UL11364: T06e54: UPnP_P: [SetAVTransportURI]: <dc:creator>末廣健一郎</dc:creator>
2024/04/20 10:21:40,997: UL11366: T06e54: UPnP_P: [SetAVTransportURI]: <dc:publisher>KADOKAWA</dc:publisher>
2024/04/20 10:21:40,997: UL11368: T06e54: UPnP_P: [SetAVTransportURI]: <upnp:album>TVアニメ「Re：ゼロから始める異世界生活」サウンドトラックCD</upnp:album>
2024/04/20 10:21:40,997: UL11370: T06e54: UPnP_P: [SetAVTransportURI]: <upnp:artist>末廣健一郎</upnp:artist>
2024/04/20 10:21:40,997: UL11372: T06e54: UPnP_P: [SetAVTransportURI]: <upnp:artist role="AlbumArtist">末廣健一郎</upnp:artist>
2024/04/20 10:21:40,997: UL11374: T06e54: UPnP_P: [SetAVTransportURI]: <upnp:artist role="Composer">末廣健一郎</upnp:artist>
2024/04/20 10:21:40,997: UL11376: T06e54: UPnP_P: [SetAVTransportURI]: <upnp:originalTrackNumber>01</upnp:originalTrackNumber>
2024/04/20 10:21:40,997: UL11378: T06e54: UPnP_P: [SetAVTransportURI]: <upnp:albumArtURI>http://10.1.1.156:29400/Image/20391.6d7b5df6-6ce3-4bea-b9025f49142ee028.jpg</upnp:albumArtURI>
2024/04/20 10:21:40,997: UL11380: T06e54: UPnP_P: [SetAVTransportURI]: <upnp:class>object.item.audioItem.musicTrack</upnp:class>
2024/04/20 10:21:40,997: UL11382: T06e54: UPnP_P: [SetAVTransportURI]: </item>
2024/04/20 10:21:40,997: UL11384: T06e54: UPnP_P: [SetAVTransportURI]: </DIDL-Lite>
2024/04/20 10:21:40,997: UL11386: T06e54: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/AVTransport]
2024/04/20 10:21:41,139: UL11388: T06e54: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] finished.
2024/04/20 10:21:41,140: UL11390: T06e54: UPnP_P: [SetAVTransportURI]: [Denon Home 350] accepted current tune by SetAVTransportURI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00016517-RFH8BCFA511.flac_WPRX?DecodeEx=WavProxy]
2024/04/20 10:21:41,140: UL11392: T06e54: UPnP_P: PlayImpl: Keep SUBSCRIBE by user request.
2024/04/20 10:21:41,170: UL11394: T06e54: UPnP_P: PlayImpl: RelTimeEvent: No
2024/04/20 10:21:41,170: UL11396: T06e54: UPnP_P: PlayImpl: SyncActive: No
2024/04/20 10:21:41,170: UL11398: T06e54: UPnP_P: PlayImpl: Post Play.
2024/04/20 10:21:41,201: UL11400: T06e54: UPnP_P: [SOAP Request]: SOAP request: [Play ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/AVTransport]
2024/04/20 10:21:41,281: UL11402: T06e54: UPnP_P: [SOAP Request]: SOAP request: [Play ] finished.
2024/04/20 10:21:41,281: UL11404: T06e54: UPnP_P: PlayImpl: Waiting for state change in 10000 ms.
2024/04/20 10:21:41,321: UL11406: T024a4: UPnP_P: [Proc:TrState ] <POL>: PlayerUPnP UPState changed [STOPPED ] to [TRANSITIONING ]
2024/04/20 10:21:41,321: UL11408: T024a4: UPnP_P: [Proc:TrState ] <POL>: State : TRANSITIONING
2024/04/20 10:21:41,341: UL11410: T06e54: UPnP_P: PlayImpl: State change detected.
2024/04/20 10:21:41,341: UL11412: T06e54: UPnP_P: PlayImpl: Waiting for state change has done in 60 ms.
2024/04/20 10:21:41,341: UL11414: T06e54: UPnP_P: PlayImpl: Waiting for state change before seek in 10000 ms.
2024/04/20 10:21:41,346: UL11415: T07740: HTTPServer: [10.1.1.192 :54923]: Socket accepted.
2024/04/20 10:21:41,346: UL11416: T00b60: HTTPServer: [10.1.1.192 :54923]: Processing [GET] /TuneID/00016517-RFH8BCFA511.flac_WPRX?DecodeEx=WavProxy
2024/04/20 10:21:41,346: UL11417: T00b60: HTTPServer: [10.1.1.192 :54923]: Tune: 00016517-RFH8BCFA511.flac_WPRX ->
2024/04/20 10:21:41,346: UL11418: T00b60: HTTPServer: [10.1.1.192 :54923]: \\10.1.1.127\music\[Hi-Res]TVアニメ「Re：ゼロから始める異世界生活」サウンドトラック／末廣健一郎\1-0008002799.flac
2024/04/20 10:21:41,350: UL11419: T00b60: HTTPServer: [10.1.1.192 :54923]: DecodeEx:WavProxy

[storaid]

Hello, Tiki

Tested it again…

Device: Denon Home 350 (32-bit depth is unsupported)

Case 1:

Source: wav/32bits@96KHz

Result: working with on-the-fly transcoding

2024/04/20 10:04:02,355: UL05924: T06e54: UPnP_P: PlayImpl: Doing SetAVTransportURI: AVTType::CurrTune
2024/04/20 10:04:02,355: UL05926: T06e54: UPnP_P: PlayImpl: Checking ProxyStream for file: [\\10.1.1.127\music\[Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST\01_邪王真眼健在!.wav]
2024/04/20 10:04:02,355: UL05928: T06e54: UPnP_P: WPLimitied=Yes : WavProxyLimitedUse==Yes && (DSD==No || Subsong==No || HiRes==Yes)
2024/04/20 10:04:02,355: UL05930: T06e54: UPnP_P: WavProxy_OK=Yes : UseWavProxy==ExDecd::IfP && MIME_OK==Yes (audio/wav) && WPLimitied==Yes
2024/04/20 10:04:02,355: UL05932: T06e54: UPnP_P: AudioL16_OK=No : UseAudioL16==ExDecd::Yes && MIME_OK==No (audio/L16)
2024/04/20 10:04:02,355: UL05934: T06e54: UPnP_P: WavProxy_Req=Yes : WavProxy_OK==Yes && (UseWavProxy(ExDecd::IfP)==ExDecd::IfP || Tag==No (USE_WAV_PROXY))
2024/04/20 10:04:02,355: UL05936: T06e54: UPnP_P: AudioL16_Req=No : AudioL16_OK==No && (UseAudioL16(ExDecd::Yes)==ExDecd::IfP || Tag==No (USE_AUDIO_L16))
2024/04/20 10:04:02,355: UL05938: T06e54: UPnP_P: ProxyStream=Yes : MIME_NG==No (audio/wav) || Subsong==No || WavProxy_Req==Yes || AudioL16_Req==No || GenToFixedDrive==No || UseProxyStream==ProxyStrm::Auto
2024/04/20 10:04:02,355: UL05940: T06e54: UPnP_P: PrxType=WavProxy : WavProxy_Req==Yes AudioL16_Req==No WavProxy_OK==Yes AudioL16_OK==No
2024/04/20 10:04:02,355: UL05942: T06e54: UPnP_P: ProxyStream is [WavProxy] (WPRX)
2024/04/20 10:04:02,356: UL05944: T06e54: UPnP_P: [SetAVTransportURI]: File: 10.1.1.127 [\\10.1.1.127\music\[Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST\01_邪王真眼健在!.wav]
2024/04/20 10:04:02,356: UL05946: T06e54: UPnP_P: [SetAVTransportURI]: URI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00011446-RFHD43DEFFD.wav_WPRX?DecodeEx=WavProxy]
2024/04/20 10:04:02,356: UL05948: T06e54: UPnP_P: [SetAVTransportURI]:
2024/04/20 10:04:02,356: UL05950: T06e54: UPnP_P: [SetAVTransportURI]:
2024/04/20 10:04:02,356: UL05952: T06e54: UPnP_P: [SetAVTransportURI]: http://10.1.1.156:29400/TuneID/00011446-RFHD43DEFFD.wav_WPRX?DecodeEx=WavProxy
2024/04/20 10:04:02,356: UL05954: T06e54: UPnP_P: [SetAVTransportURI]: 01_邪王真眼健在!
2024/04/20 10:04:02,356: UL05956: T06e54: UPnP_P: [SetAVTransportURI]: Unknown Artist
2024/04/20 10:04:02,356: UL05958: T06e54: UPnP_P: [SetAVTransportURI]: [Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST
2024/04/20 10:04:02,356: UL05960: T06e54: UPnP_P: [SetAVTransportURI]: Unknown Artist
2024/04/20 10:04:02,356: UL05962: T06e54: UPnP_P: [SetAVTransportURI]: http://10.1.1.156:29400/Image/08201.7af6d856-a704-44d0-b84509aff1e5f0b6.jpg
2024/04/20 10:04:02,356: UL05964: T06e54: UPnP_P: [SetAVTransportURI]: object.item.audioItem.musicTrack
2024/04/20 10:04:02,356: UL05966: T06e54: UPnP_P: [SetAVTransportURI]:
2024/04/20 10:04:02,356: UL05968: T06e54: UPnP_P: [SetAVTransportURI]:
2024/04/20 10:04:02,356: UL05970: T06e54: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/AVTransport]
2024/04/20 10:04:02,481: UL05972: T06e54: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] finished.
2024/04/20 10:04:02,481: UL05974: T06e54: UPnP_P: [SetAVTransportURI]: [Denon Home 350] accepted current tune by SetAVTransportURI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00011446-RFHD43DEFFD.wav_WPRX?DecodeEx=WavProxy]
2024/04/20 10:04:02,481: UL05976: T06e54: UPnP_P: PlayImpl: Keep SUBSCRIBE by user request.
2024/04/20 10:04:02,512: UL05978: T06e54: UPnP_P: PlayImpl: RelTimeEvent: No
2024/04/20 10:04:02,512: UL05980: T06e54: UPnP_P: PlayImpl: SyncActive: No
2024/04/20 10:04:02,512: UL05982: T06e54: UPnP_P: PlayImpl: Post Play.
2024/04/20 10:04:02,512: UL05984: T06e54: UPnP_P: [SOAP Request]: SOAP request: [Play ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/AVTransport]
2024/04/20 10:04:02,556: UL05986: T06e54: UPnP_P: [SOAP Request]: SOAP request: [Play ] finished.
2024/04/20 10:04:02,556: UL05988: T06e54: UPnP_P: PlayImpl: Waiting for state change in 10000 ms.
2024/04/20 10:04:02,619: UL05989: T07740: HTTPServer: [10.1.1.192 :54873]: Socket accepted.
2024/04/20 10:04:02,619: UL05990: T00b60: HTTPServer: [10.1.1.192 :54873]: Processing [GET] /TuneID/00011446-RFHD43DEFFD.wav_WPRX?DecodeEx=WavProxy
2024/04/20 10:04:02,619: UL05991: T00b60: HTTPServer: [10.1.1.192 :54873]: Tune: 00011446-RFHD43DEFFD.wav_WPRX ->
2024/04/20 10:04:02,619: UL05992: T00b60: HTTPServer: [10.1.1.192 :54873]: \\10.1.1.127\music\[Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST\01_邪王真眼健在!.wav
2024/04/20 10:04:02,625: UL05993: T00b60: HTTPServer: [10.1.1.192 :54873]: DecodeEx:WavProxy
2024/04/20 10:04:02,859: UL05997: T00b60: HTTPServer: [10.1.1.192 :54873]: Begin monitoring playback.

Case 2:

Source: m4a/32bits@96KHz

Result: working with on-the-fly transcoding

2024/04/20 10:07:42,196: UL07021: T06e54: UPnP_P: PlayImpl: Doing SetAVTransportURI: AVTType::CurrTune
2024/04/20 10:07:42,196: UL07023: T06e54: UPnP_P: PlayImpl: Checking ProxyStream for file: [\\10.1.1.127\music\[Hi-Res]calling【アニメ盤】\01_calling.m4a]
2024/04/20 10:07:42,196: UL07025: T06e54: UPnP_P: WPLimitied=Yes : WavProxyLimitedUse==Yes && (DSD==No || Subsong==No || HiRes==Yes)
2024/04/20 10:07:42,196: UL07027: T06e54: UPnP_P: WavProxy_OK=Yes : UseWavProxy==ExDecd::IfP && MIME_OK==Yes (audio/wav) && WPLimitied==Yes
2024/04/20 10:07:42,196: UL07029: T06e54: UPnP_P: AudioL16_OK=No : UseAudioL16==ExDecd::Yes && MIME_OK==No (audio/L16)
2024/04/20 10:07:42,196: UL07031: T06e54: UPnP_P: WavProxy_Req=Yes : WavProxy_OK==Yes && (UseWavProxy(ExDecd::IfP)==ExDecd::IfP || Tag==No (USE_WAV_PROXY))
2024/04/20 10:07:42,196: UL07033: T06e54: UPnP_P: AudioL16_Req=No : AudioL16_OK==No && (UseAudioL16(ExDecd::Yes)==ExDecd::IfP || Tag==No (USE_AUDIO_L16))
2024/04/20 10:07:42,196: UL07035: T06e54: UPnP_P: ProxyStream=Yes : MIME_NG==Yes (audio/mp4) || Subsong==No || WavProxy_Req==Yes || AudioL16_Req==No || GenToFixedDrive==No || UseProxyStream==ProxyStrm::Auto
2024/04/20 10:07:42,196: UL07037: T06e54: UPnP_P: PrxType=WavProxy : WavProxy_Req==Yes AudioL16_Req==No WavProxy_OK==Yes AudioL16_OK==No
2024/04/20 10:07:42,196: UL07039: T06e54: UPnP_P: ProxyStream is [WavProxy] (WPRX)
2024/04/20 10:07:42,197: UL07041: T06e54: UPnP_P: [SetAVTransportURI]: File: 10.1.1.127 [\\10.1.1.127\music\[Hi-Res]calling【アニメ盤】\01_calling.m4a]
2024/04/20 10:07:42,197: UL07043: T06e54: UPnP_P: [SetAVTransportURI]: URI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00013125-RFH9CE9C489.m4a_WPRX?DecodeEx=WavProxy]
2024/04/20 10:07:42,197: UL07045: T06e54: UPnP_P: [SetAVTransportURI]:
2024/04/20 10:07:42,197: UL07047: T06e54: UPnP_P: [SetAVTransportURI]:
2024/04/20 10:07:42,197: UL07049: T06e54: UPnP_P: [SetAVTransportURI]: http://10.1.1.156:29400/TuneID/00013125-RFH9CE9C489.m4a_WPRX?DecodeEx=WavProxy
2024/04/20 10:07:42,197: UL07051: T06e54: UPnP_P: [SetAVTransportURI]: calling
2024/04/20 10:07:42,197: UL07053: T06e54: UPnP_P: [SetAVTransportURI]: fhana
2024/04/20 10:07:42,197: UL07055: T06e54: UPnP_P: [SetAVTransportURI]: calling【アニメ盤】（32bit float / 96kHz）
2024/04/20 10:07:42,197: UL07057: T06e54: UPnP_P: [SetAVTransportURI]: fhana
2024/04/20 10:07:42,197: UL07059: T06e54: UPnP_P: [SetAVTransportURI]: 佐藤純一
2024/04/20 10:07:42,197: UL07061: T06e54: UPnP_P: [SetAVTransportURI]: 1
2024/04/20 10:07:42,197: UL07063: T06e54: UPnP_P: [SetAVTransportURI]: http://10.1.1.156:29400/Image/12501.05cd738b-b6c4-4bca-93210ba16aa53d7c.jpg
2024/04/20 10:07:42,197: UL07065: T06e54: UPnP_P: [SetAVTransportURI]: object.item.audioItem.musicTrack
2024/04/20 10:07:42,197: UL07067: T06e54: UPnP_P: [SetAVTransportURI]:
2024/04/20 10:07:42,197: UL07069: T06e54: UPnP_P: [SetAVTransportURI]:
2024/04/20 10:07:42,197: UL07071: T06e54: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/AVTransport]
2024/04/20 10:07:42,231: UL07077: T06e54: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] finished.
2024/04/20 10:07:42,231: UL07079: T06e54: UPnP_P: [SetAVTransportURI]: [Denon Home 350] accepted current tune by SetAVTransportURI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00013125-RFH9CE9C489.m4a_WPRX?DecodeEx=WavProxy]
2024/04/20 10:07:42,231: UL07081: T06e54: UPnP_P: PlayImpl: Keep SUBSCRIBE by user request.
2024/04/20 10:07:42,261: UL07083: T06e54: UPnP_P: PlayImpl: RelTimeEvent: No
2024/04/20 10:07:42,261: UL07085: T06e54: UPnP_P: PlayImpl: SyncActive: No
2024/04/20 10:07:42,261: UL07087: T06e54: UPnP_P: PlayImpl: Post Play.
2024/04/20 10:07:42,261: UL07089: T06e54: UPnP_P: [SOAP Request]: SOAP request: [Play ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/AVTransport]
2024/04/20 10:07:42,282: UL07091: T06e54: UPnP_P: [SOAP Request]: SOAP request: [Play ] finished.
2024/04/20 10:07:42,282: UL07093: T06e54: UPnP_P: PlayImpl: Waiting for state change in 10000 ms.
2024/04/20 10:07:42,347: UL07094: T07740: HTTPServer: [10.1.1.192 :54887]: Socket accepted.
2024/04/20 10:07:42,347: UL07095: T01144: HTTPServer: [10.1.1.192 :54887]: Processing [GET] /TuneID/00013125-RFH9CE9C489.m4a_WPRX?DecodeEx=WavProxy
2024/04/20 10:07:42,347: UL07096: T01144: HTTPServer: [10.1.1.192 :54887]: Tune: 00013125-RFH9CE9C489.m4a_WPRX ->
2024/04/20 10:07:42,347: UL07097: T01144: HTTPServer: [10.1.1.192 :54887]: \\10.1.1.127\music\[Hi-Res]calling【アニメ盤】\01_calling.m4a
2024/04/20 10:07:42,353: UL07098: T01144: HTTPServer: [10.1.1.192 :54887]: DecodeEx:WavProxy
2024/04/20 10:07:42,564: UL07100: T01144: HTTPServer: [10.1.1.192 :54887]: Begin monitoring playback.

it’s weird.

setting “Convert on-the-fly to Wave format” as “Yes” will cause failed wav/32bit@96KHz playback.

how does it work with this setting?

[storaid]

Hello, Tiki

Tested it by setting UseProxyStream as “Always”

it’s working, but that means ProxyStream always starts working every time playing track.

2024/04/19 21:50:09,900: UL05258: T021dc: UPnP_P: Current tune lost sequence position.
2024/04/19 21:50:09,901: UL05260: T021dc: UPnP_P: Current tune: [\\10.1.1.127\music\[Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST\01_邪王真眼健在!.wav:0]
2024/04/19 21:50:09,902: UL05262: T021dc: UPnP_P: Post PlayerNotify: 7015 [Update]
2024/04/19 21:50:09,902: UL05264: T021dc: UPnP_P: Current tune adjusted to sequence position 0.
2024/04/19 21:50:09,902: UL05266: T021dc: UPnP_P: Apply current seek value to player: 0.0
2024/04/19 21:50:09,903: UL05268: T021dc: UPnP_P: GUI part detected tune change: [\\10.1.1.127\music\[Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST\01_邪王真眼健在!.wav:0]
2024/04/19 21:50:09,907: UL05274: T021dc: UPnP_P: Proc PlayerNotify: 7015 [Update]
2024/04/19 21:50:09,956: UL05276: T02334: UpdateSVar: [Info ]: [(ImageUrl) ]: [http://10.1.1.156:29400/Image/08201.7af6d856-a704-44d0-b84509aff1e5f0b6.jpg]
2024/04/19 21:50:09,956: UL05277: T02334: UpdateSVar: [Info ]: [Metadata ]: [<DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elem...]
2024/04/19 21:50:09,956: UL05278: T02334: UpdateSVar: [TR_AVT ]: [CurrentTrackMetaData]: [<DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elem...]
2024/04/19 21:50:09,956: UL05279: T02334: UpdateSVar: [Info ]: [TrackCount ]: [6]
2024/04/19 21:50:10,165: UL05290: T021dc: UPnP_P: Requesting player command: [Play]
2024/04/19 21:50:10,165: UL05292: T056e0: UPnP_P: Processing player command: [Play]
2024/04/19 21:50:10,165: UL05294: T056e0: UPnP_P: Proc: [Play]
2024/04/19 21:50:10,165: UL05296: T056e0: UPnP_P: Playback starts from the top.
2024/04/19 21:50:10,165: UL05298: T056e0: UPnP_P: PlayerPlayStatus: [Stop] -> [Playing]
2024/04/19 21:50:10,165: UL05300: T056e0: UPnP_P: Post PlayerNotify: 0001 [PlayStatus]
2024/04/19 21:50:10,165: UL05302: T056e0: UPnP_P: Post PlayerNotify: 7016 [PlaybackBegin]
2024/04/19 21:50:10,165: UL05304: T056e0: UPnP_P: PlayImpl: Post PlayerNotify: 7017 [SpecUpdate]
2024/04/19 21:50:10,165: UL05306: T056e0: UPnP_P: PlayImpl: Post [Stop] before SetAVTransportURI by user setting.
2024/04/19 21:50:10,166: UL05308: T056e0: UPnP_P: [SOAP Request]: SOAP request: [Stop ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/AVTransport]
2024/04/19 21:50:10,166: UL05310: T021dc: UPnP_P: Proc PlayerNotify: 0001 [PlayStatus]
2024/04/19 21:50:10,171: UL05312: T021dc: UPnP_P: Proc PlayerNotify: 7016 [PlaybackBegin]
2024/04/19 21:50:10,183: UL05314: T056e0: UPnP_P: [SOAP Request]: SOAP request: [Stop ] finished.
2024/04/19 21:50:10,183: UL05316: T056e0: UPnP_P: PlayImpl: Doing SetAVTransportURI: AVTType::CurrTune
2024/04/19 21:50:10,183: UL05318: T056e0: UPnP_P: PlayImpl: Checking ProxyStream for file: [\\10.1.1.127\music\[Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST\01_邪王真眼健在!.wav]
2024/04/19 21:50:10,183: UL05320: T056e0: UPnP_P: WPLimitied=Yes : WavProxyLimitedUse==Yes && (DSD==No || Subsong==No || HiRes==Yes)
2024/04/19 21:50:10,183: UL05322: T056e0: UPnP_P: WavProxy_OK=Yes : UseWavProxy==ExDecd::Yes && MIME_OK==Yes (audio/wav) && WPLimitied==Yes
2024/04/19 21:50:10,183: UL05324: T056e0: UPnP_P: AudioL16_OK=No : UseAudioL16==ExDecd::Yes && MIME_OK==No (audio/L16)
2024/04/19 21:50:10,183: UL05326: T056e0: UPnP_P: WavProxy_Req=No : WavProxy_OK==Yes && (UseWavProxy(ExDecd::Yes)==ExDecd::IfP || Tag==No (USE_WAV_PROXY))
2024/04/19 21:50:10,183: UL05328: T056e0: UPnP_P: AudioL16_Req=No : AudioL16_OK==No && (UseAudioL16(ExDecd::Yes)==ExDecd::IfP || Tag==No (USE_AUDIO_L16))
2024/04/19 21:50:10,183: UL05330: T056e0: UPnP_P: ProxyStream=Yes : MIME_NG==No (audio/wav) || Subsong==No || WavProxy_Req==No || AudioL16_Req==No || GenToFixedDrive==No || UseProxyStream==ProxyStrm::Always
2024/04/19 21:50:10,183: UL05332: T056e0: UPnP_P: PrxType=WavProxy : WavProxy_Req==No AudioL16_Req==No WavProxy_OK==Yes AudioL16_OK==No
2024/04/19 21:50:10,184: UL05334: T056e0: UPnP_P: ProxyStream is [WavProxy] (WPRX)
2024/04/19 21:50:10,184: UL05336: T056e0: UPnP_P: [SetAVTransportURI]: File: 10.1.1.127 [\\10.1.1.127\music\[Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST\01_邪王真眼健在!.wav]
2024/04/19 21:50:10,184: UL05338: T056e0: UPnP_P: [SetAVTransportURI]: URI: 10.1.1.156 [http://10.1.1.156:29400/TuneID/00011446-RFHD43DEFFD.wav_WPRX?DecodeEx=WavProxy]
2024/04/19 21:50:10,184: UL05340: T056e0: UPnP_P: [SetAVTransportURI]: <DIDL-Lite xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/">
2024/04/19 21:50:10,184: UL05342: T056e0: UPnP_P: [SetAVTransportURI]: <item>
2024/04/19 21:50:10,184: UL05344: T056e0: UPnP_P: [SetAVTransportURI]: <res protocolInfo="http-get:*:audio/wav:*" duration="0:01:55.133" bitsPerSample="24" nrAudioChannels="2" sampleFrequency="96000" DecodeEx="WavProxy">http://10.1.1.156:29400/TuneID/00011446-RFHD43DEFFD.wav_WPRX?DecodeEx=WavProxy</res>
2024/04/19 21:50:10,184: UL05346: T056e0: UPnP_P: [SetAVTransportURI]: <dc:title>01_邪王真眼健在!</dc:title>
2024/04/19 21:50:10,184: UL05348: T056e0: UPnP_P: [SetAVTransportURI]: <dc:creator>Unknown Artist</dc:creator>
2024/04/19 21:50:10,184: UL05350: T056e0: UPnP_P: [SetAVTransportURI]: <upnp:album>[Hi-Res]『映画 中二病でも恋がしたい！ -Take On Me-』OST</upnp:album>
2024/04/19 21:50:10,184: UL05352: T056e0: UPnP_P: [SetAVTransportURI]: <upnp:artist>Unknown Artist</upnp:artist>
2024/04/19 21:50:10,184: UL05354: T056e0: UPnP_P: [SetAVTransportURI]: <upnp:albumArtURI>http://10.1.1.156:29400/Image/08201.7af6d856-a704-44d0-b84509aff1e5f0b6.jpg</upnp:albumArtURI>
2024/04/19 21:50:10,184: UL05356: T056e0: UPnP_P: [SetAVTransportURI]: <upnp:class>object.item.audioItem.musicTrack</upnp:class>
2024/04/19 21:50:10,184: UL05358: T056e0: UPnP_P: [SetAVTransportURI]: </item>
2024/04/19 21:50:10,184: UL05360: T056e0: UPnP_P: [SetAVTransportURI]: </DIDL-Lite>
2024/04/19 21:50:10,184: UL05362: T056e0: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] to: 10.1.1.192 [http://10.1.1.192:60006/upnp/control/renderer_dvc/AVTransport]
2024/04/19 21:50:10,186: UL05364: T021dc: UPnP_P: Proc PlayerNotify: 7017 [SpecUpdate]
2024/04/19 21:50:10,201: UL05365: T01f4c: --> 10.1.1.192 :29500 EVENT /SR_AVTransport.notify: uuid:8f0f62f8-fe53-11ee-8b6e-8ccc382a7e9a
2024/04/19 21:50:10,201: UL05367: T01f4c: Subscriber: [Event]: Received EVENT: [AVTransport]
2024/04/19 21:50:10,252: UL05371: T056e0: UPnP_P: [SOAP Request]: SOAP request: [SetAVTransportURI ] finished.

[storaid]

Like Foobar2000, it has similar settings.

[storaid]

IMO, the following flow:

[storaid]

sorry

that log is about WASPI-exclusive..

plz check the new log

 

Attachments:

1. 20240414_player_log-1.txt

[storaid]

Hi,

Player Log

thanks

Attachments:

1. 20240414_player_log.txt

[storaid]

Hi, Tiki

Tested it between +0db and +6db.

seems like it’s working

What information can I find in the Log to determine if this function is working?

[storaid]

Hello, Tiki

I downloaded it again and confirmed that it’s v1744 now.

and, tested 2ch and multi-channel format files, including DSD file format.

it’s good working now..

Thanks

[storaid]

Hi,

the link for AVX2 is still v1743 I checked, not v1744

[storaid]

Hello, Tiki

No AVX2 version?

[storaid]

Hi,

waiting for your new release.

now the download link is still v1743.

[storaid]

Hello, Tiki

I had a test about speaker channel settings…

seems like it’s working fine with 2-ch

[storaid]

my running audio driver is nVIDIA HDMI GPU Driver.

[storaid]

Hello, Tiki

thanks for ur feature enhancements..

tested DSD64, DSD128 files

it’s working well..

and, I have a question..

it’s possible to set Gain(db) like DSD-PCM Conversion????

[storaid]

Hello, Tiki

tested it.

now there is no error thrown in playing status…

Thanks

[storaid]

Hi,

I attempted to use the WinDbg tool for detection, trying to find some clues.

When TuneBrowser is using Creative ASIO device, it triggers the following exception:

This exception may be expected and handled. CtUsAs64!DllInstall+0x721b: 00000000<code>61dc02d7 f30f6f0408 movdqu xmm0,xmmword ptr [rax+rcx] ds:00000212</code>5044a008=????????????????????????????????

Based on my investigation, it is possible that it is affected by the Memory Integrity security feature of Windows Security.

Therefore, I disabled it and tried again.

Now it’s working.

I don’t know why this happened!

Because when I tested under Foobar 2000, the Creative ASIO Driver worked perfectly fine.

[storaid]

Hello, Tiki

Seems like this problem has been resolved…

Thank you

[storaid]

Hello, Tiki

I forgot you use libdsd2pcm for conversion
Sorry for causing you trouble…(=´ω`=)

1. and 2. suggestions, You can consider taking a look if it’s possible.

[storaid]

Hello, Tiki

I thought the DSD to PCM Conversion for UPnP renderer and local devices was the same…

You’re right, for the UPnP renderer, it currently only outputs L16/44.1KHz.

I would like to offer some suggestions.

Many current streaming DAC devices generally can decode audio files up to 384KHz…

At a minimum, there should be support for 192KHz…

1. Is it possible to make some adjustments in the settings? (As there might still be some streaming DACs that only support up to 192KHz)

For example:

– DSD64: 88.2KHz

– DSD128: 176.4KHz

And then the maximum output value being 192KHz.

2. Add an option for selecting the Maximum Bit depth: 16, 24, 32

I hope you can consider these suggestions.

Also, when using UPnP Renderer output, the waiting time for the DSD to PCM Conversion process is much longer than in Foobar2K…

In Foobar2K, the DSD to PCM conversion process is almost instantaneous and very fast.

Why is the conversion speed slower in TuneBrowser?

Thanks

[storaid]

Hello, Tiki

The DoPE feature is indeed less common.

I noticed that some software, such as JRiver, offers support for this feature.

That’s why I wanted to ask if it’s possible to implement this functionality, as there are indeed some streaming DACs that can support DoP decoding through UPnP.

I respect your opinion.

Thank you

[投稿者]

投稿