| Author |
Message |
|
bigbro
|
 Posted: Sun Apr 22, 2007 11:09 am |
|
|
Joined: Sat Dec 24, 2005 11:33 am
Posts: 400
Location:
Kitsap Peninsula, Wa., United States
|
I say minor because I cannot find any errors or things that are not working.
I am wondering if there is a log somewhere I am missing that might help me track this message down.
Every early am when Mythfilldatabase runs it inserts a message in the Log entries that says "Failed to fetch some Program info. No Other details."
I see this message on the Frontend at >
Information Center > System Status > Log Entries > then I press 4.
I am not sure exactly what level of error these are, but I have noticed that the Log Entries seems to list errors from Most Important "1" to Least Important "8"
The interesting thing, these Log Entries started around 11 April which is right about the same time I was looking around MySQL tables on this issue
http://mysettopbox.tv/phpBB2/viewtopic. ... 7499#87499
Any thoughts on where I can look to figure this out or even find out what it means?
TIA.
Andy
Last edited by bigbro on Tue Apr 24, 2007 6:36 pm, edited 1 time in total.
|
|
| Top |
|
 |
|
bigbro
|
Posted: Mon Apr 23, 2007 1:47 pm |
|
|
Joined: Sat Dec 24, 2005 11:33 am
Posts: 400
Location:
Kitsap Peninsula, Wa., United States
|
I did some digging around and found this in my mythbackend.log when mythfilldatabase runs.
Code: 2007-04-23 03:00:15.801 Running mythfilldatabase
2007-04-23 03:00:24.538 Reschedule requested for id 0.
2007-04-23 03:00:27.249 Scheduled 477 items in 2.7 = 0.57 match + 2.13 place
2007-04-23 03:00:29.814 scheduler: Scheduled items: Scheduled 477 items in 2.7 = 0.57 match + 2.13 place
2007-04-23 03:00:35.421 Reschedule requested for id 0.
2007-04-23 03:00:37.546 Scheduled 477 items in 2.1 = 0.08 match + 2.03 place
2007-04-23 03:00:37.575 scheduler: Scheduled items: Scheduled 477 items in 2.1 = 0.08 match + 2.03 place
2007-04-23 03:00:37.745 Using runtime prefix = /usr
2007-04-23 03:00:37.757 New DB connection, total: 1
2007-04-23 03:00:37.769 Connected to database 'mythconverg' at host: mythtv
2007-04-23 03:00:37.789 New DB connection, total: 2
2007-04-23 03:00:37.820 Connected to database 'mythconverg' at host: mythtv
2007-04-23 03:00:37.833 Connecting to backend server: 192.x.x.x:6543 (try 1 of 5)
2007-04-23 03:00:37.847 Using protocol version 26
2007-04-23 03:00:37.861 MainServer::HandleAnnounce Monitor
2007-04-23 03:00:37.875 adding: mythtv as a client (events: 0)
2007-04-23 03:00:37.924 MainServer::HandleAnnounce Monitor
2007-04-23 03:00:37.927 adding: mythtv as a client (events: 1)
2007-04-23 03:05:16.026 mythbackend: Running housekeeping thread
2007-04-23 03:05:16.031 mythfilldatabase still running, skipping checks.
2007-04-23 03:10:16.026 mythfilldatabase still running, skipping checks.
--03:11:34-- http://datadirect.webservices.zap2it.com/tvlistings/xtvdService
=> `/tmp/mythposttVWvmj'
Resolving datadirect.webservices.zap2it.com... 206.18.98.160
Connecting to datadirect.webservices.zap2it.com|206.18.98.160|:80... connected.
HTTP request sent, awaiting response... 401 Unauthorized
Reusing existing connection to datadirect.webservices.zap2it.com:80.
HTTP request sent, awaiting response... 401 Unauthorized
Authorization failed.
2007-04-23 03:11:36.402 MainServer::HandleAnnounce Monitor
2007-04-23 03:11:36.408 adding: mythtv as a client (events: 0)
2007-04-23 03:11:36.420 Reschedule requested for id -1.
2007-04-23 03:11:36.432 Unknown socket closing
2007-04-23 03:11:36.823 Invalid search key in recordid 228
2007-04-23 03:11:39.852 Received a remote 'Clear Cache' request
2007-04-23 03:11:41.826 Scheduled 512 items in 5.4 = 3.30 match + 2.10 place
That is all I find in here relating to Mythfilldatabase.
This error seems suspicious to me.
2007-04-23 03:11:36.823 Invalid search key in recordid 228
I don't see anything in my syslog at this point.
TIA
_________________
KnoppMyth R5.5
MBE: AMD64 3400+, K8N Neo4 Plat., 1Gig, Asus Extreme N6200, 320G pata, 2x320Gsata, 750Gsata, PVR-150 Retail w/Blaster
SBE: AMD XP2000, GA 7ZMMH, 1 Gig, XFX 256M 6200, 20 Gig, PVR-150 Retail w/Blaster
SBE: AMD Dur1800, 512M, PVR500
|
|
| Top |
|
|
|
slowtolearn
|
Posted: Mon Apr 23, 2007 2:20 pm |
|
|
Joined: Wed Nov 16, 2005 8:55 pm
Posts: 1381
Location:
Farmington, MI USA
|
bigbro wrote: Code: ...Connecting to datadirect.webservices.zap2it.com|206.18.98.160|:80... connected.
HTTP request sent, awaiting response... 401 Unauthorized
Reusing existing connection to datadirect.webservices.zap2it.com:80.
HTTP request sent, awaiting response... 401 Unauthorized
Authorization failed. ... Perhaps your Zap2It subscription has expired?
|
|
| Top |
|
|
|
bigbro
|
Posted: Mon Apr 23, 2007 3:28 pm |
|
|
Joined: Sat Dec 24, 2005 11:33 am
Posts: 400
Location:
Kitsap Peninsula, Wa., United States
|
|
I am still getting regular updates and when I look in Information Center > System Status> Listings Status, it says Guide data until 5-06-07 data and my Subscription is good until 7/14/07
Is there a way to Log the Mythfilldatabase messages?
|
|
| Top |
|
|
|
slowtolearn
|
Posted: Mon Apr 23, 2007 5:11 pm |
|
|
Joined: Wed Nov 16, 2005 8:55 pm
Posts: 1381
Location:
Farmington, MI USA
|
bigbro wrote: I am still getting regular updates and when I look in Information Center > System Status> Listings Status, it says Guide data until 5-06-07 data and my Subscription is good until 7/14/07
Is there a way to Log the Mythfilldatabase messages? Can you login to labs.zap2it.com using the same username/password you have in mythtv-setup? AFAIK, mythfilldatabase messages appear in /var/log/mythtv/mythbackend.log. Here's a snippet from mine, notice I get the "401 Unauthorized" at first but the next try works:
Code: 2007-04-23 12:53:42.053 Running mythfilldatabase
--12:53:55-- http://datadirect.webservices.zap2it.com/tvlistings/xtvdService
=> `-'
Resolving datadirect.webservices.zap2it.com... 206.18.98.160
Connecting to datadirect.webservices.zap2it.com|206.18.98.160|:80... connected.
HTTP request sent, awaiting response... 401 Unauthorized
Reusing existing connection to datadirect.webservices.zap2it.com:80.
HTTP request sent, awaiting response... 200 OK
Length: unspecified [text/xml]
0K .......... .......... .......... .......... .......... 31.11 KB/s
50K .......... .......... .......... .......... .......... 429.98 KB/s
100K .......... .......... .......... .......... .......... 141.80 KB/s
150K .......... .......... .......... .......... 200.98 KB/s
12:53:57 (83.57 KB/s) - `-' saved [194795] This is on R5D1 with .20 applied. Haven't changed anything regarding my Zap2It account in ages, and I don't see any errors in Information Center > System Status > Log Entries
|
|
| Top |
|
|
|
bigbro
|
Posted: Mon Apr 23, 2007 6:45 pm |
|
|
Joined: Sat Dec 24, 2005 11:33 am
Posts: 400
Location:
Kitsap Peninsula, Wa., United States
|
I can and just did log into zap2it.
I might add this system has been running for a little over a year now, and has grwon to 3 boxes.
I did just run mythfilldatabase from the command line.
For two sources it looks similar to yours just different for the actual version I think.
Code: --17:32:20-- http://datadirect.webservices.zap2it.com/tvlistings/xtvdService
=> `-'
Resolving datadirect.webservices.zap2it.com... 206.18.98.160
Connecting to datadirect.webservices.zap2it.com|206.18.98.160|:80... connected.
HTTP request sent, awaiting response... 401 Unauthorized
Reusing existing connection to datadirect.webservices.zap2it.com:80.
HTTP request sent, awaiting response... 200 OK
Length: unspecified [text/xml]
[ <=> ] 314,027 78.03K/s
17:32:29 (38.70 KB/s) - `-' saved [314027]
2007-04-23 17:32:29.865 DataDirect: Your subscription expires on 07/14/07 00:18:01
I think I know what is causing the problem but I will have to wait until the scheduled programming is done before I can make a change.
I think I have a bogus source as far as Zap2it. It thinks it is in zap2it but it is not.
This is when I see it moving to Source 3.
[code]2007-04-23 17:32:37.535 Updating source #3 (Video) with grabber datadirect
2007-04-23 17:32:37.535 Checking day @ offset 0, date: Mon Apr 23 2007
2007-04-23 17:32:37.537 Data refresh needed because no data exists for day @ offset 0 from 6PM - midnight.
2007-04-23 17:32:37.537 Refreshing data for Mon Apr 23 2007
2007-04-23 17:32:37.537 Retrieving datadirect data.
2007-04-23 17:32:37.537 Grabbing data for Mon Apr 23 2007 offset 0
2007-04-23 17:32:37.537 From Mon Apr 23 07:00:00 2007 to Tue Apr 24 07:00:00 2007 (UTC)
--17:32:37-- http://datadirect.webservices.zap2it.com/tvlistings/xtvdService
=> `-'
Resolving datadirect.webservices.zap2it.com... 206.18.98.160
Connecting to datadirect.webservices.zap2it.com|206.18.98.160|:80... connected.
HTTP request sent, awaiting response... 401 Unauthorized
Reusing existing connection to datadirect.webservices.zap2it.com:80.
HTTP request sent, awaiting response... 401 Unauthorized
Authorization failed.
2007-04-23 17:32:38.710 Grab complete. Actual data from Sun May 6 07:00:00 2007 to Mon May 7 07:00:00 2007 (UTC)
[/code]
So once I get a chance I will check that issue and see if it was me the broke this. That would make sense as around 4/11 when this started is also when I set that up to allow recording of video tapes. But it also was when I was setting up the second grab in an attempt to merge to EP guides together.
Thanks
|
|
| Top |
|
|
|
bigbro
|
Posted: Tue Apr 24, 2007 6:40 pm |
|
|
Joined: Sat Dec 24, 2005 11:33 am
Posts: 400
Location:
Kitsap Peninsula, Wa., United States
|
|
This is now Solved!
It was my own fault.
The clue came when I ran mythfilldatabase from a console and watched that output.
About halfway down the output I posted above it shows:
2007-04-23 17:32:37.535 Updating source #3 (Video) with grabber datadirect
This is a bad source.
I went to mythtv-setup and found that in my Video Sources removed it and now everyone is happy.
Thanks for the help and getting me pointed in the right direction.
|
|
| Top |
|
|
View unanswered posts
View active topics
KnoppMyth 
Print view
Previous topic Next topic 
LinHES Home
Board index
FAQ
Search
Login 
Register