Ticket #754: mythfrontend.debug.svn8482

File mythfrontend.debug.svn8482, 8.4 KB (added by jasonmollman@…, 18 years ago)

svn 8482 frontend log

Line 
12006-01-03 11:30:02.087 LiveTVChain(live-pvr.home.net-2006-01-03T11:27:15): Entry@1: '1252_20060103113000'
22006-01-03 11:30:02.264 RingBuf(/video/recordings/1252_20060103112717.mpg): OpenFile(myth://127.0.0.1:6543/1252_20060103113000.mpg, 10)
32006-01-03 11:30:02.293 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
42006-01-03 11:30:02.295 Position map filled from DB to: 1
52006-01-03 11:30:02.295 SyncPositionMap watchingrecording, from DB: 1 entries
62006-01-03 11:30:02.336 PosMapFromEnc: Warning, tried to fetch PositionMap from Encoder but encoder returned framesWritten == 0
72006-01-03 11:30:02.337 SyncPositionMap watchingrecording no entries from encoder, try DB
82006-01-03 11:30:02.339 Position map filled from DB to: 1
92006-01-03 11:30:02.340 SyncPositionMap watchingrecording total: 1 entries
102006-01-03 11:30:02.340 SyncPositionMap, new totframes: 15, new length: 0, posMap size: 1
112006-01-03 11:32:20.202 TV: Attempting to change from WatchingLiveTV to None
122006-01-03 11:32:20.224 TV: StopStuff() -- begin
132006-01-03 11:32:20.224 TV: StopStuff(): stopping ring buffer[s]
142006-01-03 11:32:20.252 TV: StopStuff(): stopping player[s] (1/2)
152006-01-03 11:32:20.252 TV: StopStuff(): stopping recorder[s]
162006-01-03 11:32:20.253 NVP: Exited decoder loop.
172006-01-03 11:32:20.314 IVD: Close() -- begin
182006-01-03 11:32:20.314 IVD: Stop(1) -- begin
192006-01-03 11:32:20.335 IVD: Stop(1) -- end
202006-01-03 11:32:20.336 IVD: Close() -- end
212006-01-03 11:32:20.625 TV: StopStuff(): stopping player[s] (2/2)
222006-01-03 11:32:20.794 TV: StopStuff() -- end
232006-01-03 11:32:20.794 TV: Changing from WatchingLiveTV to None
242006-01-03 11:32:20.798 TV: Attempting to change from None to None
25
26[root@pvr ~]# cat /home/myth/mythfrontend.debug
272006-01-03 11:27:04.392 Using runtime prefix = /usr/local
282006-01-03 11:27:04.455 New DB connection, total: 1
292006-01-03 11:27:04.460 Total desktop dim: 720x480, with 1 screen[s].
302006-01-03 11:27:04.464 Using screen 0, 720x480 at 0,0
312006-01-03 11:27:04.469 Enabling Settings Cache.
322006-01-03 11:27:04.470 Clearing Settings Cache.
332006-01-03 11:27:04.471 user: 500 effective user: 0 before privileged thread
342006-01-03 11:27:04.472 user: 500 effective user: 0 after privileged thread
352006-01-03 11:27:04.474 Current Schema Version: 1121
362006-01-03 11:27:04.476 mythfrontend version: 0.19.20051208-1 www.mythtv.org
372006-01-03 11:27:04.477 Enabled verbose msgs:  important general playback
382006-01-03 11:27:04.482 user: 500 effective user: 500 run_priv_thread
392006-01-03 11:27:05.144 max_width: 720 max_height: 480
402006-01-03 11:27:05.146 lang0: eng
412006-01-03 11:27:05.147 lang: eng
422006-01-03 11:27:05.157 lang1: aar
432006-01-03 11:27:05.158 lang: aar
442006-01-03 11:27:05.217 Total desktop dim: 720x480, with 1 screen[s].
452006-01-03 11:27:05.220 Using screen 0, 720x480 at 0,0
462006-01-03 11:27:05.232 Switching to square mode (Titivillus)
472006-01-03 11:27:06.035 Joystick disabled.
482006-01-03 11:27:06.072 New DB connection, total: 2
492006-01-03 11:27:06.154 Clearing Settings Cache for 'Language'.
502006-01-03 11:27:06.155 Clearing Settings Cache.
512006-01-03 11:27:06.275 Registering Internal as a media playback plugin.
522006-01-03 11:27:06.354 Registering MythDVD DVD Media Handler as a media handler
532006-01-03 11:27:07.316 Registering MythMusic Media Handler as a media handler
54SIP listening on IP Address :5060 NAT address
55SIP: Cannot register; proxy, username or password not set
56Destroying SipFsm object
572006-01-03 11:27:08.036 Starting media monitor.
582006-01-03 11:27:15.176 Connecting to backend server: 127.0.0.1:6543 (try 1 of 5)
592006-01-03 11:27:15.256 Using protocol version 22
602006-01-03 11:27:15.443 TV: Attempting to change from None to WatchingLiveTV
612006-01-03 11:27:15.449 Using protocol version 22
622006-01-03 11:27:17.449 LiveTVChain(live-pvr.home.net-2006-01-03T11:27:15): ReloadAll(): Added new recording
632006-01-03 11:27:17.482 RingBuf(myth://127.0.0.1:6543/1252_20060103112717.mpg): OpenFile(myth://127.0.0.1:6543/1252_20060103112717.mpg, 6)
642006-01-03 11:27:17.485 Invalid file handle when opening /video/recordings/1252_20060103112717.mpg.  6 retries remaining.
652006-01-03 11:27:17.987 Invalid file handle when opening /video/recordings/1252_20060103112717.mpg.  5 retries remaining.
662006-01-03 11:27:18.506 TV: StartRecorder(): took 17 ms to start recorder.
672006-01-03 11:27:18.508 TV: GetLockTimeout(1): Set lock timeout to 4294967295 ms
682006-01-03 11:27:18.872 detectInterlace(Ignore Scan, Detect Scan, 29.97, 480) ->Ignore Scan
692006-01-03 11:27:18.873 Interlaced: Ignore Scan  video_height: 480  fps: 29.97
702006-01-03 11:27:18.898 RingBuf:CalcReadAheadThresh(8000 KB) -> threshhold(250 KB) readblocksize(125 KB)
712006-01-03 11:27:18.899 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
722006-01-03 11:27:18.901 Position map filled from DB to: 1
732006-01-03 11:27:18.902 SyncPositionMap watchingrecording, from DB: 1 entries
742006-01-03 11:27:18.907 PosMapFromEnc: Warning, tried to fetch PositionMap from Encoder but encoder returned framesWritten == 0
752006-01-03 11:27:18.908 SyncPositionMap watchingrecording no entries from encoder, try DB
762006-01-03 11:27:18.909 Position map filled from DB to: 1
772006-01-03 11:27:18.909 SyncPositionMap watchingrecording total: 1 entries
782006-01-03 11:27:18.909 SyncPositionMap, new totframes: 15, new length: 0, posMap size: 1
792006-01-03 11:27:18.909 Partial position map found
802006-01-03 11:27:18.914 NVP: Disabling Audio, params(16,2,0)
812006-01-03 11:27:18.931 IVD: Init() -- begin
822006-01-03 11:27:18.938 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
832006-01-03 11:27:18.943 Snapping height to avoid scaling: disphoff 480, dispyoff: 0
842006-01-03 11:27:18.944 Snapping width to avoid scaling: dispwoff 720, dispxoff: 0
852006-01-03 11:27:18.944 Image size. dispxoff 0, dispyoff: 0, dispwoff: 720, disphoff: 480
862006-01-03 11:27:18.944 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480
872006-01-03 11:27:18.945 Snapping height to avoid scaling: disphoff 480, dispyoff: 0
882006-01-03 11:27:18.945 Snapping width to avoid scaling: dispwoff 720, dispxoff: 0
892006-01-03 11:27:18.945 Image size. dispxoff 0, dispyoff: 0, dispwoff: 720, disphoff: 480
902006-01-03 11:27:18.945 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480
912006-01-03 11:27:18.945 IVD: Open() -- begin
922006-01-03 11:27:18.945 IVD: Open() -- end
932006-01-03 11:27:19.035 Using the PVR-350 decoder/TV-out
942006-01-03 11:27:19.036 IVD: Init() -- end
952006-01-03 11:27:19.801 NVP: ClearAfterSeek()
962006-01-03 11:27:19.802 TV: StartPlayer(): took 1118 ms to start player.
972006-01-03 11:27:19.818 TV: Changing from None to WatchingLiveTV
982006-01-03 11:27:19.830 The realtime priority setting is not enabled.
992006-01-03 11:27:20.192 keyframedist changed to 15
1002006-01-03 11:30:00.981 LiveTVChain(live-pvr.home.net-2006-01-03T11:27:15): ReloadAll(): Added new recording
1012006-01-03 11:30:02.022 LiveTVChain(live-pvr.home.net-2006-01-03T11:27:15): SwitchTo(1)
1022006-01-03 11:30:02.087 LiveTVChain(live-pvr.home.net-2006-01-03T11:27:15): Entry@1: '1252_20060103113000'
1032006-01-03 11:30:02.264 RingBuf(/video/recordings/1252_20060103112717.mpg): OpenFile(myth://127.0.0.1:6543/1252_20060103113000.mpg, 10)
1042006-01-03 11:30:02.293 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
1052006-01-03 11:30:02.295 Position map filled from DB to: 1
1062006-01-03 11:30:02.295 SyncPositionMap watchingrecording, from DB: 1 entries
1072006-01-03 11:30:02.336 PosMapFromEnc: Warning, tried to fetch PositionMap from Encoder but encoder returned framesWritten == 0
1082006-01-03 11:30:02.337 SyncPositionMap watchingrecording no entries from encoder, try DB
1092006-01-03 11:30:02.339 Position map filled from DB to: 1
1102006-01-03 11:30:02.340 SyncPositionMap watchingrecording total: 1 entries
1112006-01-03 11:30:02.340 SyncPositionMap, new totframes: 15, new length: 0, posMap size: 1
1122006-01-03 11:32:20.202 TV: Attempting to change from WatchingLiveTV to None
1132006-01-03 11:32:20.224 TV: StopStuff() -- begin
1142006-01-03 11:32:20.224 TV: StopStuff(): stopping ring buffer[s]
1152006-01-03 11:32:20.252 TV: StopStuff(): stopping player[s] (1/2)
1162006-01-03 11:32:20.252 TV: StopStuff(): stopping recorder[s]
1172006-01-03 11:32:20.253 NVP: Exited decoder loop.
1182006-01-03 11:32:20.314 IVD: Close() -- begin
1192006-01-03 11:32:20.314 IVD: Stop(1) -- begin
1202006-01-03 11:32:20.335 IVD: Stop(1) -- end
1212006-01-03 11:32:20.336 IVD: Close() -- end
1222006-01-03 11:32:20.625 TV: StopStuff(): stopping player[s] (2/2)
1232006-01-03 11:32:20.794 TV: StopStuff() -- end
1242006-01-03 11:32:20.794 TV: Changing from WatchingLiveTV to None
1252006-01-03 11:32:20.798 TV: Attempting to change from None to None