Seeking and pausing on Yamaha RX-Vx73, RX-Vx75 Receivers
Posted: Mon Mar 31, 2014 4:37 pm
Hi all,
I'm not sure if this is a problem with UMS or with these receivers, but I've been unable to seek or pause with them, on any file format. I'm not sure if UMS isn't receiving a properly formatted request or what - when viewed in comparison with my Onkyo HT-RC360, it looks like no requests other than 'play' or 'stop' appear in the log at all.
This happens with two different Yamaha receivers, an RX-V673 and an RX-V575, so my operating assumption is that it affects those two entire lines. The older RX-Vx71 line seems to use different software (and has a different user-agent altogether) and is unaffected. This happens whether or not the files are first decoded to PCM, or bitstreamed in their native format to the receivers to decode and whether or not SeekByTime is true or false.
This is how starting playback looks like on the HT-RC360.
Also, there is no equivalent line under the Yamaha playback log to this:
I have several zip files worth of logs, but will post just those that I've excerpted above (ums_debug_2 for the Onkyo, ums_dbg_4 for the Yamaha).
I'm not sure if this is a problem with UMS or with these receivers, but I've been unable to seek or pause with them, on any file format. I'm not sure if UMS isn't receiving a properly formatted request or what - when viewed in comparison with my Onkyo HT-RC360, it looks like no requests other than 'play' or 'stop' appear in the log at all.
This happens with two different Yamaha receivers, an RX-V673 and an RX-V575, so my operating assumption is that it affects those two entire lines. The older RX-Vx71 line seems to use different software (and has a different user-agent altogether) and is unaffected. This happens whether or not the files are first decoded to PCM, or bitstreamed in their native format to the receivers to decode and whether or not SeekByTime is true or false.
This is how starting playback looks like on the HT-RC360.
And this is what seeking looks like; note the bolded lines:TRACE 2014-03-30 22:36:58.519 [New I/O server boss #9] Creating new pipeline
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Opened request handler on socket /192.168.1.15:46099
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Calling SetThreadExecutionState ES_SYSTEM_REQUIRED
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Request: HTTP/1.1 : GET : get/0$5$9$44$18$9$4/03-James+Horner-Southampton.flac
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Matched media renderer "Onkyo HT-RC360" based on address /192.168.1.15
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Received on socket: Range: bytes=0-
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Received on socket: Host: 192.168.1.24:5001
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Received on socket: User-Agent: Mediabolic-IMHTTP/1.8.225 UPNP/1.0 DLNADOC/1.50
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Recognized media renderer: Onkyo HT-RC360
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] HTTP: get/0$5$9$44$18$9$4/03-James+Horner-Southampton.flac / 0--1
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] discover 0 refresh forced: false
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] discover 0$5 refresh forced: false
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] discover 0$5$9 refresh forced: false
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] discover 0$5$9$44 refresh forced: false
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] discover 0$5$9$44$18 refresh forced: false
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] discover 0$5$9$44$18$9 refresh forced: false
TRACE 2014-03-30 22:36:58.520 [New I/O worker #8] Asked stream chunk : TimeRange [start=null, end=null] of 03-James Horner-Southampton.flac and player null
TRACE 2014-03-30 22:36:58.521 [New I/O worker #8] Sending 20166958 bytes.
INFO 2014-03-30 22:36:58.521 [StartPlaying Event] Started playing 03-James Horner-Southampton.flac on your Onkyo HT-RC360
DEBUG 2014-03-30 22:36:58.521 [StartPlaying Event] The full filename of which is: I:\My Music\Soundtrack\James Horner\Titanic (OST)\03-James Horner-Southampton.flac and the address of the renderer is: 192.168.1.15
The RX-V575 beginning playback with transcoding on:TRACE 2014-03-30 22:38:11.352 [New I/O server boss #9] Creating new pipeline
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Opened request handler on socket /192.168.1.15:46108
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Calling SetThreadExecutionState ES_SYSTEM_REQUIRED
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Request: HTTP/1.1 : GET : get/0$5$9$44$18$9$4/03-James+Horner-Southampton.flac
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Matched media renderer "Onkyo HT-RC360" based on address /192.168.1.15
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Received on socket: Range: bytes=188392-
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Received on socket: Host: 192.168.1.24:5001
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Received on socket: User-Agent: Mediabolic-IMHTTP/1.8.225 UPNP/1.0 DLNADOC/1.50
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Recognized media renderer: Onkyo HT-RC360
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] HTTP: get/0$5$9$44$18$9$4/03-James+Horner-Southampton.flac / 188392--1
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] discover 0 refresh forced: false
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] discover 0$5 refresh forced: false
TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] discover 0$5$9 refresh forced: false
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] discover 0$5$9$44 refresh forced: false
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] discover 0$5$9$44$18 refresh forced: false
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] discover 0$5$9$44$18$9 refresh forced: false
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Asked stream chunk : ByteRange [start=188392, end=-1] of 03-James Horner-Southampton.flac and player null
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Sending 19978566 bytes.
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Sent to socket: TransferMode.DLNA.ORG: Streaming
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Sent to socket: Content-Type: audio/x-flac
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Sent to socket: Content-Range: bytes 188392-20166957/20166958
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Sent to socket: Accept-Ranges: bytes
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Sent to socket: Connection: keep-alive
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Sent to socket: Server: Windows_7-x86-6.1, UPnP/1.0, UMS/3.5.0
TRACE 2014-03-30 22:38:11.354 [New I/O worker #8] Sent to socket: Content-Length: 19978566
TRACE 2014-03-30 22:38:11.354 [New I/O worker #2] Calling SetThreadExecutionState ES_CONTINUOUS
There are only two functional differences I found between playback calls is the option just at the end signifying which parts of the file to serve:TRACE 2014-03-31 00:26:02.056 [New I/O server boss #9] Creating new pipeline
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Opened request handler on socket /192.168.1.38:1162
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Calling SetThreadExecutionState ES_SYSTEM_REQUIRED
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Request: HTTP/1.1 : GET : get/0$2$30$1$1$5/04+-+Cold+Shoulder+-+Adele.flac
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Matched media renderer "Yamaha RX-V673" based on address /192.168.1.38
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Received on socket: Host: 192.168.1.24:5001
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Received on socket: Icy-MetaData: 1
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Received on socket: Connection: close
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Received on socket: transferMode.dlna.org: Streaming
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Received on socket: User-Agent: WinampMPEG/2.8
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Received on socket: Accept: */*
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Recognized media renderer: Yamaha RX-V673
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] HTTP: get/0$2$30$1$1$5/04+-+Cold+Shoulder+-+Adele.flac / 0-0
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] discover 0 refresh forced: false
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] discover 0$2 refresh forced: false
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] discover 0$2$30 refresh forced: false
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] discover 0$2$30$1 refresh forced: false
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] discover 0$2$30$1$1 refresh forced: false
TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Asked stream chunk : TimeRange [start=null, end=null] of 04 - Cold Shoulder - Adele.flac and player null
INFO 2014-03-31 00:26:02.058 [StartPlaying Event] Started playing 04 - Cold Shoulder - Adele.flac on your Yamaha RX-V673
DEBUG 2014-03-31 00:26:02.058 [StartPlaying Event] The full filename of which is: I:\My Music\Pop\Adele\Adele - 19\04 - Cold Shoulder - Adele.flac and the address of the renderer is: 192.168.1.38
The Onkyo has 0--1 there; I don't know which is more correct or whether at least that could shed some light on the situation.TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] HTTP: get/0$2$30$1$1$5/04+-+Cold+Shoulder+-+Adele.flac / 0-0
Also, there is no equivalent line under the Yamaha playback log to this:
Or in the Onkyo log to this:TRACE 2014-03-30 22:38:11.353 [New I/O worker #8] Received on socket: Range: bytes=188392-
Is the software in the newer Yamahas inherently more primitive then, or can we do something about this?TRACE 2014-03-31 00:26:02.057 [New I/O worker #2] Received on socket: Connection: close
I have several zip files worth of logs, but will post just those that I've excerpted above (ums_debug_2 for the Onkyo, ums_dbg_4 for the Yamaha).