Beta [Real-time scheduling] schedule without rebooting

Status
Not open for further replies.
I have just had another runaway recording. This time it was Gandhi 9:10 this morning on Movie Mix (like Terrahawk in previous post). Nothing else being recorded or played on this box today.

af123 Which logs / extracts are useful for diagnosis?




Sent from my iPad using Tapatalk
 
One thing I can add which may be significant is that something (Humax or CF) has scheduled Gandhi to record on the second screening tomorrow.

Sent from my SM-G930F using Tapatalk
 
This is the log for Sunday. the recording was scheduled for 9:10

Code:
Sun Oct  2 08:52:10 2016: Final schedule entries: 33
Sun Oct  2 08:52:10 2016: rsvsync starting.
Sun Oct  2 08:52:10 2016: Opening /var/lib/humaxtv/rsvp.db
Sun Oct  2 08:52:10 2016: Restoring any favourites.
Sun Oct  2 08:52:10 2016: Slots:
Sun Oct  2 08:52:10 2016: Ignoring: no such table: fav (no such table: fav)
02/10/2016 08:52:48 - System booted (Scheduled event).
[RR] Sun Oct  2 09:10:55 2016:    Changing to red.
[RR] Sun Oct  2 09:10:55 2016: Recording start 23:'/mnt/hd2/My Video/Gandhi_20161002_0910.nts'
[RR] Sun Oct  2 09:10:55 2016:    Recording 1
[RR] Sun Oct  2 09:10:55 2016:    Changing to red.
[RR] Sun Oct  2 09:10:55 2016: REC icon on.
[RR] Sun Oct  2 09:10:55 2016: Persistent log starting, v2.17
un Oct  2 09:10:55 2016: Persistent log starting, v2.17
[RR] Sun Oct  2 09:10:55 2016:    System is in standby.
02/10/2016 09:20:17 - Scheduled Defence Of The Realm @ 1476025200
02/10/2016 09:20:22 - Scheduled All Night Long @ 1476045600
02/10/2016 09:20:26 - Scheduled The Girl in a Swing @ 1475976300
Sun Oct  2 09:20:28 2016: Real-time mode.
[nugget]: Sun Oct  2 09:20:28 2016: Persistent log starting, v0.96
Sun Oct  2 09:20:28 2016: Nugget is available.
Sun Oct  2 09:20:28 2016: rsvsync starting.
Sun Oct  2 09:20:29 2016: Slots: +2,+9,+10
Sun Oct  2 09:20:29 2016: Moving pending entry 2 to spare slot 10
[nugget]: Sun Oct  2 09:20:29 2016: schedule save starting.
Sun Oct  2 09:20:29 2016: Opening /var/lib/humaxtv/rsvp.db
[nugget]: Sun Oct  2 09:20:29 2016: Schedule load failed.
[nugget]: Sun Oct  2 09:20:29 2016: Re-loading schedule database (36).
[nugget]: Sun Oct  2 09:20:29 2016: Magic: 0xa029a697 (open)
[nugget]: Sun Oct  2 09:20:29 2016: Closing database.
[nugget]: Sun Oct  2 09:20:29 2016: Flags: 0x100800
Sun Oct  2 09:20:29 2016: Moving pending entry 0 to spare slot 2
[nugget]: Sun Oct  2 09:20:29 2016: Last Error: 5 (ff)
Sun Oct  2 09:20:29 2016: Moving pending entry 1 to spare slot 9
Sun Oct  2 09:20:29 2016: Schedule saved.
Sun Oct  2 09:20:29 2016: Final schedule entries: 36
[nugget]: Sun Oct  2 09:20:29 2016: Schedule failed to reload, deferring.
[nugget]: Sun Oct  2 09:20:29 2016: schedule.stash 25 = 2
[nugget]: Sun Oct  2 09:20:29 2016: schedule save complete.
[nugget]: Sun Oct  2 09:20:29 2016: Closing db handle due to error.
[nugget]: Sun Oct  2 09:20:29 2016: Deferred thread starting for '36:+2,+9,+10' (attempt 1)
Sun Oct  2 09:20:29 2016: Loading schedule information to HumaxTV binary.
[nugget]: Sun Oct  2 09:20:34 2016:   Defence Of The Realm
[nugget]: Sun Oct  2 09:20:34 2016: Processing slot +2 (2)
[nugget]: Sun Oct  2 09:20:34 2016: slots(36, +2,+9,+10)
[nugget]: Sun Oct  2 09:20:34 2016:  Wake 1476044700 - Sun Oct  9 21:25:00 2016
[nugget]: Sun Oct  2 09:20:34 2016: schedule reload complete (36).
[nugget]: Sun Oct  2 09:20:34 2016:    @ 1476045600 (Sun Oct  9 21:40:00 2016)
[nugget]: Sun Oct  2 09:20:34 2016: schedule.restore 25 = 2
[nugget]: Sun Oct  2 09:20:34 2016: Ready 1476044670 - Sun Oct  9 21:24:30 2016
[nugget]: Sun Oct  2 09:20:34 2016:   All Night Long
[nugget]: Sun Oct  2 09:20:34 2016: Ready 1476024270 - Sun Oct  9 15:44:30 2016
[nugget]: Sun Oct  2 09:20:34 2016:    @ 1476025200 (Sun Oct  9 16:00:00 2016)
[nugget]: Sun Oct  2 09:20:34 2016: Deferred thread exiting.
[nugget]: Sun Oct  2 09:20:34 2016:  Wake 1476024300 - Sun Oct  9 15:45:00 2016
[nugget]: Sun Oct  2 09:20:34 2016: Re-loading schedule database (36).
[nugget]: Sun Oct  2 09:20:34 2016:   The Girl in a Swing
[nugget]: Sun Oct  2 09:20:34 2016:     - Sun Oct  9 15:44:30 2016
[nugget]: Sun Oct  2 09:20:34 2016: Cannot set EPG, missing CRID.
[nugget]: Sun Oct  2 09:20:34 2016:  Wake 1475975400 - Sun Oct  9 02:10:00 2016
[nugget]: Sun Oct  2 09:20:34 2016: Ready 1475975370 - Sun Oct  9 02:09:30 2016
[nugget]: Sun Oct  2 09:20:34 2016: Adding timer(1475975370)
[nugget]: Sun Oct  2 09:20:34 2016:     - Sun Oct  9 02:09:30 2016
[nugget]: Sun Oct  2 09:20:34 2016:     - Sun Oct  9 21:24:30 2016
[nugget]: Sun Oct  2 09:20:34 2016: Processing slot +9 (9)
[nugget]: Sun Oct  2 09:20:34 2016: Cannot set EPG, missing CRID.
[nugget]: Sun Oct  2 09:20:34 2016: Adding timer(1476024270)
[nugget]: Sun Oct  2 09:20:34 2016: Cannot set EPG, missing CRID.
[nugget]: Sun Oct  2 09:20:34 2016: Adding timer(1476044670)
[nugget]: Sun Oct  2 09:20:34 2016: Processing slot +10 (10)
[nugget]: Sun Oct  2 09:20:34 2016:    @ 1475976300 (Sun Oct  9 02:25:00 2016)
[RR] Sun Oct  2 16:40:01 2016: Ring going blue, full boot.
[RR] Sun Oct  2 16:40:01 2016: Ring going red.
[RR] Sun Oct  2 16:40:01 2016: REC icon on.
[RR] Sun Oct  2 17:00:02 2016: Standby ring dim detected.
[RR] Sun Oct  2 17:00:03 2016: Ring trying to go amber.
[RR] Sun Oct  2 17:00:03 2016: Standby ring dim detected.
[RR] Sun Oct  2 17:00:04 2016:   Still recording.
[RR] Sun Oct  2 19:30:46 2016:    Changing to purple.
[RR] Sun Oct  2 19:30:46 2016: Recording start 32:'/mnt/hd2/My Video/Mr Bean/Mr Bean_20161002_1930.nts'
[RR] Sun Oct  2 19:30:46 2016:    Recording 2
[RR] Sun Oct  2 20:02:25 2016:    Changing to red.
[RR] Sun Oct  2 20:02:25 2016: Recording end 32.
02/10/2016 20:02:31 - Recorded: Mr Bean/Mr Bean (31 minutes - ITV4)
[RR] Sun Oct  2 20:18:23 2016: Ring going blue, full boot.
[RR] Sun Oct  2 20:18:23 2016: REC icon on.
[RR] Sun Oct  2 20:18:23 2016: Ring going red.
[RR] Sun Oct  2 20:19:40 2016: Recording end 23.
[RR] Sun Oct  2 20:19:40 2016: REC icon off.
02/10/2016 20:19:45 - Recorded: /Gandhi (668 minutes - Movie Mix)
[RR] Sun Oct  2 20:31:32 2016: Standby ring dim detected.
[RR] Sun Oct  2 20:31:32 2016: Standby ring dim detected.
[RR] Sun Oct  2 20:31:33 2016:   Changing to dim blue.
[RR] Sun Oct  2 20:31:33 2016: Ring trying to go amber.
[RR] Sun Oct  2 21:01:17 2016:    Changing to red.
[RR] Sun Oct  2 21:01:17 2016:    System is in standby.
[RR] Sun Oct  2 21:01:17 2016: REC icon on.
[RR] Sun Oct  2 21:01:17 2016:    Recording 1
[RR] Sun Oct  2 21:01:17 2016: Recording start 23:'/mnt/hd2/My Video/Celebrity Island with Bear Grylls/Celebrity Island with Bear Grylls_20161002_2101.nts'
[RR] Sun Oct  2 21:04:20 2016: Ring going blue, full boot.
[RR] Sun Oct  2 21:04:20 2016: REC icon on.
[RR] Sun Oct  2 21:04:21 2016: Ring going red.
[RR] Sun Oct  2 21:11:03 2016:    Changing to purple.
[RR] Sun Oct  2 21:11:03 2016:    Recording 2
[RR] Sun Oct  2 21:11:03 2016: Recording start 46:'/mnt/hd2/My Video/Sewers of Gold_20161002_2111.nts'
[RR] Sun Oct  2 21:12:47 2016: Play icon on.
[RR] Sun Oct  2 21:28:27 2016: Play icon off.
[RR] Sun Oct  2 22:04:10 2016:    Changing to red.
[RR] Sun Oct  2 22:04:10 2016: Recording end 23.
02/10/2016 22:04:18 - Recorded: Celebrity Island with Bear Grylls/Celebrity Island with Bear Grylls (62 minutes - Channel 4 HD)
[RR] Sun Oct  2 23:20:48 2016: REC icon off.
[RR] Sun Oct  2 23:20:48 2016: Recording end 46.
[RR] Sun Oct  2 23:20:49 2016:    Recording 1
[RR] Sun Oct  2 23:20:49 2016: REC icon on.
[RR] Sun Oct  2 23:20:49 2016: Recording start 31:'/mnt/hd2/My Video/The Day Of The Triffids_20161002_2320.nts'
[RR] Sun Oct  2 23:20:49 2016: Ring going red.
02/10/2016 23:20:54 - Recorded: /Sewers of Gold (129 minutes - TalkingPictures TV)
[RR] Mon Oct  3 00:13:19 2016: Standby ring dim detected.
[RR] Mon Oct  3 00:13:19 2016: Standby ring dim detected.
[RR] Mon Oct  3 00:13:20 2016:   Still recording.
[RR] Mon Oct  3 00:13:20 2016: Ring trying to go amber.
[RR] Mon Oct  3 01:15:55 2016:    Changing to dim blue.
[RR] Mon Oct  3 01:15:55 2016: Recording end 31.
[RR] Mon Oct  3 01:15:55 2016:    System is in standby.
[RR] Mon Oct  3 01:15:55 2016: REC icon off.
[RR] Mon Oct  3 01:15:56 2016:    Recording 1
[RR] Mon Oct  3 01:15:56 2016:    Changing to red.
[RR] Mon Oct  3 01:15:56 2016: Recording start 31:'/mnt/hd2/My Video/Dr_ Crippen_20161003_0115.nts'
[RR] Mon Oct  3 01:15:56 2016:    System is in standby.
[RR] Mon Oct  3 01:15:56 2016: REC icon on.
03/10/2016 01:16:01 - Recorded: /The Day Of The Triffids (115 minutes - TalkingPictures TV)
03/10/2016 02:05:07 - Automatically upgraded package tunefix-update from 1.0.13 to 1.0.14
03/10/2016 02:09:59 - Automatically upgraded package webif from 1.3.2-6 to 1.3.2-7
[RR] Mon Oct  3 03:15:49 2016: REC icon off.
[RR] Mon Oct  3 03:15:49 2016:    Changing to dim blue.
[RR] Mon Oct  3 03:15:49 2016: Recording end 31.
[RR] Mon Oct  3 03:15:49 2016:    System is in standby.
 
I attempted to move next weeks episode of the Jonathan Ross show to ITV+1 using the 'Record this showing instead' option to resolve a conflict,
Scheduling the new recording worked but the skip of the original recording did not work:
Code:
16/10/2016 12:28:03 - System booted (Remote control handset).
16/10/2016 12:51:30 - Scheduled New: The Jonathan Ross Show @ 1477173000
16/10/2016 12:51:35 - Scheduled New: The Jonathan Ross Show @ 1477169400
Sun Oct 16 12:51:37 2016: Setting skip on slot 39 (-1)
Sun Oct 16 12:51:37 2016: Nugget is available.
Sun Oct 16 12:51:37 2016: Slots: +18,+39
Sun Oct 16 12:51:37 2016: Moving pending entry 0 to spare slot 18
[nugget]: Sun Oct 16 12:51:37 2016: Persistent log starting, v0.96
[nugget]: Sun Oct 16 12:51:37 2016: schedule save starting.
Sun Oct 16 12:51:37 2016: Final schedule entries: 39
Sun Oct 16 12:51:37 2016: Real-time mode.
[nugget]: Sun Oct 16 12:51:37 2016: Deferred thread starting for '39:+18,+39' (attempt 1)
Sun Oct 16 12:51:37 2016: Opening /var/lib/humaxtv/rsvp.db
[nugget]: Sun Oct 16 12:51:37 2016: Flags: 0x100800
Sun Oct 16 12:51:37 2016: Schedule saved.
Sun Oct 16 12:51:37 2016: Loading schedule information to HumaxTV binary.
[nugget]: Sun Oct 16 12:51:37 2016: Closing db handle due to error.
[nugget]: Sun Oct 16 12:51:37 2016: Schedule load failed.
[nugget]: Sun Oct 16 12:51:37 2016: Re-loading schedule database (39).
[nugget]: Sun Oct 16 12:51:37 2016: Schedule failed to reload, deferring.
Sun Oct 16 12:51:37 2016: Found slot 39 for 3/262242/0/29639
[nugget]: Sun Oct 16 12:51:37 2016: Last Error: 5 (ff)
[nugget]: Sun Oct 16 12:51:37 2016: schedule save complete.
Sun Oct 16 12:51:37 2016: rsvsync starting.
[nugget]: Sun Oct 16 12:51:37 2016: Closing database.
[nugget]: Sun Oct 16 12:51:37 2016: Magic: 0xa029a697 (open)
[nugget]: Sun Oct 16 12:51:42 2016:    @ 1477173000 (Sat Oct 22 22:50:00 2016)
[nugget]: Sun Oct 16 12:51:42 2016: Re-loading schedule database (39).
[nugget]: Sun Oct 16 12:51:42 2016: Processing slot +18 (18)
[nugget]: Sun Oct 16 12:51:42 2016:   New: The Jonathan Ross Show
[nugget]: Sun Oct 16 12:51:42 2016: Adding timer(1477172070)
[nugget]: Sun Oct 16 12:51:42 2016:     - Sat Oct 22 22:34:30 2016
[nugget]: Sun Oct 16 12:51:42 2016:   New: The Jonathan Ross Show
[nugget]: Sun Oct 16 12:51:42 2016:  Wake 1477172100 - Sat Oct 22 22:35:00 2016
[nugget]: Sun Oct 16 12:51:42 2016:    @ 1477169400 (Sat Oct 22 21:50:00 2016)
[nugget]: Sun Oct 16 12:51:42 2016: Ready 1477172070 - Sat Oct 22 22:34:30 2016
[nugget]: Sun Oct 16 12:51:42 2016: Processing slot +39 (39)
[nugget]: Sun Oct 16 12:51:42 2016: schedule reload complete (39).
[nugget]: Sun Oct 16 12:51:42 2016:     - Sat Oct 22 21:34:30 2016
[nugget]: Sun Oct 16 12:51:42 2016:   Recorded:
[nugget]: Sun Oct 16 12:51:42 2016: Deferred thread exiting.
[nugget]: Sun Oct 16 12:51:42 2016:   CRID: 2/[WWW.ITV.COM/225464158]
[nugget]: Sun Oct 16 12:51:42 2016: slots(39, +18,+39)
[nugget]: Sun Oct 16 12:51:42 2016:       Sat Oct 22 22:50:00 2016 +65m
[nugget]: Sun Oct 16 12:51:42 2016:   CRID: 1/[WWW.ITV.COM/229424142]
[nugget]: Sun Oct 16 12:51:42 2016:  Wake 1477168500 - Sat Oct 22 21:35:00 2016
[nugget]: Sun Oct 16 12:51:42 2016: Adding timer(1477168470)
[nugget]: Sun Oct 16 12:51:42 2016:   Recorded: 1WWW.ITV.COM/229424142|1WWW.ITV.COM/228623154|
[nugget]: Sun Oct 16 12:51:42 2016:  [0] 262248,1477173000,1477176900,53743
[nugget]: Sun Oct 16 12:51:42 2016:   Events: 1WWW.ITV.COM/229424142|
[nugget]: Sun Oct 16 12:51:42 2016:   Last Event: 0
[nugget]: Sun Oct 16 12:51:42 2016: Ready 1477168470 - Sat Oct 22 21:34:30 2016
[nugget]: Sun Oct 16 12:51:42 2016:   Last Event: 25617
[nugget]: Sun Oct 16 12:51:42 2016:   Events:

Because the original recording wasn't skipped properly the conflict remained but the conflict email incorrectly shows the ITV+1 episode as conflicting rather than the ITV episode
Code:
Conflicts have been detected in your recording schedule on the HDR-Fox T2 (Humax - dc:d3:21:57:a5:f3)
The following events conflict:

1 BBC ONE South   Sat 22/10/16 22:00 - 22:20: BBC News
      The latest national and international news from the BBC. Also in HD. [
4 Channel 4       Sat 22/10/16 22:00 - 23:30: F1 US GP Qualifying Highlights
      Steve Jones presents highlights of qualifying for the United States Gr
33 ITV +1          Sat 22/10/16 22:50 - 23:55: New: The Jonathan Ross Show
      Chat show hosted by Jonathan Ross. Jonathan's guests this week are mus

Unique Identifier 574031595
 
Last edited:
Following my post yesterday I again tried to skip Jonathan Ross at 21:50 but it remains on the schedule
Code:
18/10/2016 10:24:22 - System booted (Remote control handset).
18/10/2016 12:21:32 - Scheduled New: The Jonathan Ross Show @ 1477169400
Tue Oct 18 12:21:33 2016: rsvsync starting.
Tue Oct 18 12:21:33 2016: Real-time mode.
Tue Oct 18 12:21:33 2016: Nugget is available.
Tue Oct 18 12:21:33 2016: Schedule saved.
Tue Oct 18 12:21:33 2016: Opening /var/lib/humaxtv/rsvp.db
[nugget]: Tue Oct 18 12:21:33 2016: Persistent log starting, v0.96
[nugget]: Tue Oct 18 12:21:33 2016: schedule save starting.
[nugget]: Tue Oct 18 12:21:33 2016: schedule save complete.
[nugget]: Tue Oct 18 12:21:33 2016: Re-loading schedule database (39).
[nugget]: Tue Oct 18 12:21:33 2016: Closing database.
Tue Oct 18 12:21:33 2016: Found slot 39 for 3/262242/0/29639
Tue Oct 18 12:21:33 2016: Setting skip on slot 39 (-1)
Tue Oct 18 12:21:33 2016: Slots: +39
Tue Oct 18 12:21:33 2016: Final schedule entries: 39
Tue Oct 18 12:21:33 2016: Loading schedule information to HumaxTV binary.
[nugget]: Tue Oct 18 12:21:33 2016:   CRID: 2/[WWW.ITV.COM/225464158]
[nugget]: Tue Oct 18 12:21:33 2016:   Recorded: 1WWW.ITV.COM/229424142|1WWW.ITV.COM/229424142|1WWW.ITV.COM/228623154|
[nugget]: Tue Oct 18 12:21:33 2016:   Last Event: 25617
[nugget]: Tue Oct 18 12:21:33 2016:   Events:
[nugget]: Tue Oct 18 12:21:33 2016:  Wake 1477168500 - Sat Oct 22 21:35:00 2016
[nugget]: Tue Oct 18 12:21:33 2016: Ready 1477168470 - Sat Oct 22 21:34:30 2016
[nugget]: Tue Oct 18 12:21:33 2016: Adding timer(1477168470)
[nugget]: Tue Oct 18 12:21:33 2016:     - Sat Oct 22 21:34:30 2016
[nugget]: Tue Oct 18 12:21:33 2016: schedule reload complete (39).
[nugget]: Tue Oct 18 12:21:33 2016: slots(39, +39)
[nugget]: Tue Oct 18 12:21:33 2016: Processing slot +39 (39)
[nugget]: Tue Oct 18 12:21:33 2016:   New: The Jonathan Ross Show
[nugget]: Tue Oct 18 12:21:33 2016:    @ 1477169400 (Sat Oct 22 21:50:00 2016)
Code:
     39         3
ITV     New: The Jonathan Ross Show
(Folder: The Jonathan Ross Show)     Sat 22 Oct 2016 21:50     01:05        
    37         4
Channel 4     F1 US GP Qualifying Highlights     Sat 22 Oct 2016 22:00
Sun 23 Oct 2016 23:00     01:30
02:00        
    18         33
ITV +1     New: The Jonathan Ross Show     Sat 22 Oct 2016 22:50     01:05
But when viewing the details popup I see that it has no Events listed
Code:
Event 39     
Channel     - 3 - ITV
Event Name     New: The Jonathan Ross Show (Folder: The Jonathan Ross Show)
Start     Sat 22 Oct 2016 21:50 BST
Duration     01:05:00
Series CRID    WWW.ITV.COM/225464158
Events   
Accepted
So I wonder if it is being treated as series (like the BBC news) that does not assign individual episode CRIDs and that when the following Saturdays data becomes available in the EPG the problem will resolve itself
 
Could people please post the output of the following command?
Code:
humax# nugget dump 0xa8bfd4
 
Everybody?

On three HDRs:
00a8bfd4: 08 19 58 00 ..X.

On the HD:
00a8bfd4: 00 00 00 00 ....
 
HDRT2# nugget dump 0xa8bfd4
00a8bfd4: 08 19 58 00 ..X.
HDRT2#

Web interface version: 1.3.2-1
Custom firmware version: 3.10 (build 2734)
Humax Version: 1.03.12 (kernel HDR_CFW_3.10)
Loader Version: a7.31
System ID: 80bc.7e00
Serial Number: 63 7105223 00476
Last Boot Reason: Remote control handset
 
Following my post yesterday I again tried to skip Jonathan Ross at 21:50 but it remains on the schedule
But when viewing the details popup I see that it has no Events listed
So I wonder if it is being treated as series (like the BBC news) that does not assign individual episode CRIDs and that when the following Saturdays data becomes available in the EPG the problem will resolve itself
Now that next Saturdays EPG data is available the conflict has disappeared from the schedule and the 21:50 ITV showing of J.Ross is correctly showing as red-skipped in the RS EPG.

I wonder if there is anything that can be done when skipping recordings leaves the Events list empty - perhaps a Dummy event with a past time stamp.
 
Status
Not open for further replies.
Back
Top