Project

General

Profile

Bug #2865

Current recorded programs are deleted right after the recording is started

Added by Meindert Oldenburger over 9 years ago. Updated over 9 years ago.

Status:
Fixed
Priority:
Normal
Assignee:
Adam Sutton
Category:
PVR / DVR
Target version:
-
Start date:
2015-05-21
Due date:
% Done:

100%

Estimated time:
Found in version:
4.1-21~g48f219c
Affected Versions:

Description

This seems a regression from 'fix' on issue #2861

Files

tvheadend_access_entries.png (86.4 KB) tvheadend_access_entries.png Meindert Oldenburger, 2015-05-21 21:27
tvh_htsp_dvr.trace (109 KB) tvh_htsp_dvr.trace --trace htsp,dvr Meindert Oldenburger, 2015-05-24 10:18
zonder_unww.log (46.8 KB) zonder_unww.log No un/pw log file Meindert Oldenburger, 2015-05-26 22:31

History

#1

Updated by Meindert Oldenburger over 9 years ago

Reproducable by initiate a recording in Kodi (Isengard) of a program that is currently shown.

The following recordings failed:
- automatic recordings;
- recodings initiated by the client.

Recoding programmed in de webclient seems to be OK.

#2

Updated by Jaroslav Kysela over 9 years ago

Upgrade to 4.1-25 and and provide logs. Many things were touched.

#3

Updated by Meindert Oldenburger over 9 years ago

Tested with 4.1-25

Part of the from tvheadend:
May 21 21:14:23 core2 tvheadend15567: htsp: Got connection from 192.168.2.101
May 21 21:14:23 core2 tvheadend15567: htsp: 192.168.2.101: Welcomed client software: Kodi Media Center (HTSPv12)
May 21 21:14:35 core2 tvheadend15567: dvr: entry 172d4941466006a70229e0fe9c598f6e "Studio PowNed" on "NPO 3 HD" starting at 2015-05-21 20:54:30, scheduled for recording by "(null)"
May 21 21:14:35 core2 tvheadend15567: dvr: "Studio PowNed" on "NPO 3 HD" recorder starting
May 21 21:14:35 core2 tvheadend15567: dvr: unable to find access (owner '(null)', creator '(null)')
May 21 21:14:43 core2 tvheadend15567: htsp: 192.168.2.101 [ Kodi Media Center ]: Disconnected

Part of the log from Kodi:
21:14:35 T:140094425585728 DEBUG: ------ Window Deinit (DialogYesNo.xml) ------
21:14:35 T:140094425585728 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending message [addDvrEntry : 78]
21:14:35 T:140093061277440 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - receive message [dvrEntryAdd]
21:14:35 T:140093061277440 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - received response [78]
21:14:35 T:140093069670144 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1095314711, state:scheduled, title:Studio PowNed, desc:(s 1/afl 15) (Nederland)., error:none
21:14:35 T:140093109040896 DEBUG: CPVRTimers - Update - updating timers
21:14:35 T:140093061277440 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - receive message [dvrEntryUpdate]
21:14:35 T:140093069670144 DEBUG: Previous line repeats 1 times.
21:14:35 T:140093069670144 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1095314711, state:recording, title:Studio PowNed, desc:(s 1/afl 15) (Nederland)., error:none
21:14:35 T:140093069670144 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - recording id:1095314711, state:completed, title:Studio PowNed, desc:(s 1/afl 15) (Nederland)., error:none

#4

Updated by Meindert Oldenburger over 9 years ago

Access Entries screen dump added

#5

Updated by Jaroslav Kysela over 9 years ago

  • Status changed from New to Fixed
  • % Done changed from 0 to 100

Applied in changeset commit:tvheadend|ccbdd82e4175d965d1279d44409840a30ef8f0cf.

#6

Updated by Jaroslav Kysela over 9 years ago

OK. Creator was not identified for HTSP. Try again with latest...

#7

Updated by Meindert Oldenburger over 9 years ago

No stil not working. Tested from Kodi.

May 21 21:56:01 core2 tvheadend24733: START: HTS Tvheadend version 4.1-31~g86ef485 started, running as PID:24733 UID:114 GID:44, CWD:/ CNF:/home/hts/.hts/tvheadend

...

May 21 21:59:13 core2 tvheadend24733: dvr: "Louis Theroux: By Reason of Insanity" on "NPO 3 HD" recorder starting
May 21 21:59:13 core2 tvheadend24733: dvr: unable to find access (owner '(null)', creator '(null)')
May 21 21:59:21 core2 tvheadend24733: htsp: 192.168.2.101 [ Kodi Media Center ]: Disconnected
May 21 22:00:17 core2 tvheadend24733: dvr: entry 27acf84422a29f32c948a5af628da1d7 "NOS-Jeugdjournaal" on "NPO 3 HD" starting at 2015-05-28 18:39:30, scheduled for recording by "admin"
May 21 22:00:17 core2 tvheadend24733: dvr: entry 9c3ea840667fb9b093e0e9cc9f1145dc "Het Klokhuis" on "NPO 3 HD" starting at 2015-05-28 18:17:30, scheduled for recording by "admin"

#8

Updated by Jaroslav Kysela over 9 years ago

  • Status changed from Fixed to Accepted

Provide whole HTSP log (login, create DVR entry, close) and according dvr lines.. Note that you have to enter a new recordings to take effect.

#9

Updated by Meindert Oldenburger over 9 years ago

Is below log what you mean? else explain me what to do to get the right log. I started from the Kodi client an recording.

What do you mean with "Note that you have to enter a new recordings to take effect."

872 [ INFO]:htsp: 192.168.2.101 [ Kodi Media Center ]: Identified as user meindert
2015-05-22 21:01:10.872 [ INFO]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ]: Unauthorized access
2015-05-22 21:01:10.872 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method authenticate
2015-05-22 21:01:10.872 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method enableAsyncMetadata
2015-05-22 21:01:10.986 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getDiskSpace
2015-05-22 21:01:12.614 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.620 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.631 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.641 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.654 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.659 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.667 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.676 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.686 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.687 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.696 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.706 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.712 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.714 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.720 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.721 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.733 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.733 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.737 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.743 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.747 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.755 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.762 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.771 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.781 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.788 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.791 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.799 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.808 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.813 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.822 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.829 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.835 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.844 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.854 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.861 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.869 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.872 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.877 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.886 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.898 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.904 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.912 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.918 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.926 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.926 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.927 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.927 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.927 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.928 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.928 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.929 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.929 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.929 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.930 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.930 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.930 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.931 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.931 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.944 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.951 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.956 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.965 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.972 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.982 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.986 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.989 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:12.998 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:13.005 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:13.010 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:13.016 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:13.024 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getEvents
2015-05-22 21:01:13.638 [ TRACE]:htsp: 127.0.0.1 [ | Kodi Media Center ] - method getDiskSpace
2015-05-22 21:01:18.696 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method addDvrEntry
2015-05-22 21:01:18.697 [ INFO]:dvr: entry e8aebc864ab5bbbb2170d148d048227a "Superkids" on "RTL 4 HD" starting at 2015-05-22 20:24:30, scheduled for recording by "(null)"
2015-05-22 21:01:18.698 [ INFO]:dvr: "Superkids" on "RTL 4 HD" recorder starting
2015-05-22 21:01:18.698 [ ERROR]:dvr: unable to find access (owner '(null)', creator '(null)')
2015-05-22 21:01:20.992 [ TRACE]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ] - method getDiskSpace
2015-05-22 21:01:23.644 [ TRACE]:htsp: 127.0.0.1 [ | Kodi Media Center ] - method getDiskSpace
2015-05-22 21:01:23.707 [ INFO]:htsp: 192.168.2.101 [ meindert | Kodi Media Center ]: Disconnected
2015-05-22 21:01:33.649 [ TRACE]:htsp: 127.0.0.1 [ | Kodi Media Center ] - method getDiskSpace
2015-05-22 21:01:43.655 [ TRACE]:htsp: 127.0.0.1 [ | Kodi Media Center ] - method getDiskSpace

#10

Updated by Meindert Oldenburger over 9 years ago

Jaroslav Kysela wrote:

Provide whole HTSP log (login, create DVR entry, close) and according dvr lines.. Note that you have to enter a new recordings to take effect.

For whole HTSP log see #9, this is when i initiate a recording from the Kodi client. It stil doesn't work, but is used often.

Automatic recodings seems to go well if they are newly created, so I did this for all of them. Could you explaine to me why. I don't saw anny differences in the recodings structure in the configuration files at ~/.hts/tvheadend/dvr/autorec?!

#11

Updated by Meindert Oldenburger over 9 years ago

To provide also dvr info in the trace (see logfile).

sudo /usr/local/bin/tvheadend -u hts -g video --trace htsp,dvr -l /tmp/tvh_htsp_dvr.trace

#12

Updated by Jaroslav Kysela over 9 years ago

  • Status changed from Accepted to Fixed

Applied in changeset commit:tvheadend|38a5b8482d04d45e0c6c6341cdbc9fcd5766259b.

#13

Updated by Meindert Oldenburger over 9 years ago

Not fixed :(

#14

Updated by Jaroslav Kysela over 9 years ago

  • Status changed from Fixed to Accepted

Provide logs again.

#15

Updated by Meindert Oldenburger over 9 years ago

Good thing is that: if I enter some text in username/passwrd in the TVH addon that the problem seems to be solved. In previous releases it was not possible to make a conection with TVHeadend server but now it is even possible to initiate a recording.

Bad: If I'm not providing an username/passwrd in the TVH addon (fields are 'blank') the same behaviour is there (created en right after that a deleted message)

See log file 'zonder_unww.log'

Thanks for the hard work TVHeadend 4.0 rocks :)

#16

Updated by Jaroslav Kysela over 9 years ago

Could you retest v4.1-79-g7478260 ?

#17

Updated by Meindert Oldenburger over 9 years ago

Retest with 4.1-94~g72a0ebe and it works fine now.

Thanks

#18

Updated by Jaroslav Kysela over 9 years ago

  • Status changed from Accepted to Fixed

Also available in: Atom PDF