Active Recording "Unable to Play" Then Disappeared/Deleted

[bungee91 98]

This is a first for me.

 

Summary: I had an active recording (Fox 32 News Sunday) that I wanted to start to play at two minutes prior to its ending.

I also had another recording on the same channel (likely same tuner) that followed/overlapped this recording.

I go to play the active recording on Android Tv and get an error that I believe stated that "cannot play this video type" (which is odd, I perform this kind of playback regularly) or something similar to that error message..

 

I try again, same message, reboot the player, same..

The recording ends, I assume it will be added to the recent recordings, it never shows up there.

I look at the storage location where the recordings are stored, and there is no file for this recording.

 

I decided to start watching the 2nd show that recorded right after the first, and it played as expected while being recorded.

 

It's a BIG log (can't be helped), but this happened at approximately 10PM, the active recording that I had the issue with was Fox 32 News Sunday, and the recording that started on the same channel after it was The Final Word.

 

I did not send a log file from the app, HDHR firmware is the newest version per the silicondust forum, server log attached.

 

 

Edit:removed log

Edited August 11, 2017 by bungee91

[bungee91 98]

Try to narrow this down some...

 

I have also had an odd (possibly related issue) where the date for these recording is WAY off (this one is supposedly dated 2017-06-04 which is completely incorrect).

Not sure if Schedules Direct is having an issue, or what, but it has been happening lately.

 

From the log there seems to be an error writing to file for another recording at roughly the time I had this issue:

2017-08-06 20:58:00.2947 Info App: Writing file to path: /mnt/user/Recorded_TV/Fox 32 News at Nine Sunday/Fox 32 News at Nine Sunday 2017-06-04-1.ts
2017-08-06 20:58:00.2947 Info App: Opening recording stream from tuner provider
2017-08-06 20:58:00.3018 Info App: Recording timer fired.
2017-08-06 20:58:00.3263 Error App: Error recording to /mnt/user/Recorded_TV/Island Hunters/Season 4/Island Hunters S04E07 Big Time in Fiji.ts
	*** Error Report ***
	Version: 3.2.26.0
	Command line: /usr/lib/emby-server/bin/MediaBrowser.Server.Mono.exe -programdata /config -restartpath /usr/lib/emby-server/restart.sh
	Operating system: Unix 4.9.30.0
	64-Bit OS: True
	64-Bit Process: True
	Mono: 4.8.1 (Stable 4.8.1.0/22a39d7 Fri Jun  2 05:04:04 UTC 2017)
	Processor count: 8
	Program data path: /config
	Application directory: /usr/lib/emby-server/bin
	System.NotImplementedException: The method or operation is not implemented.
	  at Emby.Server.Implementations.LiveTv.EmbyTV.EmbyTV+<GetChannelStreamMediaSources>d__89.MoveNext () [0x00119] in <86cdc6fa995d4c5dbd0334612a6105b6>:0 
	--- End of stack trace from previous location where exception was thrown ---
	  at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.ConfiguredTaskAwaitable`1+ConfiguredTaskAwaiter[TResult].GetResult () [0x00000] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at Emby.Server.Implementations.LiveTv.EmbyTV.EmbyTV+<RecordStream>d__96.MoveNext () [0x0028a] in <86cdc6fa995d4c5dbd0334612a6105b6>:0 
	System.NotImplementedException
	  at Emby.Server.Implementations.LiveTv.EmbyTV.EmbyTV+<GetChannelStreamMediaSources>d__89.MoveNext () [0x00119] in <86cdc6fa995d4c5dbd0334612a6105b6>:0 
	--- End of stack trace from previous location where exception was thrown ---
	  at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x0000c] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) [0x0004e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) [0x0002e] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) [0x0000b] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.Runtime.CompilerServices.ConfiguredTaskAwaitable`1+ConfiguredTaskAwaiter[TResult].GetResult () [0x00000] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at Emby.Server.Implementations.LiveTv.EmbyTV.EmbyTV+<RecordStream>d__96.MoveNext () [0x0028a] in <86cdc6fa995d4c5dbd0334612a6105b6>:0 

It also is complaining it cannot find the file:

2017-08-06 22:01:02.6081 Error HttpServer: Error processing request
	*** Error Report ***
	Version: 3.2.26.0
	Command line: /usr/lib/emby-server/bin/MediaBrowser.Server.Mono.exe -programdata /config -restartpath /usr/lib/emby-server/restart.sh
	Operating system: Unix 4.9.30.0
	64-Bit OS: True
	64-Bit Process: True
	Mono: 4.8.1 (Stable 4.8.1.0/22a39d7 Fri Jun  2 05:04:04 UTC 2017)
	Processor count: 8
	Program data path: /config
	Application directory: /usr/lib/emby-server/bin
	System.IO.FileNotFoundException: Could not find file "/mnt/user/Recorded_TV/Fox 32 News at Nine Sunday/Fox 32 News at Nine Sunday 2017-06-04-1.ts".
	File name: '/mnt/user/Recorded_TV/Fox 32 News at Nine Sunday/Fox 32 News at Nine Sunday 2017-06-04-1.ts'
	  at System.IO.FileStream..ctor (System.String path, System.IO.FileMode mode, System.IO.FileAccess access, System.IO.FileShare share, System.Int32 bufferSize, System.Boolean anonymous, System.IO.FileOptions options) [0x0021a] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at System.IO.FileStream..ctor (System.String path, System.IO.FileMode mode, System.IO.FileAccess access, System.IO.FileShare share, System.Int32 bufferSize, System.IO.FileOptions options) [0x00000] in <dbb16e0bacdc4a0f87478e401bc29b6c>:0 
	  at (wrapper remoting-invoke-with-check) System.IO.FileStream:.ctor (string,System.IO.FileMode,System.IO.FileAccess,System.IO.FileShare,int,System.IO.FileOptions)
	  at Emby.Common.Implementations.IO.ManagedFileSystem.GetFileStream (System.String path, MediaBrowser.Model.IO.FileOpenMode mode, MediaBrowser.Model.IO.FileAccessMode access, MediaBrowser.Model.IO.FileShareMode share, MediaBrowser.Model.IO.FileOpenOptions fileOpenOptions) [0x00045] in <6931fa62dfb84503a521112cc3b77c40>:0 
	  at MediaBrowser.Api.Playback.Progressive.ProgressiveFileCopier.GetInputStream (System.Boolean allowAsyncFileRead) [0x00011] in <ef62672ffbb843c8adfb39a4e9c07772>:0 
	  at MediaBrowser.Api.Playback.Progressive.ProgressiveFileCopier+<WriteToAsync>d__20.MoveNext () [0x0010c] in <ef62672ffbb843c8adfb39a4e9c07772>:0 
	--- End of stack trace from previous location where exception was thrown ---

Then it almost looks like it is requested to delete it:

2017-08-06 20:58:13.4532 Info App: Deleting item, Type: Episode, Name: Fox 32 News at Nine Sunday 2017-06-04, Path: /mnt/user/Recorded_TV/Fox 32 News at Nine Sunday/Fox 32 News at Nine Sunday 2017-06-04.mkv, Id: d73edd28-d719-5a6c-ec3a-0fdf413192cf
2017-08-06 20:58:13.5337 Info App: Deleting item, Type: Episode, Name: Fox 32 News at Nine Sunday 2017-06-04, Path: /mnt/user/Recorded_TV/Fox 32 News at Nine Sunday/Fox 32 News at Nine Sunday 2017-06-04.mkv, Id: d73edd28-d719-5a6c-ec3a-0fdf413192cf
2017-08-06 20:58:13.6777 Info App: Copying recording stream to file /mnt/user/Recorded_TV/Fox 32 News at Nine Sunday/Fox 32 News at Nine Sunday 2017-06-04-1.ts
2017-08-06 20:58:50.4671 Info HttpServer: HTTP GET http://73.50.71.149:8096/emby/system/info/public?format=json. UserAgent: Dalvik/2.1.0 (Linux; U; Android 7.1.2; Pixel Build/N2G47Z)
2017-08-06 20:58:50.4737 Info HttpServer: HTTP Response 200 to 68.40.26.121. Time: 7ms. http://73.50.71.149:8096/emby/system/info/public?format=json 
2017-08-06 20:58:52.8630 Info HttpServer: HTTP GET http://73.50.71.149:8096/emby/Connect/Exchange?format=json&ConnectUserId=136634. UserAgent: Dalvik/2.1.0 (Linux; U; Android 7.1.2; Pixel Build/N2G47Z)
2017-08-06 20:58:52.8701 Info SessionManager: Reissuing access token: 4e9121a8d8f7426db449a2b2dd770271

Look forward to any help.

[Luke 37099]

@@bungee91, is this a series recording?

[bungee91 98]

@@Luke yes, it was.

 

Could it be related to my dates being all goofy sometimes (like it deleted it because it looks like an old recording)? I know when the date is far off like this Emby will mark the watched status as watched, even though I haven't yet.

[Luke 37099]

Try deleting the series recording and recreating it. It appears something has changed since you first created it, possibly some information coming from your HDHR, and now it is not recording at all because it can't figure out which channel it's on. Thanks.

[bungee91 98]

@@Luke, ok I did just that.

 

This one was still odd though, as it showed as an active recording the entire time I tried to play it (I've never seen that happen).

So it thought it was recording it.

 

I have seen this mentioned in other threads, but looking through my series recordings I had many that were no longer recording, even though guide data and the show was being aired. 

This was roughly 1/2 of my series set to record (about 8 shows). After deleting them, and setting them as a new series recording they scheduled as should be expected.

Hopefully HDHR or SD don't make any changes again that cause this to become a reoccurring issue.

[Luke 37099]

The way we generate our channel id's is based on data from HDHR, and it is sensitive to changes. So if our internal channel id changes, then all of a sudden the recording process can't find the channel it was supposed to record on. We will have to look at how we can be more resilient to changes in channel data from the tuner. thanks.

[bungee91 98]

Makes sense, thanks for following up!