Version 5.3.0 is released

Announcements about Universal Media Server
michaelt
Posts: 155
Joined: Tue Feb 03, 2015 11:18 am
Location: lost in the depths of my mind

Re: Version 5.3.0 videos crash renderer

Post by michaelt » Sat Nov 14, 2015 6:06 am

This is the same problem reported here & in the OS X forum before but with more testing and logs.
Also I will duplicate this with the full logs and title information in the OS X forum.

It seems to be data related in that some (13) videos out of 240, all Blu-ray, cause the TV to Crash/Reset about 5 seconds into the play.
I have tried many combinations to extract multiple discs with multiple versions of MakeMKV, serving with multiple UMS versions. All show the same behavior: video starts to load, spinning circle, 5 sec later the TV goes black, audio pops and it looks like it reboots.

Is it possible to log the all data being sent to the renderer?

This is the crash event at 12:35:11:

Code: Select all

DEBUG 2015-11-13 12:35:07.039 [New I/O worker #16] Connection error: java.io.IOException: Connection reset by peer
DEBUG 2015-11-13 12:35:07.039 [New I/O worker #16] Premature end, stopping...
DEBUG 2015-11-13 12:35:11.042 [StopPlaying Event] Matched media renderer "TV-55C8000" based on address /10.0.1.89
INFO  2015-11-13 12:35:11.042 [StopPlaying Event] Stopped playing Tears Of The Sun.mkv on your TV-55C8000
DEBUG 2015-11-13 12:35:11.043 [StopPlaying Event] The full filename of which is: /Users/umsmkv/aML/Video/Index/@Crash/Tears Of The Sun.mkv and the address of the renderer is: 10.0.1.89
DEBUG 2015-11-13 12:35:11.043 [StopPlaying Event] Reading resume: false (default: true)
and the TV goes black.

This is a snippet from the full log showing a lot before and a bit around the crash event:

Code: Select all

TRACE 2015-11-13 12:35:00.868 [New I/O worker #7] Sent to socket: Content-Type: text/xml; charset="utf-8"
TRACE 2015-11-13 12:35:00.868 [New I/O worker #7] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:00.869 [New I/O worker #7] Sent to socket: Content-Length: 448
TRACE 2015-11-13 12:35:00.871 [New I/O worker #8] Opened request handler on socket /10.0.1.89:38086
TRACE 2015-11-13 12:35:00.871 [New I/O worker #8] Request: HTTP/1.0 : POST : upnp/control/content_directory
DEBUG 2015-11-13 12:35:00.871 [New I/O worker #8] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:00.872 [New I/O worker #8] Received on socket: HOST: 10.0.1.50:5001
TRACE 2015-11-13 12:35:00.872 [New I/O worker #8] Received on socket: CONTENT-LENGTH: 415
TRACE 2015-11-13 12:35:00.872 [New I/O worker #8] Received on socket: CONTENT-TYPE: text/xml;charset="utf-8"
TRACE 2015-11-13 12:35:00.872 [New I/O worker #8] Received on socket: USER-AGENT: DLNADOC/1.50
TRACE 2015-11-13 12:35:00.872 [New I/O worker #8] Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
TRACE 2015-11-13 12:35:00.872 [New I/O worker #8] HTTP User-Agent: DLNADOC/1.50
TRACE 2015-11-13 12:35:00.872 [New I/O worker #8] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:00.872 [New I/O worker #8] HTTP: upnp/control/content_directory / 0-0
TRACE 2015-11-13 12:35:00.895 [New I/O worker #8] Matched format MKV to "/Users/umsmkv/aML/Video/Index/@Crash/Tears Of The Sun.mkv"
DEBUG 2015-11-13 12:35:01.084 [New I/O worker #8] Reading PrependTrackNumbers: true (default: false)
TRACE 2015-11-13 12:35:01.089 [New I/O worker #8] Parsing results for file "Tears Of The Sun.mkv": container: mkv, bitrate: 21411469, size: 19418232298, video tracks: 1, audio tracks: 2, subtitle tracks: 3, video codec: mpeg2, duration: 02:00:55.00, width: 1920, height: 1080, frame rate: 23.976, mime type: video/x-matroska, attached fonts: false
        Audio track id: 0, lang: en, audio track title from metadata: Surround 5.1, audio codec: AC3, sample frequency:48000, number of channels: 6, bits per sample: 16
        Audio track id: 1, lang: en, audio track title from metadata: Surround 5.1, audio codec: lpcm, sample frequency:48000, number of channels: 6, bits per sample: 16
        Subtitle track id: 0, type: PGS, lang: en
        Subtitle track id: 1, type: PGS, lang: en
        Subtitle track id: 2, type: PGS, lang: en
TRACE 2015-11-13 12:35:01.090 [New I/O worker #8] Adding new child "Tears Of The Sun.mkv" with class "net.pms.dlna.RealFile"
DEBUG 2015-11-13 12:35:01.090 [New I/O worker #8] Reading disable_transcode_for_extensions: "mkv" (default: "")
TRACE 2015-11-13 12:35:01.090 [New I/O worker #8] File "Tears Of The Sun.mkv" will be forced to skip transcoding by configuration
TRACE 2015-11-13 12:35:01.090 [New I/O worker #8] Getting player for resource "Tears Of The Sun.mkv"
TRACE 2015-11-13 12:35:01.091 [New I/O worker #8] Returning compatible player "FFmpeg"
TRACE 2015-11-13 12:35:01.092 [New I/O worker #8] Looking for an audio track with lang: eng
TRACE 2015-11-13 12:35:01.097 [New I/O worker #8] Matched audio track: id: 0, lang: en, audio track title from metadata: Surround 5.1, audio codec: AC3, sample frequency:48000, number of channels: 6, bits per sample: 16
TRACE 2015-11-13 12:35:01.097 [New I/O worker #8] Searching for a match for: en with eng and off
TRACE 2015-11-13 12:35:01.097 [New I/O worker #8] Disabled the subtitles: id: 0, type: UNKNOWN, lang: off
TRACE 2015-11-13 12:35:01.098 [New I/O worker #8] Matched support line f:avi|mkv             m:video/avi
TRACE 2015-11-13 12:35:01.098 [New I/O worker #8] Matched support line f:avi|mkv             m:video/avi
DEBUG 2015-11-13 12:35:01.098 [New I/O worker #8] Reading MaxVideoBitrateMbps: "120" (default: "0")
TRACE 2015-11-13 12:35:01.098 [New I/O worker #8] Final verdict: "Tears Of The Sun.mkv" will be streamed
TRACE 2015-11-13 12:35:01.098 [New I/O worker #8] Matched support line f:avi|mkv             m:video/avi
TRACE 2015-11-13 12:35:01.098 [New I/O worker #8] Matched support line f:avi|mkv             m:video/avi
TRACE 2015-11-13 12:35:01.098 [New I/O worker #8] File "Tears Of The Sun.mkv" will be sent with MIME type "video/avi"
DEBUG 2015-11-13 12:35:01.099 [New I/O worker #8] Reading hide_transcode_folder: true (default: false)
DEBUG 2015-11-13 12:35:01.099 [New I/O worker #8] Reading autoload_external_subtitles: false (default: true)
TRACE 2015-11-13 12:35:01.099 [New I/O worker #8] End of analysis for /Users/umsmkv/aML/Video/Index/@Crash
TRACE 2015-11-13 12:35:01.100 [New I/O worker #8] Not setting subsAreValidForStreaming and it is false for Tears Of The Sun.mkv
TRACE 2015-11-13 12:35:01.101 [New I/O worker #8] Network debugger: "Tears Of The Sun" http-get:*:video/avi:DLNA.ORG_OP=01 size=19418232298 duration=02:00:55.00 http://10.0.1.50:5001/get/9/Tears+Of+The+Sun.mkv
TRACE 2015-11-13 12:35:01.101 [New I/O worker #8] <?xml version="1.0" encoding="utf-8"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<Result><DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"><item id="9" parentID="3" restricted="true"><dc:title>Tears Of The Sun</dc:title><res xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" protocolInfo="http-get:*:video/avi:DLNA.ORG_OP=01" size="19418232298" duration="02:00:55.00" resolution="1920x1080" bitrate="2676433" nrAudioChannels="6" sampleFrequency="48000">http://10.0.1.50:5001/get/9/Tears+Of+The+Sun.mkv</res><res protocolInfo="http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_TN">http://10.0.1.50:5001/get/9/thumbnail0000Tears+Of+The+Sun.mkv</res><res protocolInfo="http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_SM">http://10.0.1.50:5001/get/9/thumbnail0000Tears+Of+The+Sun.mkv</res><dc:date>2015-10-08T20:09:24</dc:date><upnp:class>object.item.videoItem</upnp:class></item></DIDL-Lite></Result>
<NumberReturned>1</NumberReturned>
<TotalMatches>1</TotalMatches>
<UpdateID>2</UpdateID>
</u:BrowseResponse>
</s:Body>
</s:Envelope>

TRACE 2015-11-13 12:35:01.102 [New I/O worker #8] Sent to socket: Content-Type: text/xml; charset="utf-8"
TRACE 2015-11-13 12:35:01.102 [New I/O worker #8] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:01.102 [New I/O worker #8] Sent to socket: Content-Length: 1396
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] Opened request handler on socket /10.0.1.89:47329
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] Request: HTTP/1.0 : POST : upnp/control/content_directory
DEBUG 2015-11-13 12:35:01.105 [New I/O worker #9] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] Received on socket: HOST: 10.0.1.50:5001
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] Received on socket: CONTENT-LENGTH: 255
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] Received on socket: CONTENT-TYPE: text/xml;charset="utf-8"
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] Received on socket: USER-AGENT: DLNADOC/1.50
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#X_GetFeatureList"
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] HTTP User-Agent: DLNADOC/1.50
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:01.105 [New I/O worker #9] HTTP: upnp/control/content_directory / 0-0
DEBUG 2015-11-13 12:35:01.105 [New I/O worker #9] Invalid action in SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#X_GetFeatureList"
TRACE 2015-11-13 12:35:01.106 [New I/O worker #9] Sent to socket: Content-Type: text/xml; charset="utf-8"
TRACE 2015-11-13 12:35:01.106 [New I/O worker #9] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:01.106 [New I/O worker #9] Sent to socket: Content-Length: 448
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] Opened request handler on socket /10.0.1.89:37608
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] Request: HTTP/1.0 : POST : upnp/control/content_directory
DEBUG 2015-11-13 12:35:01.108 [New I/O worker #10] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] Received on socket: HOST: 10.0.1.50:5001
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] Received on socket: CONTENT-LENGTH: 417
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] Received on socket: CONTENT-TYPE: text/xml;charset="utf-8"
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] Received on socket: USER-AGENT: DLNADOC/1.50
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] HTTP User-Agent: DLNADOC/1.50
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:01.108 [New I/O worker #10] HTTP: upnp/control/content_directory / 0-0
TRACE 2015-11-13 12:35:01.109 [New I/O worker #10] <?xml version="1.0" encoding="utf-8"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<Result><DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"><container id="3" childCount="1" parentID="1" restricted="true"><dc:title>@Crash</dc:title><res protocolInfo="http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_TN">http://10.0.1.50:5001/get/3/thumbnail0000%40Crash</res><res protocolInfo="http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_SM">http://10.0.1.50:5001/get/3/thumbnail0000%40Crash</res><dc:date>2015-11-13T11:32:04</dc:date><upnp:class>object.container.storageFolder</upnp:class></container></DIDL-Lite></Result>
<NumberReturned>1</NumberReturned>
<TotalMatches>1</TotalMatches>
<UpdateID>2</UpdateID>
</u:BrowseResponse>
</s:Body>
</s:Envelope>

TRACE 2015-11-13 12:35:01.109 [New I/O worker #10] Sent to socket: Content-Type: text/xml; charset="utf-8"
TRACE 2015-11-13 12:35:01.109 [New I/O worker #10] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:01.109 [New I/O worker #10] Sent to socket: Content-Length: 1099
TRACE 2015-11-13 12:35:01.116 [New I/O worker #11] Opened request handler on socket /10.0.1.89:48470
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] Request: HTTP/1.0 : POST : upnp/control/content_directory
DEBUG 2015-11-13 12:35:01.117 [New I/O worker #11] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] Received on socket: HOST: 10.0.1.50:5001
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] Received on socket: CONTENT-LENGTH: 656
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] Received on socket: CONTENT-TYPE: text/xml;charset="utf-8"
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] Received on socket: USER-AGENT: DLNADOC/1.50
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] HTTP User-Agent: DLNADOC/1.50
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] HTTP: upnp/control/content_directory / 0-0
TRACE 2015-11-13 12:35:01.117 [New I/O worker #11] discover 3 refresh forced: true
TRACE 2015-11-13 12:35:01.118 [New I/O worker #11] End of analysis for /Users/umsmkv/aML/Video/Index/@Crash
TRACE 2015-11-13 12:35:01.118 [New I/O worker #11] Not setting subsAreValidForStreaming and it is false for Tears Of The Sun.mkv
TRACE 2015-11-13 12:35:01.119 [New I/O worker #11] Network debugger: "Tears Of The Sun" http-get:*:video/avi:DLNA.ORG_OP=01 size=19418232298 duration=02:00:55.00 http://10.0.1.50:5001/get/9/Tears+Of+The+Sun.mkv
TRACE 2015-11-13 12:35:01.119 [New I/O worker #11] <?xml version="1.0" encoding="utf-8"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
<Result><DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"><item id="9" parentID="3" restricted="true"><dc:title>Tears Of The Sun</dc:title><res xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/" protocolInfo="http-get:*:video/avi:DLNA.ORG_OP=01" size="19418232298" duration="02:00:55.00" resolution="1920x1080" bitrate="2676433" nrAudioChannels="6" sampleFrequency="48000">http://10.0.1.50:5001/get/9/Tears+Of+The+Sun.mkv</res><res protocolInfo="http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_TN">http://10.0.1.50:5001/get/9/thumbnail0000Tears+Of+The+Sun.mkv</res><res protocolInfo="http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_SM">http://10.0.1.50:5001/get/9/thumbnail0000Tears+Of+The+Sun.mkv</res><dc:date>2015-10-08T20:09:24</dc:date><upnp:class>object.item.videoItem</upnp:class></item></DIDL-Lite></Result>
<NumberReturned>1</NumberReturned>
<TotalMatches>1</TotalMatches>
<UpdateID>2</UpdateID>
</u:BrowseResponse>
</s:Body>
</s:Envelope>

TRACE 2015-11-13 12:35:01.120 [New I/O worker #11] Sent to socket: Content-Type: text/xml; charset="utf-8"
TRACE 2015-11-13 12:35:01.120 [New I/O worker #11] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:01.120 [New I/O worker #11] Sent to socket: Content-Length: 1396
TRACE 2015-11-13 12:35:01.282 [New I/O worker #12] Opened request handler on socket /10.0.1.89:59891
TRACE 2015-11-13 12:35:01.283 [New I/O worker #12] Request: HTTP/1.0 : GET : get/9/thumbnail0000Tears+Of+The+Sun.mkv
DEBUG 2015-11-13 12:35:01.283 [New I/O worker #12] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:01.283 [New I/O worker #12] Received on socket: HOST: 10.0.1.50:5001
TRACE 2015-11-13 12:35:01.283 [New I/O worker #12] Received on socket: ACCEPT-LANGUAGE: en-us
TRACE 2015-11-13 12:35:01.283 [New I/O worker #12] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2015-11-13 12:35:01.283 [New I/O worker #12] Received on socket: transferMode.dlna.org: Interactive
TRACE 2015-11-13 12:35:01.283 [New I/O worker #12] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:01.283 [New I/O worker #12] HTTP: get/9/thumbnail0000Tears+Of+The+Sun.mkv / 0-0
DEBUG 2015-11-13 12:35:01.284 [New I/O worker #12] Reading thumbnail_seek_position: 80 (default: 4)
DEBUG 2015-11-13 12:35:01.286 [New I/O worker #12] Starting /Users/umsmkv/bin/Universal Media Server 530J8.app/Contents/Resources/osx/ffmpeg -ss 80 -i /Users/umsmkv/aML/Video/Index/@Crash/Tears Of The Sun.mkv -an -an -vf scale='if(gt(a,1.78),320,-1)':'if(gt(a,1.78),-1,180)', pad=320:180:(320-iw)/2:(180-ih)/2 -vframes 1 -f image2 pipe:
TRACE 2015-11-13 12:35:01.286 [New I/O worker #12] Setting FONTCONFIG_PATH to "/Users/umsmkv/bin/Universal Media Server 530J8.app/Contents/Resources/fonts"
TRACE 2015-11-13 12:35:01.647 [New I/O worker #12] Successfully initialized buffer to 1,048,576 bytes.
DEBUG 2015-11-13 12:35:01.647 [New I/O worker #12] Attaching thread: ffmpeg-2
DEBUG 2015-11-13 12:35:01.648 [New I/O worker #12] Unix process ID (/Users/umsmkv/bin/Universal Media Server 530J8.app/Contents/Resources/osx/ffmpeg): 45445
TRACE 2015-11-13 12:35:01.648 [New I/O worker #12] EOF
TRACE 2015-11-13 12:35:01.648 [New I/O worker #12] Ready to Stop: true
DEBUG 2015-11-13 12:35:01.649 [New I/O worker #12] Reading alternate_thumb_folder: "/Users/umsmkv/aML/VideoIndex/CoverArt" (default: "")
DEBUG 2015-11-13 12:35:01.649 [New I/O worker #12] use def thumb images/thumbnail-video-256.png
TRACE 2015-11-13 12:35:01.650 [New I/O worker #12] Available Content-Length: 19803
TRACE 2015-11-13 12:35:01.653 [New I/O worker #12] Sent to socket: TransferMode.DLNA.ORG: Interactive
TRACE 2015-11-13 12:35:01.653 [New I/O worker #12] Sent to socket: Content-Type: image/jpeg
TRACE 2015-11-13 12:35:01.653 [New I/O worker #12] Sent to socket: Accept-Ranges: bytes
TRACE 2015-11-13 12:35:01.653 [New I/O worker #12] Sent to socket: Expires: Tue, 08 Mar 2016 11:21:41 GMT
TRACE 2015-11-13 12:35:01.653 [New I/O worker #12] Sent to socket: Connection: keep-alive
TRACE 2015-11-13 12:35:01.654 [New I/O worker #12] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:01.654 [New I/O worker #12] Sent to socket: Content-Length: 19803
DEBUG 2015-11-13 12:35:04.150 [ffmpeg-2-Cleanup] Stopping Unix process 45445: ffmpeg-2
TRACE 2015-11-13 12:35:04.150 [ffmpeg-2-Cleanup] Killing the Unix process: 45445
TRACE 2015-11-13 12:35:04.151 [ffmpeg-2-Cleanup] Destroying buffer
TRACE 2015-11-13 12:35:06.766 [New I/O worker #13] Opened request handler on socket /10.0.1.89:47981
TRACE 2015-11-13 12:35:06.767 [New I/O worker #13] Request: HTTP/1.0 : HEAD : get/9/Tears+Of+The+Sun.mkv
DEBUG 2015-11-13 12:35:06.767 [New I/O worker #13] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:06.767 [New I/O worker #13] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2015-11-13 12:35:06.767 [New I/O worker #13] Received on socket: Host: 10.0.1.50:5001
TRACE 2015-11-13 12:35:06.767 [New I/O worker #13] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:06.767 [New I/O worker #13] HTTP: get/9/Tears+Of+The+Sun.mkv / 0-0
TRACE 2015-11-13 12:35:06.767 [New I/O worker #13] Asked stream chunk : TimeRange [start=null, end=null] of Tears Of The Sun.mkv and player null
DEBUG 2015-11-13 12:35:06.768 [New I/O worker #13] Reading SubtitleHttpHeader: "CaptionInfo.sec" (default: "")
TRACE 2015-11-13 12:35:06.768 [New I/O worker #13] Did not send subtitle headers because dlna.getMediaSubtitle returned null
DEBUG 2015-11-13 12:35:06.769 [StartPlaying Event] Matched media renderer "TV-55C8000" based on address /10.0.1.89
INFO  2015-11-13 12:35:06.770 [StartPlaying Event] Started playing Tears Of The Sun.mkv on your TV-55C8000
DEBUG 2015-11-13 12:35:06.771 [StartPlaying Event] The full filename of which is: /Users/umsmkv/aML/Video/Index/@Crash/Tears Of The Sun.mkv and the address of the renderer is: 10.0.1.89
TRACE 2015-11-13 12:35:06.772 [New I/O worker #13] Sent to socket: Content-Type: video/avi
TRACE 2015-11-13 12:35:06.772 [New I/O worker #13] Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2015-11-13 12:35:06.772 [New I/O worker #13] Sent to socket: Accept-Ranges: bytes
TRACE 2015-11-13 12:35:06.772 [New I/O worker #13] Sent to socket: Connection: keep-alive
TRACE 2015-11-13 12:35:06.772 [New I/O worker #13] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:06.772 [New I/O worker #13] Sent to socket: Content-Length: 19418232298
TRACE 2015-11-13 12:35:06.776 [New I/O worker #14] Opened request handler on socket /10.0.1.89:48830
TRACE 2015-11-13 12:35:06.778 [New I/O worker #14] Request: HTTP/1.0 : GET : get/9/Tears+Of+The+Sun.mkv
DEBUG 2015-11-13 12:35:06.780 [New I/O worker #14] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:06.781 [New I/O worker #14] Received on socket: Range: bytes=0-
TRACE 2015-11-13 12:35:06.781 [New I/O worker #14] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2015-11-13 12:35:06.781 [New I/O worker #14] Received on socket: transferMode.dlna.org: Streaming
TRACE 2015-11-13 12:35:06.781 [New I/O worker #14] Received on socket: getMediaInfo.sec: 1
TRACE 2015-11-13 12:35:06.781 [New I/O worker #14] Received on socket: Host: 10.0.1.50:5001
TRACE 2015-11-13 12:35:06.781 [New I/O worker #14] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:06.781 [New I/O worker #14] HTTP: get/9/Tears+Of+The+Sun.mkv / 0--1
TRACE 2015-11-13 12:35:06.781 [New I/O worker #14] Asked stream chunk : TimeRange [start=null, end=null] of Tears Of The Sun.mkv and player null
TRACE 2015-11-13 12:35:06.782 [New I/O worker #14] Did not send subtitle headers because dlna.getMediaSubtitle returned null
TRACE 2015-11-13 12:35:06.782 [New I/O worker #14] Sending 19418232298 bytes.
TRACE 2015-11-13 12:35:06.784 [New I/O worker #14] Sent to socket: TransferMode.DLNA.ORG: Streaming
TRACE 2015-11-13 12:35:06.785 [New I/O worker #14] Sent to socket: Content-Type: video/avi
TRACE 2015-11-13 12:35:06.785 [New I/O worker #14] Sent to socket: Content-Range: bytes 0-19418232297/19418232298
TRACE 2015-11-13 12:35:06.785 [New I/O worker #14] Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2015-11-13 12:35:06.785 [New I/O worker #14] Sent to socket: Accept-Ranges: bytes
TRACE 2015-11-13 12:35:06.785 [New I/O worker #14] Sent to socket: Connection: keep-alive
TRACE 2015-11-13 12:35:06.785 [New I/O worker #14] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:06.785 [New I/O worker #14] Sent to socket: Content-Length: 19418232298
DEBUG 2015-11-13 12:35:06.819 [New I/O worker #14] Connection error: java.io.IOException: Connection reset by peer
TRACE 2015-11-13 12:35:06.819 [New I/O worker #15] Opened request handler on socket /10.0.1.89:34277
DEBUG 2015-11-13 12:35:06.819 [New I/O worker #14] Premature end, stopping...
TRACE 2015-11-13 12:35:06.819 [New I/O worker #15] Request: HTTP/1.0 : GET : get/9/Tears+Of+The+Sun.mkv
DEBUG 2015-11-13 12:35:06.819 [New I/O worker #15] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:06.820 [New I/O worker #15] Received on socket: Range: bytes=19418018784-
TRACE 2015-11-13 12:35:06.820 [New I/O worker #15] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2015-11-13 12:35:06.820 [New I/O worker #15] Received on socket: transferMode.dlna.org: Streaming
TRACE 2015-11-13 12:35:06.820 [New I/O worker #15] Received on socket: Host: 10.0.1.50:5001
TRACE 2015-11-13 12:35:06.820 [New I/O worker #15] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:06.820 [New I/O worker #15] HTTP: get/9/Tears+Of+The+Sun.mkv / 19418018784--1
TRACE 2015-11-13 12:35:06.821 [New I/O worker #15] Asked stream chunk : ByteRange [start=19418018784, end=-1] of Tears Of The Sun.mkv and player null
TRACE 2015-11-13 12:35:06.821 [New I/O worker #15] Did not send subtitle headers because dlna.getMediaSubtitle returned null
TRACE 2015-11-13 12:35:06.822 [New I/O worker #15] Sending 213514 bytes.
TRACE 2015-11-13 12:35:06.823 [New I/O worker #15] Sent to socket: TransferMode.DLNA.ORG: Streaming
TRACE 2015-11-13 12:35:06.824 [New I/O worker #15] Sent to socket: Content-Type: video/avi
TRACE 2015-11-13 12:35:06.824 [New I/O worker #15] Sent to socket: Content-Range: bytes 19418018784-19418232297/19418232298
TRACE 2015-11-13 12:35:06.824 [New I/O worker #15] Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2015-11-13 12:35:06.824 [New I/O worker #15] Sent to socket: Accept-Ranges: bytes
TRACE 2015-11-13 12:35:06.824 [New I/O worker #15] Sent to socket: Connection: keep-alive
TRACE 2015-11-13 12:35:06.824 [New I/O worker #15] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:06.824 [New I/O worker #15] Sent to socket: Content-Length: 213514
TRACE 2015-11-13 12:35:06.889 [New I/O worker #16] Opened request handler on socket /10.0.1.89:38445
TRACE 2015-11-13 12:35:06.889 [New I/O worker #16] Request: HTTP/1.0 : GET : get/9/Tears+Of+The+Sun.mkv
DEBUG 2015-11-13 12:35:06.889 [New I/O worker #16] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] Received on socket: Range: bytes=3903-
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] Received on socket: getcontentFeatures.dlna.org: 1
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] Received on socket: transferMode.dlna.org: Streaming
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] Received on socket: Host: 10.0.1.50:5001
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] Recognized media renderer: TV-55C8000
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] HTTP: get/9/Tears+Of+The+Sun.mkv / 3903--1
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] Asked stream chunk : ByteRange [start=3903, end=-1] of Tears Of The Sun.mkv and player null
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] Did not send subtitle headers because dlna.getMediaSubtitle returned null
TRACE 2015-11-13 12:35:06.890 [New I/O worker #16] Sending 19418228395 bytes.
TRACE 2015-11-13 12:35:06.893 [New I/O worker #16] Sent to socket: TransferMode.DLNA.ORG: Streaming
TRACE 2015-11-13 12:35:06.893 [New I/O worker #16] Sent to socket: Content-Type: video/avi
TRACE 2015-11-13 12:35:06.893 [New I/O worker #16] Sent to socket: Content-Range: bytes 3903-19418232297/19418232298
TRACE 2015-11-13 12:35:06.893 [New I/O worker #16] Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
TRACE 2015-11-13 12:35:06.893 [New I/O worker #16] Sent to socket: Accept-Ranges: bytes
TRACE 2015-11-13 12:35:06.893 [New I/O worker #16] Sent to socket: Connection: keep-alive
TRACE 2015-11-13 12:35:06.893 [New I/O worker #16] Sent to socket: Server: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0
TRACE 2015-11-13 12:35:06.893 [New I/O worker #16] Sent to socket: Content-Length: 19418228395
DEBUG 2015-11-13 12:35:07.039 [New I/O worker #16] Connection error: java.io.IOException: Connection reset by peer
DEBUG 2015-11-13 12:35:07.039 [New I/O worker #16] Premature end, stopping...
DEBUG 2015-11-13 12:35:11.042 [StopPlaying Event] Matched media renderer "TV-55C8000" based on address /10.0.1.89
INFO  2015-11-13 12:35:11.042 [StopPlaying Event] Stopped playing Tears Of The Sun.mkv on your TV-55C8000
DEBUG 2015-11-13 12:35:11.043 [StopPlaying Event] The full filename of which is: /Users/umsmkv/aML/Video/Index/@Crash/Tears Of The Sun.mkv and the address of the renderer is: 10.0.1.89
DEBUG 2015-11-13 12:35:11.043 [StopPlaying Event] Reading resume: false (default: true)
TRACE 2015-11-13 12:35:33.341 [UPNPHelper] Receiving a M-SEARCH from [10.0.1.89:1025]
DEBUG 2015-11-13 12:35:33.345 [cling-42] Subscription cancelled: ConnectionManager on uuid:3a3e65ac-abf0-4e93-5488-d2f1c2b5ef41: DEVICE_WAS_REMOVED
DEBUG 2015-11-13 12:35:33.345 [cling-44] Subscription cancelled: AVTransport on uuid:3a3e65ac-abf0-4e93-5488-d2f1c2b5ef41: DEVICE_WAS_REMOVED
DEBUG 2015-11-13 12:35:33.345 [cling-40] Subscription cancelled: RenderingControl on uuid:3a3e65ac-abf0-4e93-5488-d2f1c2b5ef41: DEVICE_WAS_REMOVED
DEBUG 2015-11-13 12:35:33.347 [cling-47] TV-55C8000 is now offline.
TRACE 2015-11-13 12:35:33.351 [UPNPHelper] Sending discovery [10.0.1.89:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Fri, 13 Nov 2015 17:35:33 GMT<CRLF>LOCATION: http://10.0.1.50:5001/description/fetch<CRLF>SERVER: Mac_OS_X-x86_64-10.7.5, UPnP/1.0, UMS/5.3.0<CRLF>ST: urn:schemas-upnp-org:device:MediaServer:1<CRLF>EXT: <CRLF>USN: uuid:3d7edd4a-31e0-4758-9169-eb3f39a36933::urn:schemas-upnp-org:device:MediaServer:1<CRLF>Content-Length: 0<CRLF><CRLF>
TRACE 2015-11-13 12:35:33.362 [New I/O worker #1] Opened request handler on socket /10.0.1.89:53828
TRACE 2015-11-13 12:35:33.362 [New I/O worker #1] Request: HTTP/1.0 : GET : description/fetch
DEBUG 2015-11-13 12:35:33.362 [New I/O worker #1] Matched media renderer "TV-55C8000" based on address /10.0.1.89
TRACE 2015-11-13 12:35:33.362 [New I/O worker #1] Received on socket: HOST: 10.0.1.50:5001
TRACE 2015-11-13 12:35:33.362 [New I/O worker #1] Received on socket: USER-AGENT: SEC_HHP_TV-55C8000/1.0
TRACE 2015-11-13 12:35:33.362 [New I/O worker #1] Received on socket: ACCEPT-LANGUAGE: en-us
TRACE 2015-11-13 12:35:33.362 [New I/O worker #1] HTTP User-Agent: SEC_HHP_TV-55C8000/1.0
TRACE 2015-11-13 12:35:33.362 [New I/O worker #1] Recognized media renderer: TV-55C8000
MacPro5,1 - 2.8 GHz, 32 GB, 20TB, OS X 10.11.6,
. . . . and a few others.
Samsung UN55C8000
Home Theater (WIP): OS X based now, dedicated Linux later.

bevster
Posts: 24
Joined: Sun Jun 24, 2012 5:27 pm

Re: Version 5.3.0 is released

Post by bevster » Sat Nov 14, 2015 4:23 pm

HT1 wrote:
bevster wrote:I have to just keep restarting my computer to get it to start on win10, 2-4 restarts and it will start.. If it does not start with windows I cannot even run it manually ( nothing happens ).. I have uninstalled and reinstalled like the previous few posts have been discussing.. This all started for me when I installed win10
did you do an in-place upgrade or did you format your hard drive and install win10 fresh?

weird hard-to-explain sh*t happens when you do in-place upgrades.
Yes I did an in-place upgrade, guess I will have to live with it this way then. Just don't have the time to start fresh

HT1
Posts: 122
Joined: Thu Mar 05, 2015 12:30 pm

Re: Version 5.3.0 is released

Post by HT1 » Mon Nov 16, 2015 4:56 am

sure, create a shortcut on your desktop to javaw

then open its properties and paste the rest of the command from the batch file.

then move to your startup folder.
Win 10 64bits java 8
Qnap TS-212 2x3TB WD Red
Sony Bravia MN (my conf)

blue000000
Posts: 11
Joined: Mon Feb 16, 2015 11:43 am

Re: Version 5.3.0 is released

Post by blue000000 » Mon Nov 16, 2015 12:36 pm

Just cross posting here. I uploaded all the info for better support for my renderer in this other thread.

viewtopic.php?f=5&t=2379&p=21169#p21054

let me know if i can do anything else.

Panny
Posts: 15
Joined: Mon Sep 21, 2015 8:06 pm

Re: Version 5.3.0 is released

Post by Panny » Wed Nov 18, 2015 6:53 am

HT1 wrote:sure, create a shortcut on your desktop to javaw

then open its properties and paste the rest of the command from the batch file.

then move to your startup folder.
That's one option. I was thinking along the lines of a longer term solution if these auto start-up issues continue, as it apparently has to do with how quickly javaw starts (at least in my case).

Couldn't the UMS shortcut that is placed in the common start-up folder at installation point to the UMS.bat instead and have it run minimized (you would then have to delete pause from it)?

Paradox
Posts: 140
Joined: Tue Dec 17, 2013 5:50 am

Re: Version 5.3.0 is released

Post by Paradox » Fri Nov 20, 2015 12:16 pm

Panny wrote: It works with the ums.bat file, thanks. I deleted the 'pause' from it, otherwise you get the 'press any key to continue' command window. Without the 'pause' I get the briefest glimpse of the command window.

Is there any way to incorporate the instruction from the ums.bat file into the UMS shortcut in the startup folder (for future releases)?
Adding anything else like that .bat file to the startup folder will stop things working or make UMS run twice for people that have it running correctly from startup of windows so no i doubt that will be or should be done for future editions. Its not the proper way to call for applications to autostart. You are basically in a unique position and need a work around for something which works as it should for the the other 99.9999% of users.

michaelt wrote:This is the same problem reported here & in the OS X forum before but with more testing and logs.
Also I will duplicate this with the full logs and title information in the OS X forum.

It seems to be data related in that some (13) videos out of 240, all Blu-ray, cause the TV to Crash/Reset about 5 seconds into the play.
I have tried many combinations to extract multiple discs with multiple versions of MakeMKV, serving with multiple UMS versions. All show the same behavior: video starts to load, spinning circle, 5 sec later the TV goes black, audio pops and it looks like it reboots.

Is it possible to log the all data being sent to the renderer?

This is the crash event at 12:35:11:
{SNIPPED}
I suspect this is related to your prior issues of file or folder naming and using &, @ or similar symbols in the file or folder structure which it appears are not supported. Remove symbols like those, does it then work?

Panny
Posts: 15
Joined: Mon Sep 21, 2015 8:06 pm

Re: Version 5.3.0 is released

Post by Panny » Sat Nov 28, 2015 3:03 am

Just to confirm that my auto-startup issue on Windows 10 has been resolved by not starting the program as administrator. Thanks to @phrozenstare in the 5.3.1 thread for pointing me to the resolution.

Would the developers know why auto-start is prevented if running as administrator? In order to auto-update the program you need to run it as an administrator, so there seems to be a conflict between two operations in the same program running on Windows 10.

Thanks

Post Reply