Recording Issues

[mattisam 8]

Emby Version 4.8.11.0, Debian 12.

I am having some odd issues recording from an IPTV provider. The issue is in two parts.

It kicks off the recording a minute ahead as it is setup to do:

2025-03-24 14:59:00.726 Info LiveTV: Getting media sources for recording from channel 14885232 US: CBS 6 (KOTV) TULSA HD to record to /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E117.ts
2025-03-24 14:59:00.727 Info LiveTV: Opening live stream for recording from channel 14885232 US: CBS 6 (KOTV) TULSA HD to /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E117.ts
2025-03-24 14:59:00.727 Info LiveTvManager: Opening channel stream, external channel Id: m3u_f1d641a89bf6d7555daa9f62c034f3c2575d20316e338943b8b9bb855f183656_dc8d4fa083f6708a6c659c2aa9618e5b
2025-03-24 14:59:00.727 Info LiveTV: Streaming Channel m3u_f1d641a89bf6d7555daa9f62c034f3c2575d20316e338943b8b9bb855f183656_dc8d4fa083f6708a6c659c2aa9618e5b, with streamId: f43691f30d60b29dd7f45f491ed793b7
2025-03-24 14:59:00.727 Info SharedHttpPipelineSource: Opening SharedHttpPipelineSource Live stream from Http-http://host2:9981/x_path4_x/x_path5_x/x_path14_x?profile=pass
2025-03-24 14:59:00.727 Info HttpClient: GET http://host2:9981/x_path4_x/x_path5_x/x_path14_x?profile=pass

And then just over an hour later:

2025-03-24 16:01:00.005 Info LiveTV: Recording stopped: /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E117.ts
2025-03-24 16:01:00.005 Info MediaSourceManager: Live stream f43691f30d60b29dd7f45f491ed793b7 consumer count is now 0
2025-03-24 16:01:00.005 Info MediaSourceManager: Closing live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_f43691f30d60b29dd7f45f491ed793b7
2025-03-24 16:01:00.005 Info SharedHttpPipelineSource: Closing SharedHttpPipelineSource
2025-03-24 16:01:00.006 Info SharedHttpPipelineSource: Deleting temp files /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000000.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000001.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000002.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000003.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000004.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000005.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000006.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000007.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000008.ts, /var/lib/emby/transcoding-temp/92d546c24ce04fb0ab15b62841137d37/0000009.ts
2025-03-24 16:01:00.007 Info SharedHttpPipelineSource: SharedHttpPipelineSource is done streaming.
2025-03-24 16:01:00.040 Info MediaSourceManager: Live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_f43691f30d60b29dd7f45f491ed793b7 closed successfully
2025-03-24 16:01:00.045 Info LiveTV: Triggering refresh on /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E117.ts
2025-03-24 16:01:00.046 Info LiveTV: Refreshing recording parent /srv/EmbyRecordings/The Price Is Right (1972)

And a few minutes later:
 

2025-03-24 16:11:31.169 Info LibraryMonitor: Thu, Mar 20, 2025 (/srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E117.ts) will be refreshed.

There are no error entries between these, just other log stuff. But there are two issues. Running ffprobe on the file:
 

Input #0, mpegts, from './The Price Is Right S53E117.ts':
  Duration: 00:09:42.27, start: 9912.418067, bitrate: 3554 kb/s

So far from being an hour and 2 minutes like it should. I understand that the provider or internet can cause issues recording with dropout and what not, but there is nothing in the log about it. So what happened.

Second question is even the partial recording never shows up in Emby. I did a rescan of the library and it never shows up, but the file is there and emby has access to it. There are several entries where it says the file "will be refreshed".

I did not post the entire log as it has my servers IP in it and I dont want to make it public.

Thank you,

Matt

 

 

[Luke 39988]

Hi there, please attach or PM me the complete emby server log file. Thanks.

[mattisam 8]

Thank you Luke for looking at this. Here is the log from yesterday in question.

embyserver-63878457600.txt

[mattisam 8]

Today the same show did much better, somewhat. ffprobe shows 1 hour 2 minutes, so that is correct.

I have my IPTV provider going through TvHeadEnd (but not using the TVHeadEnd plugin in Emby). It is in passthough so its not doing anything to the stream. It says:

So all looks good on the file. But it never  shows up in Emby.

 

I do see this in the logs when it starts:

2025-03-25 14:59:04.840 Info LiveTV: Recording ensure file unique input: /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts
2025-03-25 14:59:04.840 Info LiveTV: Recording ensure file unique result: /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts
2025-03-25 14:59:04.840 Info LiveTV: Will record to /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts for 61.919321581666665 minutes.
2025-03-25 14:59:04.843 Info HttpClient: GET https://emby.tmsimg.com/assets/p27808367_b_v12_aa.jpg?w=480&h=720
2025-03-25 14:59:04.843 Info LiveTV: Copying recording stream to file /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts
2025-03-25 14:59:05.006 Info LiveTV: Triggering refresh on /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts
2025-03-25 14:59:05.007 Info LiveTV: Refreshing recording parent /srv/EmbyRecordings/The Price Is Right (1972)
2025-03-25 14:59:05.063 Info HttpClient: GET https://api4.thetvdb.com/v4/series/77072/episodes/official?page=0
2025-03-25 14:59:05.116 Error SessionManager: Error sending message
	*** Error Report ***
	Version: 4.8.11.0
	Command line: /opt/emby-server/system/EmbyServer.dll -programdata /var/lib/emby -ffdetect /opt/emby-server/bin/ffdetect -ffmpeg /opt/emby-server/bin/ffmpeg -ffprobe /opt/emby-server/bin/ffprobe -restartexitcode 3 -updatepackage emby-server-deb_{version}_amd64.deb
	Operating system: Linux version 6.1.0-32-amd64 (debian-kernel@lists.debian.org) (gcc-12 (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP PREEMPT
	Framework: .NET 6.0.36
	OS/Process: x64/x64
	Runtime: opt/emby-server/system/System.Private.CoreLib.dll
	Processor count: 16
	Data path: /var/lib/emby
	Application path: /opt/emby-server/system
	System.ObjectDisposedException: System.ObjectDisposedException: Cannot access a disposed object.
	Object name: 'RSA'.
	   at System.Security.Cryptography.RSAImplementation.RSAOpenSsl.ThrowIfDisposed()
	   at System.Security.Cryptography.RSAImplementation.RSAOpenSsl.GetKey()
	   at System.Security.Cryptography.RSAImplementation.RSAOpenSsl.TrySignHash(ReadOnlySpan`1 hash, Span`1 destination, HashAlgorithmName hashAlgorithm, RSASignaturePadding padding, Boolean allocateSignature, Int32& bytesWritten, Byte[]& signature)
	   at System.Security.Cryptography.RSAImplementation.RSAOpenSsl.SignHash(Byte[] hash, HashAlgorithmName hashAlgorithm, RSASignaturePadding padding)
	   at Microsoft.IdentityModel.Tokens.AsymmetricAdapter.SignRsa(Byte[] bytes)
	   at Microsoft.IdentityModel.Tokens.AsymmetricAdapter.Sign(Byte[] bytes)
	   at Microsoft.IdentityModel.Tokens.AsymmetricSignatureProvider.Sign(Byte[] input)
	   at Microsoft.IdentityModel.JsonWebTokens.JwtTokenUtilities.CreateEncodedSignature(String input, SigningCredentials signingCredentials)
	   at System.IdentityModel.Tokens.Jwt.JwtSecurityTokenHandler.WriteToken(SecurityToken token)
	   at Emby.Server.Implementations.Session.FirebaseSessionController.GenerateJwtToken(StringDictionary unicodeEnc)
	   at Emby.Server.Implementations.Session.FirebaseSessionController.RequestAccessToken()
	   at Emby.Server.Implementations.Session.FirebaseSessionController.SendMessage[T](ReadOnlyMemory`1 name, String messageId, T data, CancellationToken cancellationToken)
	   at Emby.Server.Implementations.Session.SessionManager.SendMessageToSession[T](SessionInfo session, Boolean requireUserInterface, ReadOnlyMemory`1 name, T data, CancellationToken cancellationToken)
	Source: System.Security.Cryptography.Algorithms
	TargetSite: Void ThrowIfDisposed()

And at the end of recording:

2025-03-25 16:00:59.998 Info LiveTV: Recording stopped: /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts
2025-03-25 16:00:59.998 Info MediaSourceManager: Live stream f43691f30d60b29dd7f45f491ed793b7 consumer count is now 0
2025-03-25 16:00:59.998 Info MediaSourceManager: Closing live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_f43691f30d60b29dd7f45f491ed793b7
2025-03-25 16:00:59.998 Info SharedHttpPipelineSource: Closing SharedHttpPipelineSource
2025-03-25 16:00:59.998 Info SharedHttpPipelineSource: Deleting temp files /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000000.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000001.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000002.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000003.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000004.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000005.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000006.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000007.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000008.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000009.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000010.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000011.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000012.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000013.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000014.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000015.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000016.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000017.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000018.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000019.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000020.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000021.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000022.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000023.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000024.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000025.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000026.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000027.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000028.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000029.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000030.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000031.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000032.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000033.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000034.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000035.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000036.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000037.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000038.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000039.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000040.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000041.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000042.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000043.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000044.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000045.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000046.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000047.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000048.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000049.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000050.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000051.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000052.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000053.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000054.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000055.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000056.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000057.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000058.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000059.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000060.ts, /var/lib/emby/transcoding-temp/02722b554a384af7b905703044d31b42/0000061.ts
2025-03-25 16:00:59.998 Info SharedHttpPipelineSource: SharedHttpPipelineSource is done streaming.
2025-03-25 16:01:00.178 Info MediaSourceManager: Live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_f43691f30d60b29dd7f45f491ed793b7 closed successfully
2025-03-25 16:01:00.181 Info LiveTV: Triggering refresh on /srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts
2025-03-25 16:01:00.181 Info LiveTV: Refreshing recording parent /srv/EmbyRecordings/The Price Is Right (1972)
2025-03-25 16:01:00.237 Info MediaProbeManager: ProcessRun 'ffprobe' Execute: /opt/emby-server/bin/ffprobe -i file:"/srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts" -threads 0 -v info -print_format json -show_streams -show_chapters -show_format -show_data
2025-03-25 16:01:00.275 Info MediaProbeManager: ProcessRun 'ffprobe' Process exited with code 0 - Succeeded
2025-03-25 16:01:00.336 Info HttpClient: GET https://api4.thetvdb.com/v4/episodes/11022406/extended?meta=translations
2025-03-25 16:01:00.530 Info QuickSingleImageExtractor: ProcessRun 'quick-extract-image' Execute: /opt/emby-server/bin/ffmpeg -skip_list 372 -f mpegts -threads 1 -i file:"/srv/EmbyRecordings/The Price Is Right (1972)/Season 53/The Price Is Right S53E118.ts" -an -sn -update 1 -vf "thumbnail=24" -vsync 0 -f image2 "/var/lib/emby/cache/temp/fe6dcde3-ce3e-45f7-a3eb-4a01ce294942.jpg"
2025-03-25 16:01:06.891 Info App: Sqlite: 284 - automatic index on LastWatchedEpisodes(SeriesPresentationUniqueKey)
2025-03-25 16:01:10.525 Info App: Sqlite: 284 - automatic index on LastWatchedEpisodes(SeriesPresentationUniqueKey)
2025-03-25 16:01:10.803 Info App: Sqlite: 284 - automatic index on LastWatchedEpisodes(SeriesPresentationUniqueKey)
2025-03-25 16:01:16.515 Info App: Sqlite: 284 - automatic index on LastWatchedEpisodes(SeriesPresentationUniqueKey)

 

So it looks like the recording was successful today, but wont show up in Emby. Attached is todays log.

embyserver.txt

[mattisam 8]

I also did a "Scan Library Files" and it did not appear in Emby. The file seems to be 100% complete but not showing up.

[mattisam 8]

So today the show recorded fine and shows 1 hour 2 minutes per ffprobe. And it showed up in Emby right away. (Episode 120). The Episode 118 is there and good to go both with ffprobe and watching it via VLC, but never shows up in Emby. Yesterdays episode 119 never recorded for some reason and I have no idea why. This is a strange issue. Anyone?

[mattisam 8]

It recorded great and the recordings showed up in Emby just fine for a few days. Once again it failed to record. Anyone?

It seems that it tried to start, but then failed? I am watching a different channel at the same time and my provider provides 4 streams. So I dont know why it did not work
 

2025-04-01 14:59:28.570 Info SharedHttpPipelineSource: Finished copying from response stream: Http-http://host2:9981/x_path4_x/x_path5_x/x_path11_x?profile=pass
2025-04-01 14:59:28.570 Error SharedHttpPipelineSource: Shut down stream from http://host2:9981/x_path4_x/x_path5_x/x_path11_x?profile=pass - The operation does not allow more than 2 retries within 00:05:00
2025-04-01 14:59:31.571 Error SharedHttpPipelineSource: Failed to shut down GraphRunner!
2025-04-01 14:59:31.571 Info SharedHttpPipelineSource: SharedHttpPipelineSource is done streaming.

[Luke 39988]

On 4/1/2025 at 11:07 AM, mattisam said:

It recorded great and the recordings showed up in Emby just fine for a few days. Once again it failed to record. Anyone?

It seems that it tried to start, but then failed? I am watching a different channel at the same time and my provider provides 4 streams. So I dont know why it did not work
 

2025-04-01 14:59:28.570 Info SharedHttpPipelineSource: Finished copying from response stream: Http-http://host2:9981/x_path4_x/x_path5_x/x_path11_x?profile=pass
2025-04-01 14:59:28.570 Error SharedHttpPipelineSource: Shut down stream from http://host2:9981/x_path4_x/x_path5_x/x_path11_x?profile=pass - The operation does not allow more than 2 retries within 00:05:00
2025-04-01 14:59:31.571 Error SharedHttpPipelineSource: Failed to shut down GraphRunner!
2025-04-01 14:59:31.571 Info SharedHttpPipelineSource: SharedHttpPipelineSource is done streaming.

Hi there, please attach the complete emby server log file. Thanks.

[mattisam 8]

Its been working so far. I will report if it does not work again.

Edited April 5 by mattisam