Jump to content


Photo

4.2.0.25 Recording failed with "Emby Premiere required to use Emby DVR."

dvr premiere

  • Please log in to reply
22 replies to this topic

#1 ProperlyFormattedDataFile OFFLINE  

ProperlyFormattedDataFile

    Advanced Member

  • Members
  • 65 posts
  • Local time: 12:14 PM

Posted 09 July 2019 - 08:57 PM

This seems similar to the thread about invalid license keys, but in my case the key seems to show as valid.  Today a regular recording failed to record, and the only clue I get from the logs is this:

 

2019-07-09 19:00:00.021 Info LiveTV: Recording timer fired for Jeopardy!.
2019-07-09 19:00:00.039 Warn LiveTV: Emby Premiere required to use Emby DVR.

 

I have a valid premiere license, and on the server on the Emby Premiere tab I have:

 
<green checkmark> You have a Monthly Emby Premiere plan and your device usage is well within your limit.
 
 

Attached Files



#2 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 138076 posts
  • Local time: 12:14 PM

Posted 09 July 2019 - 10:32 PM

Hi, does the emby premiere key page show your key in it?



#3 ProperlyFormattedDataFile OFFLINE  

ProperlyFormattedDataFile

    Advanced Member

  • Members
  • 65 posts
  • Local time: 12:14 PM

Posted 09 July 2019 - 11:32 PM

Hi, does the emby premiere key page show your key in it?

 

Yes, it does.



#4 sfatula OFFLINE  

sfatula

    Advanced Member

  • Members
  • 387 posts
  • Local time: 11:14 AM
  • LocationCalera, OK

Posted 10 July 2019 - 03:14 AM

This seems similar to the thread about invalid license keys, but in my case the key seems to show as valid.  Today a regular recording failed to record, and the only clue I get from the logs is this:
 
2019-07-09 19:00:00.021 Info LiveTV: Recording timer fired for Jeopardy!.
2019-07-09 19:00:00.039 Warn LiveTV: Emby Premiere required to use Emby DVR.
 
I have a valid premiere license, and on the server on the Emby Premiere tab I have:
 
<green checkmark> You have a Monthly Emby Premiere plan and your device usage is well within your limit.


I've had this same issue when a license validate check failed. The claim was it takes a long time of failures (>1cday) before emby will deny access to premiere features, but for me, it was virtually immediate.

#5 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 138076 posts
  • Local time: 12:14 PM

Posted 11 July 2019 - 02:43 AM

Please try 4.2.0.27+, and if you run into this again, please attach the emby server log. thanks.



#6 ProperlyFormattedDataFile OFFLINE  

ProperlyFormattedDataFile

    Advanced Member

  • Members
  • 65 posts
  • Local time: 12:14 PM

Posted 15 July 2019 - 03:26 PM

Please try 4.2.0.27+, and if you run into this again, please attach the emby server log. thanks.

 

Thanks, it's happened once so far on 4.2.0.27.  I've attached the log.  The error is on the same 7PM Jeopardy recording.

Attached Files



#7 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 138076 posts
  • Local time: 12:14 PM

Posted 15 July 2019 - 03:46 PM

Thanks, it's happened once so far on 4.2.0.27.  I've attached the log.  The error is on the same 7PM Jeopardy recording.

Can you attach the previous log, before the log file rotation? thanks.



#8 ProperlyFormattedDataFile OFFLINE  

ProperlyFormattedDataFile

    Advanced Member

  • Members
  • 65 posts
  • Local time: 12:14 PM

Posted 15 July 2019 - 07:36 PM

It happened today again on 4.2.0.30 at 7:00PM.  I've attached my /var/lib/emby/logs directory as a zip.

 

Can you attach the previous log, before the log file rotation? thanks.

 

Attached Files



#9 sfatula OFFLINE  

sfatula

    Advanced Member

  • Members
  • 387 posts
  • Local time: 11:14 AM
  • LocationCalera, OK

Posted 15 July 2019 - 08:18 PM

So, had 4 shows set to record at 7PM today. It's 7:16PM, and, 1 is recording. Where's the other 3? I have 6 tuners.

 

 

Attached Files



#10 sfatula OFFLINE  

sfatula

    Advanced Member

  • Members
  • 387 posts
  • Local time: 11:14 AM
  • LocationCalera, OK

Posted 15 July 2019 - 08:21 PM

Yep, so, had trouble with 4.2.0.30 noted elsewhere, stopped emby, went back to .28, started emby, and, it says premiere required. So, it would not record one of the 3 shows. Needs fixed.

Attached Files


Edited by sfatula, 15 July 2019 - 08:40 PM.


#11 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 138076 posts
  • Local time: 12:14 PM

Posted 15 July 2019 - 10:18 PM

Why do you feel that they didn't happen? 6

#12 sfatula OFFLINE  

sfatula

    Advanced Member

  • Members
  • 387 posts
  • Local time: 11:14 AM
  • LocationCalera, OK

Posted 15 July 2019 - 10:24 PM

Cause they didn't, no files in the directories, nothing on latest, nothing showing active recording except for the one, and, tuner showed only 1 being used. I see you merged my post, mine is on 4.2.0.30.

Finally, looked in hdhomerun system log, only 1 channel tuned. 23:59 is my 7pm. Note after restarting, it tunes to the scheduled channels.

 

This is the second time this has happened to me, curiously both times same day as a Beta update.

HDHR log:
20190715-23:59:00 Tuner: tuner0 tuning 10.1 KTENNBC (8vsb:545MHz-3)
20190715-23:59:01 Tuner: tuner0 streaming http to 192.168.3.18:36615
20190716-00:19:15 Tuner: tuner0 http stream ended (remote closed)
20190716-00:19:31 Tuner: tuner0 tuning 12.1 KXIICBS (8vsb:207MHz-1)
20190716-00:19:31 Tuner: tuner1 tuning 10.3 KTENABC (8vsb:545MHz-5)
20190716-00:19:31 Tuner: tuner1 streaming http to 192.168.3.18:46823
20190716-00:19:31 Tuner: tuner0 streaming http to 192.168.3.18:43403
20190716-00:22:17 Tuner: tuner2 tuning 10.1 KTENNBC (8vsb:545MHz-3)
20190716-00:22:18 Tuner: tuner2 streaming http to 192.168.3.18:40243
20190716-01:03:00 Tuner: tuner0 http stream ended (remote closed)
20190716-02:03:00 Tuner: tuner2 http stream ended (remote closed)
20190716-02:04:02 Tuner: tuner1 http stream ended (remote closed)
20190716-02:59:00 Tuner: tuner0 tuning 10.1 KTENNBC (8vsb:545MHz-3)
20190716-02:59:01 Tuner: tuner0 streaming http to 192.168.3.18:42389
20190716-03:37:00 Tuner: tuner0 http stream ended (remote closed)


Edited by sfatula, 16 July 2019 - 02:53 AM.


#13 ProperlyFormattedDataFile OFFLINE  

ProperlyFormattedDataFile

    Advanced Member

  • Members
  • 65 posts
  • Local time: 12:14 PM

Posted 15 July 2019 - 11:15 PM

So the 7:00PM recording failed as I mentioned.  The next two scheduled recordings happened, and I was able to watch the recording of one of them while it was in progress, but as soon as the recordings finished the files were deleted.  This feels like a separate bug that I ran into on an earlier build:

 

2019-07-15 22:00:00.002 Info MediaEncoder: ProcessRun 'Encoding c17180': Stopping ffmpeg process with q command for /usr/local/data/recordings/American Ninja Warrior (2009)/Season 11/American Ninja Warrior S11E07 Los Angeles City Finals.ts
2019-07-15 22:00:00.076 Info MediaEncoder: ProcessRun 'Encoding c17180' Process exited with code 0
2019-07-15 22:00:00.076 Info MediaEncoder: FFMpeg exited with code 0
2019-07-15 22:00:00.174 Info HttpServer: SocketException: http://127.0.0.1:809...36f06/stream.ts
2019-07-15 22:00:00.174 Info HttpServer: HTTP Response 200 to 127.0.0.1. Time: 7198541ms. http://127.0.0.1:809...36f06/stream.ts
2019-07-15 22:00:00.480 Info LiveTV: Recording stopped: /usr/local/data/recordings/American Ninja Warrior (2009)/Season 11/American Ninja Warrior S11E07 Los Angeles City Finals.ts
2019-07-15 22:00:00.481 Info MediaSourceManager: Live stream native_dcae7ee91da8349d9a3089106668280d_0cad33e7094290eb2e02437d2bfd39ed consumer count is now 0
2019-07-15 22:00:00.481 Info MediaSourceManager: Closing live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_native_dcae7ee91da8349d9a3089106668280d_0cad33e7094290eb2e02437d2bfd39ed
2019-07-15 22:00:00.481 Info SharedHttpPipelineSource: Closing SharedHttpPipelineSource
2019-07-15 22:00:00.481 Info MediaSourceManager: Live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_native_dcae7ee91da8349d9a3089106668280d_0cad33e7094290eb2e02437d2bfd39ed closed successfully
2019-07-15 22:00:00.481 Info LiveTV: Triggering refresh on /usr/local/data/recordings/American Ninja Warrior (2009)/Season 11/American Ninja Warrior S11E07 Los Angeles City Finals.ts
2019-07-15 22:00:00.483 Info LiveTV: Refreshing recording parent /usr/local/data/recordings/American Ninja Warrior (2009)/Season 11
2019-07-15 22:00:00.509 Info SharedHttpPipelineSource: SharedHttpPipelineSource is done streaming.
2019-07-15 22:00:00.509 Info SharedHttpPipelineSource: Deleting temp files /var/lib/emby/transcoding-temp/c3fcbf0a281b4ff0b3da46ec7cd36f06.ts
2019-07-15 22:00:00.595 Info App: Removing item from database, Type: Episode, Name: Los Angeles City Finals, Path: /usr/local/data/recordings/American Ninja Warrior (2009)/Season 11/American Ninja Warrior S11E07 Los Angeles City Finals.ts, Id: 110259

Attached Files


Edited by ProperlyFormattedDataFile, 15 July 2019 - 11:16 PM.


#14 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 138076 posts
  • Local time: 12:14 PM

Posted 16 July 2019 - 12:04 PM

Hi, please try 4.2.0.31. It should be all good now. Thanks.



#15 sfatula OFFLINE  

sfatula

    Advanced Member

  • Members
  • 387 posts
  • Local time: 11:14 AM
  • LocationCalera, OK

Posted 16 July 2019 - 01:07 PM

I don't see any comment in the 4.2.0.31 release notes that says anything about recordings not starting, just an omission?

This version (.31) did start 3 recordings after I installed it.

Edited by sfatula, 16 July 2019 - 08:02 PM.


#16 ProperlyFormattedDataFile OFFLINE  

ProperlyFormattedDataFile

    Advanced Member

  • Members
  • 65 posts
  • Local time: 12:14 PM

Posted 16 July 2019 - 10:05 PM

Hi, please try 4.2.0.31. It should be all good now. Thanks.

 

The Premiere issue hasn't happened again yet, but recordings are still disappearing at an alarming rate.  Of the two recording today, 1 of them disappeared after recording:

 

2019-07-16 20:18:48.836 Info LiveTV: Recording stopped: /usr/local/data/recordings/Pandora (2008)/Season 1/Pandora S01E01 Shelter From the Storm.ts
2019-07-16 20:18:48.836 Info MediaSourceManager: Live stream native_38e2ec422972cf18ae399822dbeeeca8_0cad33e7094290eb2e02437d2bfd39ed consumer count is now 0
2019-07-16 20:18:48.836 Info MediaSourceManager: Closing live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_native_38e2ec422972cf18ae399822dbeeeca8_0cad33e7094290eb2e02437d2bfd39ed
2019-07-16 20:18:48.836 Info SharedHttpPipelineSource: Closing SharedHttpPipelineSource
2019-07-16 20:18:48.855 Info HttpServer: HTTP GET https://rhonhart.dyn...rogramId=101433. UserAgent: Mozilla/5.0 (Linux; Android 9; Pixel 3 Build/PQ3A.190801.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/75.0.3770.143 Mobile Safari/537.36
2019-07-16 20:18:48.864 Info HttpServer: HTTP Response 200 to 174.203.16.63. Time: 9ms. https://rhonhart.dyn...rogramId=101433
2019-07-16 20:18:48.870 Info SharedHttpPipelineSource: SharedHttpPipelineSource is done streaming.
2019-07-16 20:18:48.870 Info SharedHttpPipelineSource: Deleting temp files /var/lib/emby/transcoding-temp/49f1d840a9784053b2c50e78c720eaf0.ts
2019-07-16 20:18:48.870 Info HttpServer: HTTP Response 200 to 127.0.0.1. Time: 70301ms. http://127.0.0.1:809...0eaf0/stream.ts
2019-07-16 20:18:48.870 Info MediaSourceManager: Live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_native_38e2ec422972cf18ae399822dbeeeca8_0cad33e7094290eb2e02437d2bfd39ed closed successfully
2019-07-16 20:18:48.870 Info LiveTV: Triggering refresh on /usr/local/data/recordings/Pandora (2008)/Season 1/Pandora S01E01 Shelter From the Storm.ts
2019-07-16 20:18:48.871 Info LiveTV: Refreshing recording parent /usr/local/data/recordings/Pandora (2008)/Season 1
2019-07-16 20:18:48.886 Info HttpServer: HTTP GET https://rhonhart.dyn...39b3e851c638d36. UserAgent: Mozilla/5.0 (Linux; Android 9; Pixel 3 Build/PQ3A.190801.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/75.0.3770.143 Mobile Safari/537.36
2019-07-16 20:18:48.890 Info HttpServer: HTTP Response 200 to 174.203.16.63. Time: 4ms. https://rhonhart.dyn...39b3e851c638d36
2019-07-16 20:18:48.956 Info HttpServer: HTTP GET https://rhonhart.dyn...39b3e851c638d36. UserAgent: Mozilla/5.0 (Linux; Android 9; Pixel 3 Build/PQ3A.190801.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/75.0.3770.143 Mobile Safari/537.36
2019-07-16 20:18:48.963 Info HttpServer: HTTP Response 200 to 174.203.16.63. Time: 6ms. https://rhonhart.dyn...39b3e851c638d36
2019-07-16 20:18:48.978 Info App: Removing item from database, Type: Episode, Name: Shelter From the Storm, Path: /usr/local/data/recordings/Pandora (2008)/Season 1/Pandora S01E01 Shelter From the Storm.ts, Id: 111875
2019-07-16 20:18:48.978 Info App: Deleting path /var/lib/emby/metadata/library/a2/a29d2be745a8a8b339447d21feb08dd6

Attached Files



#17 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 138076 posts
  • Local time: 12:14 PM

Posted 16 July 2019 - 10:49 PM

The Premiere issue hasn't happened again yet, but recordings are still disappearing at an alarming rate.  Of the two recording today, 1 of them disappeared after recording:

 

2019-07-16 20:18:48.836 Info LiveTV: Recording stopped: /usr/local/data/recordings/Pandora (2008)/Season 1/Pandora S01E01 Shelter From the Storm.ts
2019-07-16 20:18:48.836 Info MediaSourceManager: Live stream native_38e2ec422972cf18ae399822dbeeeca8_0cad33e7094290eb2e02437d2bfd39ed consumer count is now 0
2019-07-16 20:18:48.836 Info MediaSourceManager: Closing live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_native_38e2ec422972cf18ae399822dbeeeca8_0cad33e7094290eb2e02437d2bfd39ed
2019-07-16 20:18:48.836 Info SharedHttpPipelineSource: Closing SharedHttpPipelineSource
2019-07-16 20:18:48.855 Info HttpServer: HTTP GET https://rhonhart.dyn...rogramId=101433. UserAgent: Mozilla/5.0 (Linux; Android 9; Pixel 3 Build/PQ3A.190801.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/75.0.3770.143 Mobile Safari/537.36
2019-07-16 20:18:48.864 Info HttpServer: HTTP Response 200 to 174.203.16.63. Time: 9ms. https://rhonhart.dyn...rogramId=101433
2019-07-16 20:18:48.870 Info SharedHttpPipelineSource: SharedHttpPipelineSource is done streaming.
2019-07-16 20:18:48.870 Info SharedHttpPipelineSource: Deleting temp files /var/lib/emby/transcoding-temp/49f1d840a9784053b2c50e78c720eaf0.ts
2019-07-16 20:18:48.870 Info HttpServer: HTTP Response 200 to 127.0.0.1. Time: 70301ms. http://127.0.0.1:809...0eaf0/stream.ts
2019-07-16 20:18:48.870 Info MediaSourceManager: Live stream 06044cf0e6f93cdae5f285c9ecfaaeb4_01413a525b3a9622ce6fdf19f7dde354_native_38e2ec422972cf18ae399822dbeeeca8_0cad33e7094290eb2e02437d2bfd39ed closed successfully
2019-07-16 20:18:48.870 Info LiveTV: Triggering refresh on /usr/local/data/recordings/Pandora (2008)/Season 1/Pandora S01E01 Shelter From the Storm.ts
2019-07-16 20:18:48.871 Info LiveTV: Refreshing recording parent /usr/local/data/recordings/Pandora (2008)/Season 1
2019-07-16 20:18:48.886 Info HttpServer: HTTP GET https://rhonhart.dyn...39b3e851c638d36. UserAgent: Mozilla/5.0 (Linux; Android 9; Pixel 3 Build/PQ3A.190801.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/75.0.3770.143 Mobile Safari/537.36
2019-07-16 20:18:48.890 Info HttpServer: HTTP Response 200 to 174.203.16.63. Time: 4ms. https://rhonhart.dyn...39b3e851c638d36
2019-07-16 20:18:48.956 Info HttpServer: HTTP GET https://rhonhart.dyn...39b3e851c638d36. UserAgent: Mozilla/5.0 (Linux; Android 9; Pixel 3 Build/PQ3A.190801.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/75.0.3770.143 Mobile Safari/537.36
2019-07-16 20:18:48.963 Info HttpServer: HTTP Response 200 to 174.203.16.63. Time: 6ms. https://rhonhart.dyn...39b3e851c638d36
2019-07-16 20:18:48.978 Info App: Removing item from database, Type: Episode, Name: Shelter From the Storm, Path: /usr/local/data/recordings/Pandora (2008)/Season 1/Pandora S01E01 Shelter From the Storm.ts, Id: 111875
2019-07-16 20:18:48.978 Info App: Deleting path /var/lib/emby/metadata/library/a2/a29d2be745a8a8b339447d21feb08dd6

 

There should have been a recording ffmpeg log. can you please attach that? Thanks.



#18 ProperlyFormattedDataFile OFFLINE  

ProperlyFormattedDataFile

    Advanced Member

  • Members
  • 65 posts
  • Local time: 12:14 PM

Posted 16 July 2019 - 11:13 PM

There should have been a recording ffmpeg log. can you please attach that? Thanks.

 

Sure, attached what I believe is the right file

Attached Files


Edited by ProperlyFormattedDataFile, 16 July 2019 - 11:20 PM.


#19 Luke OFFLINE  

Luke

    System Architect

  • Administrators
  • 138076 posts
  • Local time: 12:14 PM

Posted 16 July 2019 - 11:16 PM

You attached the emby server log (good), but there should have also been an ffmpeg recording log.t hanks.



#20 ProperlyFormattedDataFile OFFLINE  

ProperlyFormattedDataFile

    Advanced Member

  • Members
  • 65 posts
  • Local time: 12:14 PM

Posted 16 July 2019 - 11:21 PM

You attached the emby server log (good), but there should have also been an ffmpeg recording log.t hanks.

 

Already edited above.  Forgot to actually upload the file after selecting it the first time I posted.







Also tagged with one or more of these keywords: dvr, premiere

0 user(s) are reading this topic

0 members, 0 guests, 0 anonymous users