Page 1 of 1

Playback terminating unexpectedly

PostPosted: Thu Jul 27, 2017 7:06 pm
by gavsdavs
I have a couple of Sony Devices using serviio very successfully for playout of movies.

A Television: KDL-32EX723 (Sony, 2011 according to Serviio)
A Blu ray player: BDP-Sx70/SMP-N100 (Sony, 2010 according to Serviio)

I have some 300 odd movies, and recently, some of the films I have are only playing partially on the TV. I watch 30-40 minutes and playback stops and I get "Playback not available" on the TV.

It is not all films, only a handful of them.
The affected films always appear to stop playback at the same place.

The bluray player appears unaffected.

This kind of suggests I have a fault with the TV, because the same media will play to completion using serviio using the Blu ray player.

I do not know how to debug/diagnose this. I've left the logging in serviio at the defaults

I get this message:
2017-07-24 23:50:37,699 INFO [PlaybackEventsManager] Playback of media item 274 (xxxxxxxxx( 2017).1080p.x264.mp4]) has stopped at 17% on Identifier=10.99.99.7, Profile=Sony Bravia TV (2011), Name=KDL-32EX723

The TV says it doesn't have any new updates to install.

How would people suggest I try and work out why playback is terminating like this ?

Re: Playback terminating unexpectedly

PostPosted: Fri Jul 28, 2017 10:03 am
by zip
It could be due to the file encoding. probably something the TV doesn't like. Maybe too high bitrate at that point (busy scene?) or just something that makes the TV think the file is corrupt.

Re: Playback terminating unexpectedly

PostPosted: Fri Jul 28, 2017 5:52 pm
by gavsdavs
zip wrote:It could be due to the file encoding. probably something the TV doesn't like. Maybe too high bitrate at that point (busy scene?) or just something that makes the TV think the file is corrupt.

I don't think the exit points are particularly busy scenes.

The machine serviio runs on should have enough horsepower and IO to transcode the file - and it should have got through that by the 35-40 minutes or so which is when it crashes out.

I may end up tcpdumping it to a file or stracing the process.

What fun.

Thanks for your thoughts - let me know if you have any other ideas....

Re: Playback terminating unexpectedly

PostPosted: Tue Mar 10, 2020 4:36 pm
by gavsdavs
I turned on debugging and I can see two WebServer 'Write failed' errors in the space of three seconds.
  Code:
2020-03-10 16:27:26,923 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Length: 1223078488,Content-Type: video/mp4,Cache-control: n
o-cache,Connection: close,Content-Range: bytes 0-1223078487/1223078488,contentFeatures.dlna.org: DLNA.ORG_PN=AVC_MP4_MP_HD_720p_AAC;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLA
GS=01500000000000000000000000000000,Date: Tue, 10 Mar 2020 16:27:26 GMT,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*,Server: Linux UPnP/1.0 DLNADOC/1.50 Serviio/2.0,transferMode.d
lna.org: Streaming]]
2020-03-10 16:27:30,847 DEBUG [MediaItemDAOImpl] Getting counts of media items by type
2020-03-10 16:27:31,274 DEBUG [WebServer] I/O error: Broken pipe (Write failed)
2020-03-10 16:27:31,280 DEBUG [ServiioHttpService] Incoming request from /10.99.99.5:52581: GET /resource/5440/MEDIA_ITEM/AVC_MP4_MP_HD_720p_AAC-0/ORIGINAL HTTP/1.1, headers
 = [Range: bytes=271658192-,getcontentFeatures.dlna.org: 1,Pragma: getIfoFileURI.dlna.org,X-AV-Physical-Unit-Info: pa="BRAVIA KDL-32EX723";,X-AV-Client-Info: av=5.0; cn="Son
y Corporation"; mn="BRAVIA KDL-32EX723"; mv="1.7";,Host: 10.99.99.116:8895]]
2020-03-10 16:27:31,280 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.99.99.5
2020-03-10 16:27:31,280 DEBUG [RendererService] Rendered 00000000-0000-1010-8000-5453ed21cab1 doesn't have a User assigned to it, using Default
2020-03-10 16:27:31,280 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.99.99.5
2020-03-10 16:27:31,280 DEBUG [RendererService] Rendered 00000000-0000-1010-8000-5453ed21cab1 doesn't have a User assigned to it, using Default
2020-03-10 16:27:31,280 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 10.99.99.5
2020-03-10 16:27:31,280 DEBUG [RendererService] Rendered 00000000-0000-1010-8000-5453ed21cab1 doesn't have a User assigned to it, using Default
2020-03-10 16:27:31,280 DEBUG [ResourceDeliveryProcessor] Resource request accepted. Using client 'Identifier=10.99.99.5, Profile=Sony Bravia TV (2011), Name=KDL-32EX723'
2020-03-10 16:27:31,280 DEBUG [ResourceDeliveryProcessor] Request for resource 5440 and type 'MEDIA_ITEM' received
2020-03-10 16:27:31,280 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 5440 (local)
2020-03-10 16:27:31,280 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 5440)
2020-03-10 16:27:31,281 DEBUG [VideoDAOImpl] Reading a Video (id = 5440)
2020-03-10 16:27:31,281 DEBUG [VideoDeliveryEngine] Retrieving resource information for item 5440, format AVC_MP4_MP_HD_720p_AAC and profile Sony Bravia TV (2011)
2020-03-10 16:27:31,281 DEBUG [MediaResourceRetrievalStrategy] Getting information about media item 5440 (local)
2020-03-10 16:27:31,281 DEBUG [MediaItemDAOImpl] Reading a MediaItem (id = 5440)
2020-03-10 16:27:31,281 DEBUG [VideoDAOImpl] Reading a Video (id = 5440)
2020-03-10 16:27:31,281 DEBUG [VideoDeliveryEngine] Delivering item '5440' for client 'Identifier=10.99.99.5, Profile=Sony Bravia TV (2011), Name=KDL-32EX723'
2020-03-10 16:27:31,281 DEBUG [VideoDeliveryEngine] Delivering file 'The World's Fastest Indian (2005).720p.x264.mp4' in native format
2020-03-10 16:27:31,281 DEBUG [MediaItemDAOImpl] Getting file of media item 5440
2020-03-10 16:27:31,281 DEBUG [GETMethodProcessor] Stream entity has length: 951420296
2020-03-10 16:27:31,281 DEBUG [ResourceTransportRequestHandler] HTTP/1.1 206 Partial Content, headers = [[Content-Length: 951420296,Content-Type: video/mp4,Cache-control: no-cache,Connection: close,Content-Range: bytes 271658192-1223078487/1223078488,contentFeatures.dlna.org: DLNA.ORG_PN=AVC_MP4_MP_HD_720p_AAC;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000,Date: Tue, 10 Mar 2020 16:27:31 GMT,realTimeInfo.dlna.org: DLNA.ORG_TLAG=*,Server: Linux UPnP/1.0 DLNADOC/1.50 Serviio/2.0,transferMode.dlna.org: Streaming]]
2020-03-10 16:27:34,515 DEBUG [WebServer] I/O error: Broken pipe (Write failed)


Still can't work out what's failing here.

Re: Playback terminating unexpectedly

PostPosted: Sat Mar 14, 2020 9:13 pm
by gavsdavs
THis is only happening on the Sony TV, it works fine on a standalone Bluray player.
Very odd. Wish I could work out why