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.