[mythtv-users] 0.25 mythfilldatabase error [SOLVED]

Sat Jun 16 12:38:48 UTC 2012

For anyone else facing this problem...it turns out that a web filter (DANSGUARDIAN) was catching a "bad word" in the http stream and blocking the data download...

Just a suggestion: perhaps a mythfilldatabase error saying something like unexpected data response

Thanks
________________________________________
From: mythtv-users-bounces at mythtv.org [mythtv-users-bounces at mythtv.org] On Behalf Of Michelle Dupuis [mdupuis at ocg.ca]
Sent: Friday, June 15, 2012 11:24 PM
To: Myth TV Users List
Subject: Re: [mythtv-users] 0.25 mythfilldatabase error

I though that perhaps I should include a full debug output (see below).  One more clue is the timer error at the end of the output...

[root at dvr ~]# mythfilldatabase --loglevel debug --max-days 1
2012-06-15 23:23:32.149291 C  mythfilldatabase version:  [v0.25] www.mythtv.org
2012-06-15 23:23:32.149318 N  Enabled verbose msgs:  general
2012-06-15 23:23:32.149343 N  Setting Log Level to LOG_DEBUG
2012-06-15 23:23:32.149382 I  Added logging to the console
2012-06-15 23:23:32.149389 I  Added database logging to table logging
2012-06-15 23:23:32.149490 N  Setting up SIGHUP handler
2012-06-15 23:23:32.149584 N  Using runtime prefix = /usr
2012-06-15 23:23:32.149596 N  Using configuration directory = /root/.mythtv
2012-06-15 23:23:32.149741 I  Assumed character encoding: en_US.UTF-8
2012-06-15 23:23:32.150075 E  Unable to read configuration file mysql.txt
2012-06-15 23:23:32.150232 N  Empty LocalHostName.
2012-06-15 23:23:32.150241 I  Using localhost value of dvr.mydomain
2012-06-15 23:23:32.159254 N  Setting QT default locale to en_CA
2012-06-15 23:23:32.159264 I  Current locale en_CA
2012-06-15 23:23:32.159298 N  Reading locale defaults from /usr/share/mythtv//locales/en_ca.xml
2012-06-15 23:23:32.163598 I  Loading en_ca translation for module mythfrontend
2012-06-15 23:23:32.164286 I  Current MythTV Schema Version (DBSchemaVer): 1299
2012-06-15 23:23:32.246017 I  Updating source #1 (Bell ExpressVu) with grabber schedulesdirect1
2012-06-15 23:23:32.246680 I  Found 518 channels for source 1 which use grabber
2012-06-15 23:23:32.246723 I  Checking day @ offset 0, date: Fri Jun 15 2012
2012-06-15 23:23:32.246725 I  Data Refresh needed because of user request
2012-06-15 23:23:32.246727 N  Refreshing data for Fri Jun 15 2012
2012-06-15 23:23:32.246767 I  New static DB connectionDataDirectCon
2012-06-15 23:23:32.248191 I  Retrieving datadirect data.
2012-06-15 23:23:32.248222 I  Grabbing data for Fri Jun 15 2012 offset 0
2012-06-15 23:23:32.248236 I  From Fri Jun 15 04:00:00 2012 to Sat Jun 16 04:00:00 2012 (UTC)
2012-06-15 23:23:32.248253 I  DataDirect: Grabbing listing data
2012-06-15 23:23:32.248308 I  Downloading DataDirect feed
2012-06-15 23:23:42.325618 E  DataDirect: Failed to get data: Download error
2012-06-15 23:23:42.325628 E  Encountered error in grabbing data.
2012-06-15 23:23:42.326028 E  Failed to fetch some program info
2012-06-15 23:23:42.326048 I  Adjusting program database end times.
2012-06-15 23:23:42.401089 I      0 replacements made
2012-06-15 23:23:42.401094 I  Marking generic episodes.
2012-06-15 23:23:42.451923 I      Found 0
2012-06-15 23:23:42.451955 I  Extending non-unique programids with multiple parts.
2012-06-15 23:23:42.463336 I      Found 0
2012-06-15 23:23:42.463355 I  Marking repeats.
2012-06-15 23:23:42.491037 I      Found 0
2012-06-15 23:23:42.491044 I  Unmarking new episode rebroadcast repeats.
2012-06-15 23:23:42.524503 I      Found 0
2012-06-15 23:23:42.654231 I  Marking episode first showings.
2012-06-15 23:23:43.513555 I      Found 4123
2012-06-15 23:23:43.513562 I  Marking episode last showings.
2012-06-15 23:23:44.366038 I      Found 4123
2012-06-15 23:23:44.379522 I  DataDirect: Grabbing next suggested grabbing time
2012-06-15 23:23:45.781794 I  Suggested Time data: 612 bytes
2012-06-15 23:23:45.782093 I  DataDirect: BlockedTime is: 2012-06-15T23:23:44
2012-06-15 23:23:45.782186 I  DataDirect: NextSuggestedTime is: 2012-06-17T16:14:32
2012-06-15 23:23:45.782885 I
===============================================================
| Attempting to contact the master backend for rescheduling.  |
| If the master is not running, rescheduling will happen when |
| the master backend is restarted.                            |
===============================================================
2012-06-15 23:23:45.785561 I  MythCoreContext: Connecting to backend server: 172.31.254.84:6543 (try 1 of 1)
2012-06-15 23:23:45.786397 I  Using protocol version 72
2012-06-15 23:23:45.795254 I  Received remote 'Clear Cache' request
2012-06-15 23:23:45.796948 N  mythfilldatabase run complete.
Application asked to unregister timer 0x1e000001 which is not registered in this thread. Fix application.
2012-06-15 23:23:46.008219 I  DataDirect: Deleting temporary files

________________________________________
From: mythtv-users-bounces at mythtv.org [mythtv-users-bounces at mythtv.org] On Behalf Of Michelle Dupuis [mdupuis at ocg.ca]
Sent: Friday, June 15, 2012 11:22 PM
To: Myth TV Users List
Subject: Re: [mythtv-users] 0.25 mythfilldatabase error

and my qt version is 4.7.2:

qt47.x86_64                               1:4.7.2-1_18.el6              @atrpms-testing

perhaps a new bug?  Strange that it seemed to work once (I got 2 days of data) but not since...
________________________________________
From: mythtv-users-bounces at mythtv.org [mythtv-users-bounces at mythtv.org] On Behalf Of Michelle Dupuis [mdupuis at ocg.ca]
Sent: Friday, June 15, 2012 11:09 PM
To: Myth TV Users List
Subject: Re: [mythtv-users] 0.25 mythfilldatabase error

I saw this bug, but my schedulesdirect username does not have an @ symbol (and I confirmed the username is set correctly in mythtv-setup)

________________________________________
From: mythtv-users-bounces at mythtv.org [mythtv-users-bounces at mythtv.org] On Behalf Of Michael T. Dean [mtdean at thirdcontact.com]
Sent: Friday, June 15, 2012 10:57 PM
To: Myth TV Users List
Subject: Re: [mythtv-users] 0.25 mythfilldatabase error

On 06/15/2012 10:49 PM, Michelle Dupuis wrote:
> I have started my new myth .25 backend tonight hand have a few
> interesting errors.  The first is that mythfilldatabase is not
> populating the schedules.  (See error below)/.  Any ideas?
> I was able to get 2 days (from schedule direct) but nothing after...
> 2012-06-15 22:10:32.648670 E  DataDirect: Failed to get data: Download
> error
> 2012-06-15 22:10:32.648678 E  Encountered error in grabbing data.
> 2012-06-15 22:10:32.648763 I  Checking day @ offset 13, date: Thu Jun
> 28 2012
> 2012-06-15 22:10:32.661084 I  Data refresh needed because no data
> exists for day @ offset 13 from 8PM - midnight.
> 2012-06-15 22:10:32.661089 N  Refreshing data for Thu Jun 28 2012
> 2012-06-15 22:10:32.661391 I  Retrieving datadirect data.
> 2012-06-15 22:10:32.661407 I  Grabbing data for Fri Jun 15 2012 offset 13
> 2012-06-15 22:10:32.661419 I  From Thu Jun 28 04:00:00 2012 to Fri Jun
> 29 04:00:00 2012 (UTC)
> 2012-06-15 22:10:32.661424 I  DataDirect: Grabbing listing data
> 2012-06-15 22:10:32.661439 I  Downloading DataDirect feed
> 2012-06-15 22:10:42.670503 E  DataDirect: Failed to get data: Download
> error
> 2012-06-15 22:10:42.670511 E  Encountered error in grabbing data.

Qt 4.7.1 and a Schedules Direct username with an @ sign (e-mail address)?

http://code.mythtv.org/trac/ticket/10662#comment:27 and comment 28

Mike
_______________________________________________
mythtv-users mailing list
mythtv-users at mythtv.org
http://www.mythtv.org/mailman/listinfo/mythtv-users
_______________________________________________
mythtv-users mailing list
mythtv-users at mythtv.org
http://www.mythtv.org/mailman/listinfo/mythtv-users
_______________________________________________
mythtv-users mailing list
mythtv-users at mythtv.org
http://www.mythtv.org/mailman/listinfo/mythtv-users
_______________________________________________
mythtv-users mailing list
mythtv-users at mythtv.org
http://www.mythtv.org/mailman/listinfo/mythtv-users