Jump to content


Photo

Issue with 4.4.0.44 playback all the sudden?


  • Please log in to reply
10 replies to this topic

#1 scaryfast OFFLINE  

scaryfast

    Newbie

  • Members
  • 6 posts
  • Local time: 09:00 AM

Posted 30 March 2020 - 10:02 PM

Long time emby user with lifetime membership and literally never had a problem...until today.

 

Server - Mac mini catalina 10.15.3 - 3.6Quad Core and 8gb ram.  Dedicated machine for Emby.

File Share - Drobo5n dedicated for movies.

 

If I play this from my iPhone emby app or browser on another Mac it works instantly.  Trying to stream from the any of the RokuTV's fails with this error.  To my knowledge, I havent changed anything away from default settings. Please advise.

 

Some moves all the sudden are giving this error --> System.Exception: System.Exception: Error starting ffmpeg

 

-----Log File below---

 

2020-03-30 20:44:30.066 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 8ms. http://192.168.1.140...,ProductionYear

2020-03-30 20:44:30.090 Info HttpServer: HTTP GET http://192.168.1.140...imit=1&limit=10. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:30.113 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 23ms. http://192.168.1.140...imit=1&limit=10
2020-03-30 20:44:30.117 Info HttpServer: HTTP GET http://192.168.1.140...imit=1&limit=40. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:30.119 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 2ms. http://192.168.1.140...imit=1&limit=40
2020-03-30 20:44:30.806 Info HttpServer: HTTP GET http://192.168.1.140...System/Endpoint. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:30.807 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 1ms. http://192.168.1.140...System/Endpoint
2020-03-30 20:44:31.149 Info HttpServer: HTTP GET http://192.168.1.140...,ProductionYear. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:31.156 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 7ms. http://192.168.1.140...,ProductionYear
2020-03-30 20:44:31.688 Info HttpServer: HTTP GET http://192.168.1.140...fields=Overview. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:31.692 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 4ms. http://192.168.1.140...fields=Overview
2020-03-30 20:44:32.089 Info HttpServer: HTTP POST http://192.168.1.140...5818e4303b04a07. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:32.097 Info MediaInfoService: User policy for Home. EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
2020-03-30 20:44:32.097 Info MediaInfoService: Profile: VideoContainerProfile, DirectPlay=false. Reason=Roku SG.NumVideoStreams Condition: Equals. ConditionValue: 1. IsRequired: False. Path: /Volumes/Drobo5N/drb154901a00351/1/Drobo-Backup/Movies/Spies in Disguise - 2019.m4v
2020-03-30 20:44:32.097 Info MediaInfoService: Profile: VideoContainerProfile, DirectPlay=false. Reason=Roku SG.NumVideoStreams Condition: Equals. ConditionValue: 1. IsRequired: False. Path: /Volumes/Drobo5N/drb154901a00351/1/Drobo-Backup/Movies/Spies in Disguise - 2019.m4v
2020-03-30 20:44:32.097 Info MediaInfoService: Profile: VideoContainerProfile, DirectPlay=false. Reason=Roku SG.NumVideoStreams Condition: Equals. ConditionValue: 1. IsRequired: False. Path: /Volumes/Drobo5N/drb154901a00351/1/Drobo-Backup/Movies/Spies in Disguise - 2019.m4v
2020-03-30 20:44:32.097 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 8ms. http://192.168.1.140...5818e4303b04a07
2020-03-30 20:44:33.060 Info HttpServer: HTTP GET http://192.168.1.140...Enhancers=false. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:33.060 Info HttpServer: HTTP GET http://192.168.1.140...78e02e45f930821. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:33.061 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 1ms. http://192.168.1.140...78e02e45f930821
2020-03-30 20:44:33.061 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 2ms. http://192.168.1.140...Enhancers=false
2020-03-30 20:44:33.510 Info HttpServer: HTTP GET http://192.168.1.140...5818e4303b04a07. Connection=close, Host=192.168.1.140:8096, User-Agent=Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:33.514 Info HttpServer: HTTP GET http://192.168.1.140...audiochannels=6. Host=192.168.1.140:8096, User-Agent=Roku/DVP-9.20 (309.20E04502A), Accept=*/*, Accept-Encoding=deflate, gzip
2020-03-30 20:44:33.531 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 17ms. http://192.168.1.140...audiochannels=6
2020-03-30 20:44:33.537 Info HttpServer: HTTP GET http://192.168.1.140...audiochannels=6. Host=192.168.1.140:8096, User-Agent=Roku/DVP-9.20 (309.20E04502A), Accept=*/*, Accept-Encoding=deflate, gzip
2020-03-30 20:44:33.695 Info HttpServer: HTTP Response 200 to 192.168.1.161. Time: 158ms. http://192.168.1.140...audiochannels=6
2020-03-30 20:44:33.780 Info HttpServer: HTTP POST http://192.168.1.140...essions/Playing. UserAgent: Roku/DVP-9.20 (309.20E04502A)
2020-03-30 20:44:33.814 Info SessionManager: Playback start reported by app Roku SG 3.0.212 playing Spies in Disguise. Started at 0 ms
2020-03-30 20:44:33.817 Info HttpServer: HTTP Response 204 to 192.168.1.161. Time: 37ms. http://192.168.1.140...essions/Playing
2020-03-30 20:44:34.214 Info HttpServer: HTTP GET http://192.168.1.140...audiochannels=6. Host=192.168.1.140:8096, User-Agent=Roku/DVP-9.20 (309.20E04502A), Accept=*/*
2020-03-30 20:44:34.333 Info DynamicHlsService: ProcessRun 'StreamTranscode 074423' Execute: /Applications/EmbyServer.app/Contents/MacOS/ffmpeg -loglevel +timing -y -print_graphs_file /Users/scaryfast/emby-server/logs/ffmpeg-directstream-07442333-7686-444a-9d3d-ef7b4afaff4f_1graph.txt -copyts -start_at_zero -f m4v -noaccurate_seek -c:v:0 h264 -c:v:1 mjpeg -c:v:2 mjpeg -i "/Volumes/Drobo5N/drb154901a00351/1/Drobo-Backup/Movies/Spies in Disguise - 2019.m4v" -map 0:0 -map 0:1 -sn -c:v:0 copy -bsf:v:0 h264_mp4toannexb -c:a:0 copy -metadata:s:a:0 language=eng -disposition:a:0 default -copypriorss:a:0 0 -max_delay 5000000 -avoid_negative_ts disabled -f segment -map_metadata -1 -map_chapters -1 -segment_format mpegts -segment_list /Users/scaryfast/emby-server/transcoding-temp/A293F8.m3u8 -segment_list_type m3u8 -segment_time 3 -segment_start_number 0 -break_non_keyframes 1 -individual_header_trailer 0 -write_header_trailer 0 -segment_write_temp 1 "/Users/scaryfast/emby-server/transcoding-temp/A293F8_%d.ts"
2020-03-30 20:44:35.811 Info DynamicHlsService: AppendExtraLogData - Read graph file: /Users/scaryfast/emby-server/logs/ffmpeg-directstream-07442333-7686-444a-9d3d-ef7b4afaff4f_1graph.txt
2020-03-30 20:44:35.820 Info DynamicHlsService: AppendExtraLogData - Deserialized GraphData fileStream: {0:n} bytes Graph Count: 0
2020-03-30 20:44:35.820 Info DynamicHlsService: AppendExtraLogData - File Deleted
2020-03-30 20:44:35.831 Error HttpServer: Error processing request
    *** Error Report ***
    Version: 4.4.0.40
    Command line: /Applications/EmbyServer.app/Contents/MacOS/EmbyServer.dll
    Operating system: Unix 19.3.0.0
    64-Bit OS: True
    64-Bit Process: True
    User Interactive: True
    Runtime: file:///Applications/EmbyServer.app/Contents/MacOS/System.Private.CoreLib.dll
    System.Environment.Version: 3.1.2
    Processor count: 4
    Program data path: /Users/scaryfast/emby-server
    Application directory: /Applications/EmbyServer.app/Contents/MacOS
    System.Exception: System.Exception: Error starting ffmpeg
     at Emby.Server.MediaEncoding.Api.BaseStreamingService.StartFfMpeg(StreamState state, String outputPath, CancellationTokenSource cancellationTokenSource, Boolean acquireResources)
     at Emby.Server.MediaEncoding.Api.Hls.DynamicHlsService.GetDynamicSegment(StreamRequest request, String playlistId, String segmentId, Int32 subtitleStreamIndex)
     at Emby.Server.Implementations.Services.ServiceController.GetTaskResult(Task task)
     at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, RestPath restPath, String responseContentType, CancellationToken cancellationToken)
     at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, ReadOnlyMemory`1 urlString, ReadOnlyMemory`1 localPath, CancellationToken cancellationToken)
    Source: Emby.Server.MediaEncoding
    TargetSite: Void MoveNext()



#2 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 156872 posts
  • Local time: 09:00 AM

Posted 31 March 2020 - 12:21 AM

Hi there, I apologize for this issue. The 4.4 release has a problem with conversion of m4v files. We'll be getting a 4.4.1 update out to resolve this. Thanks.


  • AZRichG likes this

#3 scaryfast OFFLINE  

scaryfast

    Newbie

  • Members
  • 6 posts
  • Local time: 09:00 AM

Posted 03 April 2020 - 11:11 AM

@Luke is there an ETA for the 4.4.1 release.  Just looking for ballpark, is this Days out or Months out?



#4 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 156872 posts
  • Local time: 09:00 AM

Posted 03 April 2020 - 11:33 AM

Yes very soon.

#5 scaryfast OFFLINE  

scaryfast

    Newbie

  • Members
  • 6 posts
  • Local time: 09:00 AM

Posted 04 April 2020 - 10:38 AM

@Luke 4.4.1 did NOT resolve the issue.  Please advise.

 

2020-04-04 09:35:59.546 Info DynamicHlsService: ProcessRun 'StreamTranscode fcb535' Execute: /Applications/EmbyServer.app/Contents/MacOS/ffmpeg -loglevel +timing -y -print_graphs_file /Users/scaryfast/emby-server/logs/ffmpeg-directstream-fcb535c4-7bb6-45ac-ac87-1e1aa9a9d844_1graph.txt -copyts -start_at_zero -f m4v -noaccurate_seek -c:v:0 h264 -c:v:1 mjpeg -c:v:2 mjpeg -i "/Volumes/Drobo5N/drb154901a00351/1/Drobo-Backup/Movies/moviename - 2020.m4v" -map 0:0 -map 0:1 -sn -c:v:0 copy -bsf:v:0 h264_mp4toannexb -c:a:0 copy -metadata:s:a:0 language=eng -disposition:a:0 default -copypriorss:a:0 0 -max_delay 5000000 -avoid_negative_ts disabled -f segment -map_metadata -1 -map_chapters -1 -segment_format mpegts -segment_list /Users/scaryfast/emby-server/transcoding-temp/DFD62C.m3u8 -segment_list_type m3u8 -segment_time 3 -segment_start_number 0 -break_non_keyframes 1 -individual_header_trailer 0 -write_header_trailer 0 -segment_write_temp 1 "/Users/scaryfast/emby-server/transcoding-temp/DFD62C_%d.ts"

2020-04-04 09:36:00.170 Info DynamicHlsService: AppendExtraLogData - Read graph file: /Users/scaryfast/emby-server/logs/ffmpeg-directstream-fcb535c4-7bb6-45ac-ac87-1e1aa9a9d844_1graph.txt
2020-04-04 09:36:00.177 Info DynamicHlsService: AppendExtraLogData - Deserialized GraphData fileStream: {0:n} bytes Graph Count: 0
2020-04-04 09:36:00.178 Info DynamicHlsService: AppendExtraLogData - File Deleted
2020-04-04 09:36:00.220 Error HttpServer: Error processing request
    *** Error Report ***
    Version: 4.4.1.0
    Command line: /Applications/EmbyServer.app/Contents/MacOS/EmbyServer.dll -psn_0_2241059 -noautorunwebapp
    Operating system: Unix 19.4.0.0
    64-Bit OS: True
    64-Bit Process: True
    User Interactive: True
    Runtime: file:///Applications/EmbyServer.app/Contents/MacOS/System.Private.CoreLib.dll
    System.Environment.Version: 3.1.2
    Processor count: 4
    Program data path: /Users/scaryfast/emby-server
    Application directory: /Applications/EmbyServer.app/Contents/MacOS
    System.Exception: System.Exception: Error starting ffmpeg
     at Emby.Server.MediaEncoding.Api.BaseStreamingService.StartFfMpeg(StreamState state, String outputPath, CancellationTokenSource cancellationTokenSource, Boolean acquireResources)
     at Emby.Server.MediaEncoding.Api.Hls.DynamicHlsService.GetDynamicSegment(StreamRequest request, String playlistId, String segmentId, Int32 subtitleStreamIndex)
     at Emby.Server.Implementations.Services.ServiceController.GetTaskResult(Task task)
     at Emby.Server.Implementations.Services.ServiceHandler.ProcessRequestAsync(HttpListenerHost appHost, IRequest httpReq, IResponse httpRes, RestPath restPath, String responseContentType, CancellationToken cancellationToken)
     at Emby.Server.Implementations.HttpServer.HttpListenerHost.RequestHandler(IRequest httpReq, ReadOnlyMemory`1 urlString, ReadOnlyMemory`1 localPath, CancellationToken cancellationToken)
    Source: Emby.Server.MediaEncoding
    TargetSite: Void MoveNext()



#6 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 156872 posts
  • Local time: 09:00 AM

Posted 04 April 2020 - 10:39 AM

Please attach the complete emby server and ffmpeg logs. Thanks.

#7 scaryfast OFFLINE  

scaryfast

    Newbie

  • Members
  • 6 posts
  • Local time: 09:00 AM

Posted 04 April 2020 - 10:46 AM

@Luke please see attached and let me know if you need anything else.

Attached Files



#8 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 156872 posts
  • Local time: 09:00 AM

Posted 05 April 2020 - 03:35 PM

Ok yes there's still an issue with certain m4v files. We'll be getting a 4.4.2 update out in the coming days. Thanks.



#9 scaryfast OFFLINE  

scaryfast

    Newbie

  • Members
  • 6 posts
  • Local time: 09:00 AM

Posted 08 April 2020 - 08:43 PM

@Luke I see a bunch of 4.4.2 releases 4.4.2.0 but I dont see a release for MacOS and the main page is still linked to 4.4.1.

 

Which version should I install to test.



#10 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 156872 posts
  • Local time: 09:00 AM

Posted 09 April 2020 - 01:01 AM

It's coming. Thanks.



#11 scaryfast OFFLINE  

scaryfast

    Newbie

  • Members
  • 6 posts
  • Local time: 09:00 AM

Posted 09 April 2020 - 03:09 PM

FIXED with 4.4.2.  Thank you.






0 user(s) are reading this topic

0 members, 0 guests, 0 anonymous users