フォーラム › TuneBrowser › UI issue: timebar’s progress bar display › 返信先: UI issue: timebar’s progress bar display
2024-09-30 20:51
#15531
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.