[mythtv-users] Scheduling bug?

Jeppe N. Madsen jeppe at ingolfs.dk
Tue Jan 2 21:59:42 UTC 2007


Hi,

I'm seeing something strange with recordings and I think it may be a
bug.....

Here's the setup: yesterday I wanted to record two movies on the same
channel:

2100 - 2300 King Arthur
2300 - 0050 A man apart

After having marked these for recording, I accidentally left Live TV
running on the same channel. Today I found out only King Arthur was
actually recorded. Looking at the logs (excerpt below), it seems as if
"A man apart" was auto expired even if plenty of other recordings are
available for expiration. Is it the live TV that messes things up? It
looks as if short durations of live tv is recorded and expired. Any
other explanations?

/usr/local/mythtv/bin/mythbackend --version
Library API version: 0.20.20061004-2
Source code version: 11493

Output from mythbackend --printexpire:

MythTV AutoExpire List (programs listed in order of expiration)
House                                    2023MiB  Tue Jan 2 21:00:00 2007  [-10]
Viden om: "Teleportation nu virkelighed  1102MiB  Mon Nov 13 23:30:00 2006 [  0]
The Last Castle: "The Last Castle"       4603MiB  Fri Nov 24 21:30:00 2006 [  0]
Disney Sjov                              1102MiB  Fri Dec 1 19:00:00 2006  [  0]
Disney sjov                              2024MiB  Sat Dec 2 07:50:00 2006  [  0]
Disney Sjov                              1102MiB  Sun Dec 3 19:00:00 2006  [  0]
Love Actually                            4786MiB  Sun Dec 3 21:05:00 2006  [  0]
Disney Sjov                              1103MiB  Fri Dec 8 19:00:00 2006  [  0]
Disney Sjov                              2023MiB  Sat Dec 9 07:50:00 2006  [  0]
Disney Sjov                              1029MiB  Sun Dec 10 17:30:00 2006 [  0]
Disney Sjov                              1102MiB  Fri Dec 15 19:00:00 2006 [  0]
Disney Sjov: "Anders And som smed". "Fi  2023MiB  Sat Dec 16 07:50:00 2006 [  0]
Disney Sjov                              1103MiB  Sun Dec 17 19:00:00 2006 [  0]
Disney sjov                              1102MiB  Fri Dec 22 19:00:00 2006 [  0]
Liz - historien om Elizabeth Taylor: "T  2158MiB  Wed Dec 27 13:20:00 2006 [  0]
Liz - historien om Elizabeth Taylor: "T  2086MiB  Thu Dec 28 13:20:00 2006 [  0]
Terkel i knibe                           3312MiB  Thu Dec 28 20:00:00 2006 [  0]
Lunefulde lykke                          3312MiB  Thu Dec 28 22:10:00 2006 [  0]
Disney sjov                              2207MiB  Fri Dec 29 19:00:00 2006 [  0]
Léon                                    4232MiB  Sat Dec 30 22:15:00 2006 [  0]
King Arthur                              4418MiB  Mon Jan 1 21:00:00 2007  [  0]

rgds
Jeppe

2007-01-01 20:00:02.424 TVRec(1): Enabling Full LiveTV UI.
0: start_time: 401.184 duration: 161.842
1: start_time: 401.158 duration: 161.834
stream: start_time: 4457.316 duration: 1798.524 bitrate=5146 kb/s
2007-01-01 20:00:02.624 AFD: Opened codec 0x8282fa0, id(MPEG2VIDEO) type(Video)
2007-01-01 20:00:02.707 AFD: Opened codec 0x81cad30, id(MP2) type(Audio)
2007-01-01 20:00:02.731 TVRec(1): RingBufferChanged()
2007-01-01 20:00:02.888 Finished recording Regionalprogram: channel 2
2007-01-01 20:00:02.978 Reschedule requested for id 0.
2007-01-01 20:00:03.455 MainServer::HandleAnnounce Playback
2007-01-01 20:00:03.580 adding: frontend as a client (events: 0)
2007-01-01 20:00:03.490 Scheduled 21 items in 0.5 = 0.11 match + 0.39 place
2007-01-01 20:00:03.615 MainServer::HandleAnnounce FileTransfer
2007-01-01 20:00:03.687 adding: frontend as a remote file transfer
2007-01-01 20:00:46.398 JobQueue: Commercial Flagging Starting for Regionalprogram recorded from channel 2 at Mon Jan 1 19:30:00 2007
2007-01-01 20:00:48.287 Using runtime prefix = /usr/local/mythtv
2007-01-01 20:00:48.601 New DB connection, total: 1
2007-01-01 20:00:48.691 Connected to database 'mythconverg' at host: localhost
2007-01-01 20:00:48.739 New DB connection, total: 2
2007-01-01 20:00:48.768 Connected to database 'mythconverg' at host: localhost
2007-01-01 20:00:48.908 Connecting to backend server: 10.0.1.20:6543 (try 1 of 5)
2007-01-01 20:00:48.976 Using protocol version 31
2007-01-01 20:00:49.007 MainServer::HandleAnnounce Monitor
2007-01-01 20:00:49.039 adding: mediaserver as a client (events: 0)
2007-01-01 20:00:49.065 MainServer::HandleAnnounce Monitor
2007-01-01 20:00:49.097 adding: mediaserver as a client (events: 1)
0: start_time: 401.184 duration: 161.870
1: start_time: 401.158 duration: 161.864
stream: start_time: 4457.316 duration: 1798.844 bitrate=5146 kb/s
2007-01-01 20:00:49.297 AFD: Opened codec 0x8176a50, id(MPEG2VIDEO) type(Video)
2007-01-01 20:00:49.489 AFD: Opened codec 0x8176eb0, id(MP2) type(Audio)
2007-01-01 20:25:04.685 JobQueue: Commercial Flagging Finished, 3 break(s) found.
0: start_time: 401.184 duration: 161.870
1: start_time: 401.158 duration: 161.864
stream: start_time: 4457.316 duration: 1798.844 bitrate=5146 kb/s
2007-01-01 20:25:04.974 AFD: Opened codec 0x8280800, id(MPEG2VIDEO) type(Video)
2007-01-01 20:25:05.017 AFD: Opened codec 0x8280c60, id(MP2) type(Audio)
2007-01-01 20:59:29.802 TVRec(1): ASK_RECORDING 1 29 0
2007-01-01 21:00:03.384 Finished recording TV 2 006 "Årets seer": channel 2
2007-01-01 21:00:03.686 TVRec(1): Enabling Full LiveTV UI.
0: start_time: 563.105 duration: 324.036
1: start_time: 563.061 duration: 324.048
stream: start_time: 6256.236 duration: 3600.884 bitrate=5148 kb/s
2007-01-01 21:00:03.914 AFD: Opened codec 0x823fe60, id(MPEG2VIDEO) type(Video)
2007-01-01 21:00:04.036 AFD: Opened codec 0x82402d0, id(MP2) type(Audio)
2007-01-01 21:00:04.055 TVRec(1): RingBufferChanged()
2007-01-01 21:00:04.307 Finished recording TV 2 006 "Årets seer": channel 2
2007-01-01 21:00:04.057 Started recording: King Arthur: channel 2 on cardid 1, sourceid 1
2007-01-01 21:00:04.336 MainServer::HandleAnnounce Playback
2007-01-01 21:00:04.636 adding: frontend as a client (events: 0)
2007-01-01 21:00:04.688 MainServer::HandleAnnounce FileTransfer
2007-01-01 21:00:04.751 adding: frontend as a remote file transfer
2007-01-01 21:00:05.222 Finished recording King Arthur: channel 2
2007-01-01 21:00:05.554 Finished recording King Arthur: channel 2
0: start_time: 887.191 duration: 0.036
1: start_time: 887.145 duration: 0.050
stream: start_time: 9857.172 duration: 0.908 bitrate=4834 kb/s
2007-01-01 21:00:05.798 AFD: Opened codec 0x81d5d80, id(MPEG2VIDEO) type(Video)
2007-01-01 21:00:05.918 TVRec(1): RingBufferChanged()
2007-01-01 21:00:05.971 AFD: Opened codec 0x81d60f0, id(MP2) type(Audio)
2007-01-01 21:00:06.029 Finished recording King Arthur: channel 2
2007-01-01 21:00:08.808 MainServer::HandleAnnounce Playback
2007-01-01 21:00:08.910 adding: frontend as a client (events: 0)
2007-01-01 21:00:08.937 MainServer::HandleAnnounce FileTransfer
2007-01-01 21:00:08.968 adding: frontend as a remote file transfer
2007-01-01 21:02:33.832 Expiring King Arthur from Mon Jan 1 21:00:00 2007, 0 MBytes, forced expire (LiveTV recording)
2007-01-01 22:05:34.744 Expiring Regionale nyheder from Mon Jan 1 18:10:00 2007, 309 MBytes, free space is too low (have 2044 MBytes free , but want 2105 MBytes)
2007-01-01 22:15:35.151 Expiring Go' aften Danmark from Mon Jan 1 18:25:00 2007, 1290 MBytes, free space is too low (have 1988 MBytes free , but want 2105 MBytes)
2007-01-01 22:55:35.835 Expiring Statsministerens Nytårstale from Mon Jan 1 19:15:00 2007, 553 MBytes, free space is too low (have 1804 MBytes free , but want 2105 MBytes)
2007-01-01 22:59:29.233 TVRec(1): ASK_RECORDING 1 29 1
2007-01-01 23:00:00.622 TVRec(1): Enabling Full LiveTV UI.
2007-01-01 23:00:02.737 Finished recording King Arthur: channel 2
2007-01-01 23:00:03.164 TVRec(1): Enabling Full LiveTV UI.
0: start_time: 887.288 duration: 647.780
1: start_time: 887.251 duration: 647.769
stream: start_time: 9858.348 duration: 7197.972 bitrate=5148 kb/s
2007-01-01 23:00:03.329 AFD: Opened codec 0x81d1150, id(MPEG2VIDEO) type(Video)
2007-01-01 23:00:03.369 AFD: Opened codec 0x81d14c0, id(MP2) type(Audio)
2007-01-01 23:00:03.432 Canceled recording (Aborted): A Man Apart: channel 2 on cardid 1, sourceid 1
2007-01-01 23:00:04.373 MainServer::HandleAnnounce Playback
2007-01-01 23:00:04.491 adding: frontend as a client (events: 0)
2007-01-01 23:00:04.525 MainServer::HandleAnnounce FileTransfer
2007-01-01 23:00:04.558 adding: frontend as a remote file transfer
2007-01-01 23:00:04.555 TVRec(1): RingBufferChanged()
2007-01-01 23:00:04.897 Finished recording A Man Apart: channel 2
2007-01-01 23:00:05.014 TVRec(1): Enabling Full LiveTV UI.
2007-01-01 23:00:05.126 Finished recording King Arthur: channel 2
2007-01-01 23:00:05.128 Reschedule requested for id 0.
2007-01-01 23:00:05.439 TVRec(1): RingBufferChanged()
2007-01-01 23:00:05.836 Finished recording A Man Apart: channel 2
2007-01-01 23:00:05.877 Scheduled 20 items in 0.7 = 0.40 match + 0.34 place
0: start_time: 1535.148 duration: 0.011
1: start_time: 1535.102 duration: 0.011
stream: start_time: 17056.692 duration: 0.628 bitrate=4276 kb/s
2007-01-01 23:00:06.316 AFD: Opened codec 0x82725d0, id(MPEG2VIDEO) type(Video)
2007-01-01 23:00:06.332 Canceled recording (Aborted): A Man Apart: channel 2 on cardid 1, sourceid 1
2007-01-01 23:00:06.389 AFD: Opened codec 0x8272940, id(MP2) type(Audio)
2007-01-01 23:00:08.245 MainServer::HandleAnnounce Playback
2007-01-01 23:00:08.365 adding: frontend as a client (events: 0)
2007-01-01 23:00:08.392 MainServer::HandleAnnounce FileTransfer
2007-01-01 23:00:08.423 adding: frontend as a remote file transfer
2007-01-01 23:00:53.113 JobQueue: Commercial Flagging Starting for King Arthur recorded from channel 2 at Mon Jan 1 21:00:00 2007
2007-01-01 23:00:54.925 Using runtime prefix = /usr/local/mythtv
2007-01-01 23:00:55.275 New DB connection, total: 1
2007-01-01 23:00:55.375 Connected to database 'mythconverg' at host: localhost
2007-01-01 23:00:55.428 New DB connection, total: 2
2007-01-01 23:00:55.452 Connected to database 'mythconverg' at host: localhost
2007-01-01 23:00:55.585 Connecting to backend server: 10.0.1.20:6543 (try 1 of 5)
2007-01-01 23:00:55.637 Using protocol version 31
2007-01-01 23:00:55.669 MainServer::HandleAnnounce Monitor
2007-01-01 23:00:55.701 adding: mediaserver as a client (events: 0)
2007-01-01 23:00:55.736 MainServer::HandleAnnounce Monitor
2007-01-01 23:00:55.768 adding: mediaserver as a client (events: 1)
0: start_time: 887.288 duration: 647.809
1: start_time: 887.251 duration: 647.806
stream: start_time: 9858.348 duration: 7198.292 bitrate=5148 kb/s
2007-01-01 23:00:55.916 AFD: Opened codec 0x8178260, id(MPEG2VIDEO) type(Video)
2007-01-01 23:00:56.130 AFD: Opened codec 0x81786c0, id(MP2) type(Audio)
2007-01-01 23:02:36.020 Expiring A Man Apart from Mon Jan 1 23:00:00 2007, 0 MBytes, forced expire (LiveTV recording)
2007-01-01 23:05:36.289 Expiring TV 2 006 "Årets seer" from Mon Jan 1 20:00:00 2007, 2209 MBytes, free space is too low (have 1988 MBytes free , but want 2105 MBytes)
2007-01-02 00:05:37.393 Expiring Nyhederne from Sun Dec 31 19:00:00 2006, 697 MBytes, free space is too low (have 1988 MBytes free , but want 2105 MBytes)
2007-01-02 00:05:37.690 Reschedule requested for id 0.
2007-01-02 00:05:38.087 Scheduled 20 items in 0.4 = 0.06 match + 0.34 place
2007-01-02 00:05:38.207 Canceled recording (Aborted): A Man Apart: channel 2 on cardid 1, sourceid 1
2007-01-02 00:05:47.345 Reschedule requested for id 0.
2007-01-02 00:05:48.152 Scheduled 20 items in 0.8 = 0.72 match + 0.08 place
2007-01-02 00:05:48.337 Canceled recording (Aborted): A Man Apart: channel 2 on cardid 1, sourceid 1
2007-01-02 00:25:37.919 Expiring Nyhederne from Mon Jan 1 19:00:00 2007, 550 MBytes, free space is too low (have 1952 MBytes free , but want 2105 MBytes)
2007-01-02 00:25:38.113 Reschedule requested for id 0.
2007-01-02 00:25:38.485 Scheduled 20 items in 0.4 = 0.04 match + 0.33 place
2007-01-02 00:25:38.574 Canceled recording (Aborted): A Man Apart: channel 2 on cardid 1, sourceid 1
2007-01-02 00:25:47.505 Reschedule requested for id 0.
2007-01-02 00:25:47.954 Scheduled 20 items in 0.4 = 0.35 match + 0.09 place
2007-01-02 00:25:48.032 Canceled recording (Aborted): A Man Apart: channel 2 on cardid 1, sourceid 1
2007-01-02 00:40:50.485 JobQueue: Commercial Flagging Finished, 3 break(s) found.
0: start_time: 887.288 duration: 647.809
1: start_time: 887.251 duration: 647.806
stream: start_time: 9858.348 duration: 7198.292 bitrate=5148 kb/s
2007-01-02 00:40:50.836 AFD: Opened codec 0x81d0150, id(MPEG2VIDEO) type(Video)
2007-01-02 00:40:50.899 AFD: Opened codec 0x81d04c0, id(MP2) type(Audio)
2007-01-02 00:45:38.451 Expiring Regionalprogram from Mon Jan 1 19:30:00 2007, 1103 MBytes, free space is too low (have 1764 MBytes free , but want 2105 MBytes)
2007-01-02 00:45:38.678 Reschedule requested for id 0.
2007-01-02 00:45:39.055 Scheduled 20 items in 0.4 = 0.06 match + 0.32 place
2007-01-02 00:45:39.119 Canceled recording (Aborted): A Man Apart: channel 2 on cardid 1, sourceid 1
2007-01-02 00:45:48.599 Reschedule requested for id 0.
2007-01-02 00:45:50.214 Scheduled 20 items in 1.6 = 1.53 match + 0.08 place
2007-01-02 00:45:50.271 Canceled recording (Aborted): A Man Apart: channel 2 on cardid 1, sourceid 1
2007-01-02 00:50:00.881 Finished recording A Man Apart: channel 2
0: start_time: 1535.191 duration: 593.672
1: start_time: 1535.152 duration: 593.687
stream: start_time: 17057.244 duration: 6596.796 bitrate=5148 kb/s
2007-01-02 00:50:01.614 AFD: Opened codec 0x81d0940, id(MPEG2VIDEO) type(Video)
2007-01-02 00:50:02.034 AFD: Opened codec 0x828f090, id(MP2) type(Audio)
2007-01-02 00:50:02.760 TVRec(1): Enabling Full LiveTV UI.
2007-01-02 00:50:03.211 MainServer::HandleAnnounce Playback
2007-01-02 00:50:03.326 adding: frontend as a client (events: 0)
2007-01-02 00:50:03.231 TVRec(1): RingBufferChanged()
2007-01-02 00:50:03.363 MainServer::HandleAnnounce FileTransfer
2007-01-02 00:50:03.434 adding: frontend as a remote file transfer
2007-01-02 00:50:03.607 Finished recording A Man Apart: channel 2
2007-01-02 01:15:38.995 Expiring A Man Apart from Mon Jan 1 23:00:00 2007, 4050 MBytes, free space is too low (have 1764 MBytes free , but want 2105 MBytes)
2007-01-02 01:40:00.898 Finished recording Rød Zone "Atomubåden Typhoon": channel 2



More information about the mythtv-users mailing list