[mythtv] Master Backend + Slave Backend Problem?

Rich yahoo.rich at bladeracing.com
Fri Jan 7 16:39:43 EST 2005


When scheduling a recording back to back in time on the same channel the
Master will successfully record the first show and flag for commercials.

The next showing records 105MB then stops recording.  Until I completely
kill the mythbackend process on the Master server no more recordings will
take place.

I am in the process of building the lates CVS as of today.  Perhaps this
has been already noted?

Please let me know if there is anything I may provide that would help
identify what the problem is.

Configuration:
Master Backend: host (r1.bladeracing.com)
  FC3 SMP - 2.6.9-1.724 - Built from source
  2 PVR-350 ivtv-0.2.0-rc3 driver
  MythTV CVS bld 12-18-2004
  Video storage path /hdb1/video
Slave Backend: host (gsxr.bladeracing.com)
  FC3 x86_64 - 2.6.9-1.724 - Built from source
  1 PVR-350 ivtv-0.2.0-rc3 driver
  MythTV CVS bld 01-01-2005
  Video storage path /sda3/video

Here is what I see from the Master server (r1) mythbackend.log file.

2005-01-07 09:15:55.676 Sort by time...
2005-01-07 09:15:55.693 PruneRedundants...
--- print list start ---
Title - Subtitle                    Chan ChID Day Start  End   S C I  T N Pri
Star Trek: The Next Generation - "N   44 1045  07 11:00-12:00  1 1 1  A 1   4
Star Trek: The Next Generation - "I   44 1045  07 12:00-13:00  1 1 1  A 1   4
snip...
2005-01-07 10:59:56.404 Started recording "Star Trek: The Next Generation"
on channel: 1045 on cardid: 1, sourceid 1
2005-01-07 10:59:56.441 TuneTo(2) curList[i].freq(55250)
2005-01-07 10:59:56.496 TuneToFrequency(884)
2005-01-07 10:59:56.565 scheduler: Last message repeated 7 times
2005-01-07 10:59:56.592 scheduler: Schedule Change
2005-01-07 10:59:57.040 TuneTo(44) curList[i].freq(343250)
2005-01-07 10:59:57.053 TuneToFrequency(5492)
2005-01-07 10:59:57.568 Changing from None to RecordingOnly
2005-01-07 10:59:57.660 Using profile 'Default' to record
2005-01-07 10:59:57.675 Checking signal on device input: 4
2005-01-07 12:00:00.002 switching to overrecord for 1 more seconds
2005-01-07 12:00:01.003 Finished recording Star Trek: The Next Generation
on channel: 1045
2005-01-07 12:00:01.088 scheduler: Finished recording
2005-01-07 12:00:01.179 Changing from RecordingOnly to None
2005-01-07 12:00:01.185 backend still changing state, waiting..
2005-01-07 12:00:02.025 changing state finished, starting now
2005-01-07 12:00:02.031 Started recording "Star Trek: The Next Generation"
on channel: 1045 on cardid: 1, sourceid 1
2005-01-07 12:00:02.097 scheduler: Schedule Change
2005-01-07 12:00:02.159 TuneTo(2) curList[i].freq(55250)
2005-01-07 12:00:02.173 TuneToFrequency(884)
2005-01-07 12:00:02.692 TuneTo(44) curList[i].freq(343250)
2005-01-07 12:00:02.705 TuneToFrequency(5492)
2005-01-07 12:00:03.289 Changing from None to RecordingOnly
2005-01-07 12:00:03.380 Using profile 'Default' to record
2005-01-07 12:00:03.393 Checking signal on device input: 4
2005-01-07 12:00:04.176 Found changes in the todo list.
2005-01-07 12:00:04.179 15      QUERY_FREESPACE
2005-01-07 12:00:04.190 PruneOldRecords...
2005-01-07 12:00:04.194 AddNewRecords...
Query 0: /record.search = 0 AND program.title = record.title
2005-01-07 12:00:04.202  |-- Start DB Query 0...
2005-01-07 12:00:05.524  |-- 567 results in 1.31919 sec. Processing...
2005-01-07 12:00:05.651  +-- Cleanup...
2005-01-07 12:00:05.663 Sort by time...
2005-01-07 12:00:05.665 PruneOverlaps...
2005-01-07 12:00:05.668 Sort by priority...
2005-01-07 12:00:05.670 BuildListMaps...
2005-01-07 12:00:05.673 SchedNewRecords...
2005-01-07 12:00:05.676 Scheduling:
snip.. An ordered list by Title name of scheduled programs is printed
2005-01-07 12:00:07.129 ClearListMaps...
2005-01-07 12:00:07.153 Sort by time...
2005-01-07 12:00:07.170 PruneRedundants...
--- print list start ---
snip.. new list printed by start time
---  print list end  ---
2005-01-07 12:00:09.492 Scheduled 175 items in 3.0 seconds.
2005-01-07 12:00:09.502 scheduler: Scheduled items
2005-01-07 12:00:17.750 MainServer::HandleAnnounce Playback
2005-01-07 12:00:17.753 adding: gsxr.bladeracing.com as a client (events: 0)
2005-01-07 12:00:17.756 2       OK
2005-01-07 12:00:17.766 MainServer::HandleAnnounce FileTransfer
2005-01-07 12:00:17.768 adding: gsxr.bladeracing.com as a remote file
transfer
2005-01-07 12:00:17.829 Local file path:
/hdb1/video/1045_20050107110000_20050107120000.nuv
2005-01-07 12:00:17.902 28      OK[]:[]22[]:[]1[]:[]93697984
2005-01-07 12:00:18.268 MainServer::HandleAnnounce Playback
2005-01-07 12:00:18.273 adding: gsxr.bladeracing.com as a client (events: 0)
2005-01-07 12:00:18.275 2       OK
2005-01-07 12:00:18.282 MainServer::HandleAnnounce FileTransfer
2005-01-07 12:00:18.285 adding: gsxr.bladeracing.com as a remote file
transfer
2005-01-07 12:00:18.289 Local file path:
/hdb1/video/1045_20050107110000_20050107120000.nuv
2005-01-07 12:00:18.307 28      OK[]:[]25[]:[]1[]:[]93697984
2005-01-07 12:00:18.363 6       128000
2005-01-07 12:00:18.373 7       0[]:[]0
2005-01-07 12:00:18.386 6       128000
2005-01-07 12:00:18.408 6       128000
2005-01-07 12:00:18.427 6       128000
2005-01-07 12:00:18.449 6       128000
2005-01-07 12:00:18.467 6       128000
2005-01-07 12:00:18.486 6       128000
2005-01-07 12:00:18.515 6       128000
2005-01-07 12:00:18.534 6       128000
2005-01-07 12:00:18.553 6       128000
2005-01-07 12:00:18.572 6       128000
2005-01-07 12:00:18.592 6       128000
2005-01-07 12:00:18.611 6       128000
2005-01-07 12:00:18.632 6       128000
2005-01-07 12:00:18.675 6       256000
2005-01-07 12:00:18.708 6       256000
2005-01-07 12:00:18.746 6       256000
2005-01-07 12:00:18.774 6       128000
2005-01-07 12:00:18.814 6       256000
snip.. This above line goes on for 30 minutes
2005-01-07 12:33:57.182 6       256000
2005-01-07 12:33:57.312 6       256000
2005-01-07 12:33:57.468 5       57280
2005-01-07 12:33:58.615 1       0
2005-01-07 12:33:58.685 14      1[]:[]93698048
2005-01-07 12:33:59.633 1       0
2005-01-07 12:34:00.883 2       ok
2005-01-07 12:34:01.104 2       ok
2005-01-07 13:00:00.002 switching to overrecord for 1 more seconds
2005-01-07 13:00:01.003 Finished recording Star Trek: The Next Generation
on channel: 1045
2005-01-07 13:00:01.021 scheduler: Finished recording
2005-01-07 13:00:01.101 Changing from RecordingOnly to None
2005-01-07 13:00:01.940 Found changes in the todo list.
2005-01-07 13:00:01.945 15      QUERY_FREESPACE
2005-01-07 13:00:01.957 PruneOldRecords...
2005-01-07 13:00:01.961 AddNewRecords...
Query 0: /record.search = 0 AND program.title = record.title
2005-01-07 13:00:01.969  |-- Start DB Query 0...
2005-01-07 13:00:03.431  |-- 567 results in 1.46103 sec. Processing...
2005-01-07 13:00:03.551  +-- Cleanup...
2005-01-07 13:00:03.561 Sort by time...
2005-01-07 13:00:03.564 PruneOverlaps...
2005-01-07 13:00:03.566 Sort by priority...
2005-01-07 13:00:03.569 BuildListMaps...
2005-01-07 13:00:03.571 SchedNewRecords...
2005-01-07 13:00:03.574 Scheduling:

Looking at the recorded file sizes.  The first is the expected ~4.1GB. 
The second recording is 105MB.

One concern I have is line dated 2005-01-07 12:00:17.753 of the log file. 
The "Slave" backend server is added as a client.  Yet the following line
dated 2005-01-07 12:00:17.829 indicated a file path this of the "Master"
backend server.  Why is that?

Thanks for your time in looking at this,
Rich




More information about the mythtv-dev mailing list