[mythtv-users] Schema upgrade from 0.21->0.24_fixes hangs
Josh Mastronarde
jmastron at gmail.com
Tue May 31 23:31:14 UTC 2011
On Tue, May 31, 2011 at 4:25 PM, Josh Mastronarde <jmastron at gmail.com> wrote:
> On Mon, May 30, 2011 at 9:04 PM, Josh Mastronarde <jmastron at gmail.com> wrote:
>> [snip -- schema upgrade appearing to hang]
>>
>> Just an update -- this appears to have nothing to do with Myth; I did
>> a "mythfilldatabase -v database" and did some comparisons with the
>> working 0.21 system; insertions are taking ~200 times longer; I
>> confirmed this with a simple mysql script also, not using any MythTV
>> programs at all. Presumably the schema upgrade would finish
>> eventually, but it's not worth seeing until I can figure out what's
>> wrong with mysql. If anyone's seen this with Fedora 15, let me know,
>> otherwise I'll dig elsewhere.
Sorry about the last message; I tried to cut the size down to the
allowed limit, but pasted (very) wrong. Trying again:
Well, I'm back to thinking there's a Myth related issue -- I tracked
the mysql performance problem to being ext4 related; added "barrier=0"
to the mount options and the test case runs as fast as I'd expect now.
However, the Myth schema upgrade, either through mythtv-setup or
mythbackend, still hangs on the first step (1215->1216; it wants to go
to 1264). I ran "mythbackend -v all", and the logs are attached
below. There's a lot of activity, including database activity in the
first minute, then nothing after the "Upgrading to MythTV schema
version 1216" line; I let it sit for 6 hours last night and no further
log updates appeared.
Any ideas where to look?
Thanks,
Josh
2011-05-31 14:51:50.845 mythbackend version: fixes/0.24
[v0.24.1-4-gc321ee6] www.mythtv.org
2011-05-31 14:51:50.863 Using runtime prefix = /usr/local
2011-05-31 14:51:50.863 Using configuration directory = /home/mythtv/.mythtv
2011-05-31 14:51:50.871 (old)Settings::ReadSettings(settings.txt) - No such file
2011-05-31 14:51:50.881
(old)Settings::ReadSettings(/usr/local/share/mythtv/mysql.txt) - No
such file
2011-05-31 14:51:50.889
(old)Settings::ReadSettings(/usr/local/etc/mythtv/mysql.txt) - No such
file
2011-05-31 14:51:50.897
(old)Settings::ReadSettings(/home/mythtv/.mythtv/mysql.txt) - No such
file
2011-05-31 14:51:50.897 (old)Settings::ReadSettings(./mysql.txt) - No such file
2011-05-31 14:51:50.897 Unable to read configuration file mysql.txt
2011-05-31 14:51:50.897 Empty LocalHostName.
2011-05-31 14:51:50.897 Using localhost value of localhost.localdomain
2011-05-31 14:51:50.897 Clearing Settings Cache.
2011-05-31 14:51:50.918 MCP::DefaultUPnP() - No default UPnP backend
2011-05-31 14:51:50.918 Clearing Settings Cache.
2011-05-31 14:51:50.955 New DB connection, total: 1
2011-05-31 14:51:51.037 Connected to database 'mythconverg' at host: localhost
2011-05-31 14:51:51.100 Closing DB connection named 'DBManager0'
2011-05-31 14:51:51.100 Clearing Settings Cache.
2011-05-31 14:51:51.100 Connected to database 'mythconverg' at host: localhost
2011-05-31 14:51:51.102 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'language' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.102 MSqlQuery::next(DBManager0) Result: "data = en_US"
2011-05-31 14:51:51.102 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'country' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.102 MSqlQuery::next(DBManager0) Result: "data = US"
2011-05-31 14:51:51.102 Current locale en_US
2011-05-31 14:51:51.133 Reading locale defaults from
/usr/local/share/mythtv//locales/en_us.xml
2011-05-31 14:51:51.139 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'freqtable' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:51.145 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'freqtable' AND hostname IS NULL <<<< Returns 1
row(s)
2011-05-31 14:51:51.145 MSqlQuery::next(DBManager0) Result: "data = us-bcast"
2011-05-31 14:51:51.146 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'iso639language0' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.146 MSqlQuery::next(DBManager0) Result: "data = eng"
2011-05-31 14:51:51.146 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'iso639language1' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.146 MSqlQuery::next(DBManager0) Result: "data = eng"
2011-05-31 14:51:51.146 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'tvformat' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:51.147 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'tvformat' AND hostname IS NULL <<<< Returns 1
row(s)
2011-05-31 14:51:51.147 MSqlQuery::next(DBManager0) Result: "data = NTSC"
2011-05-31 14:51:51.147 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'vbiformat' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:51.147 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'vbiformat' AND hostname IS NULL <<<< Returns 1
row(s)
2011-05-31 14:51:51.147 MSqlQuery::next(DBManager0) Result: "data = None"
2011-05-31 14:51:51.148 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'country' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.148 MSqlQuery::next(DBManager0) Result: "data = US"
2011-05-31 14:51:51.148 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'dateformat' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.148 MSqlQuery::next(DBManager0) Result: "data = ddd MMM d"
2011-05-31 14:51:51.149 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'language' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.149 MSqlQuery::next(DBManager0) Result: "data = en_US"
2011-05-31 14:51:51.149 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'mytharchivedateformat' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.149 MSqlQuery::next(DBManager0) Result: "data = %a %b %d"
2011-05-31 14:51:51.149 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'mytharchivetimeformat' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.149 MSqlQuery::next(DBManager0) Result: "data = %I:%M %p"
2011-05-31 14:51:51.150 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'mytharchivevideoformat' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.150 MSqlQuery::next(DBManager0) Result: "data = PAL"
2011-05-31 14:51:51.150 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'shortdateformat' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.150 MSqlQuery::next(DBManager0) Result: "data = M/d"
2011-05-31 14:51:51.150 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'timeformat' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:51.150 MSqlQuery::next(DBManager0) Result: "data = h:mm AP"
2011-05-31 14:51:51.151 Enabling Settings Cache.
2011-05-31 14:51:51.151 Clearing Settings Cache.
2011-05-31 14:51:51.151 Disabling Settings Cache.
2011-05-31 14:51:51.151 Clearing Settings Cache.
2011-05-31 14:51:51.154 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'dbschemaautoupgrade' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:51.154 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'dbschemaautoupgrade' AND hostname IS NULL <<<<
Returns 0 row(s)
2011-05-31 14:51:51.154 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:51.155 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:51:51.155 MSqlQuery::next(DBManager0) Result: "data = 1215"
2011-05-31 14:51:51.155 Current MythTV Schema Version (DBSchemaVer): 1215
2011-05-31 14:51:51.155 MythTV database schema is old. Waiting to see
if DB is being upgraded.
2011-05-31 14:51:52.155 New DB connection, total: 2
2011-05-31 14:51:52.155 Connected to database 'mythconverg' at host: localhost
2011-05-31 14:51:52.156 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:52.156 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname IS NULL
<<<< Returns 1 row(s)
2011-05-31 14:51:52.156 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:06"
2011-05-31 14:51:52.157 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:52.157 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname IS NULL <<<<
Returns 1 row(s)
2011-05-31 14:51:52.157 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:13"
2011-05-31 14:51:52.157 DBUtil::BackupInProgress(): Found database
backup end time of 2011-05-30T23:35:13 later than start time of
2011-05-30T23:35:06, therefore backup is not running.
2011-05-31 14:51:52.157 MSqlQuery::exec(DBManager0) CREATE TABLE IF
NOT EXISTS schemalock ( schemalock int(1));
2011-05-31 14:51:52.158 MSqlQuery::exec(DBManager0) LOCK TABLE schemalock WRITE;
2011-05-31 14:51:52.158 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:52.158 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:51:52.158 MSqlQuery::next(DBManager1) Result: "data = 1215"
2011-05-31 14:51:52.158 Current MythTV Schema Version (DBSchemaVer): 1215
2011-05-31 14:51:52.158 MSqlQuery::exec(DBManager0) UNLOCK TABLES;
2011-05-31 14:51:53.159 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:53.159 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname IS NULL
<<<< Returns 1 row(s)
2011-05-31 14:51:53.159 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:06"
2011-05-31 14:51:53.159 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:53.160 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname IS NULL <<<<
Returns 1 row(s)
2011-05-31 14:51:53.160 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:13"
2011-05-31 14:51:53.160 DBUtil::BackupInProgress(): Found database
backup end time of 2011-05-30T23:35:13 later than start time of
2011-05-30T23:35:06, therefore backup is not running.
2011-05-31 14:51:53.160 MSqlQuery::exec(DBManager0) CREATE TABLE IF
NOT EXISTS schemalock ( schemalock int(1));
2011-05-31 14:51:53.160 MSqlQuery::exec(DBManager0) LOCK TABLE schemalock WRITE;
2011-05-31 14:51:53.160 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:53.161 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:51:53.161 MSqlQuery::next(DBManager1) Result: "data = 1215"
2011-05-31 14:51:53.161 Current MythTV Schema Version (DBSchemaVer): 1215
2011-05-31 14:51:53.161 MSqlQuery::exec(DBManager0) UNLOCK TABLES;
2011-05-31 14:51:54.161 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:54.162 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname IS NULL
<<<< Returns 1 row(s)
2011-05-31 14:51:54.162 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:06"
2011-05-31 14:51:54.162 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:54.162 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname IS NULL <<<<
Returns 1 row(s)
2011-05-31 14:51:54.162 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:13"
2011-05-31 14:51:54.163 DBUtil::BackupInProgress(): Found database
backup end time of 2011-05-30T23:35:13 later than start time of
2011-05-30T23:35:06, therefore backup is not running.
2011-05-31 14:51:54.163 MSqlQuery::exec(DBManager0) CREATE TABLE IF
NOT EXISTS schemalock ( schemalock int(1));
2011-05-31 14:51:54.163 MSqlQuery::exec(DBManager0) LOCK TABLE schemalock WRITE;
2011-05-31 14:51:54.163 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:54.163 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:51:54.163 MSqlQuery::next(DBManager1) Result: "data = 1215"
2011-05-31 14:51:54.163 Current MythTV Schema Version (DBSchemaVer): 1215
2011-05-31 14:51:54.164 MSqlQuery::exec(DBManager0) UNLOCK TABLES;
2011-05-31 14:51:55.164 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:55.164 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname IS NULL
<<<< Returns 1 row(s)
2011-05-31 14:51:55.165 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:06"
2011-05-31 14:51:55.165 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:55.165 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname IS NULL <<<<
Returns 1 row(s)
2011-05-31 14:51:55.165 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:13"
2011-05-31 14:51:55.166 DBUtil::BackupInProgress(): Found database
backup end time of 2011-05-30T23:35:13 later than start time of
2011-05-30T23:35:06, therefore backup is not running.
2011-05-31 14:51:55.166 MSqlQuery::exec(DBManager0) CREATE TABLE IF
NOT EXISTS schemalock ( schemalock int(1));
2011-05-31 14:51:55.166 MSqlQuery::exec(DBManager0) LOCK TABLE schemalock WRITE;
2011-05-31 14:51:55.166 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:55.166 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:51:55.166 MSqlQuery::next(DBManager1) Result: "data = 1215"
2011-05-31 14:51:55.166 Current MythTV Schema Version (DBSchemaVer): 1215
2011-05-31 14:51:55.167 MSqlQuery::exec(DBManager0) UNLOCK TABLES;
2011-05-31 14:51:56.167 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.167 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunstart' AND hostname IS NULL
<<<< Returns 1 row(s)
2011-05-31 14:51:56.167 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:06"
2011-05-31 14:51:56.168 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.168 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdblastrunend' AND hostname IS NULL <<<<
Returns 1 row(s)
2011-05-31 14:51:56.168 MSqlQuery::next(DBManager1) Result: "data =
2011-05-30 23:35:13"
2011-05-31 14:51:56.168 DBUtil::BackupInProgress(): Found database
backup end time of 2011-05-30T23:35:13 later than start time of
2011-05-30T23:35:06, therefore backup is not running.
2011-05-31 14:51:56.168 MSqlQuery::exec(DBManager0) CREATE TABLE IF
NOT EXISTS schemalock ( schemalock int(1));
2011-05-31 14:51:56.168 MSqlQuery::exec(DBManager0) LOCK TABLE schemalock WRITE;
2011-05-31 14:51:56.169 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.169 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:51:56.169 MSqlQuery::next(DBManager1) Result: "data = 1215"
2011-05-31 14:51:56.169 Current MythTV Schema Version (DBSchemaVer): 1215
2011-05-31 14:51:56.169 MSqlQuery::exec(DBManager0) UNLOCK TABLES;
2011-05-31 14:51:56.169 Timed out waiting.
2011-05-31 14:51:56.170 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'disableautomaticbackup' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.170 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'disableautomaticbackup' AND hostname IS NULL
<<<< Returns 0 row(s)
2011-05-31 14:51:56.172 MSqlQuery::exec(DBManager0) SELECT CONCAT('`',
INFORMATION_SCHEMA.TABLES.TABLE_SCHEMA, '`.`',
INFORMATION_SCHEMA.TABLES.TABLE_NAME, '`') AS
`TABLE_NAME` FROM INFORMATION_SCHEMA.TABLES WHERE
INFORMATION_SCHEMA.TABLES.TABLE_SCHEMA = DATABASE() AND
INFORMATION_SCHEMA.TABLES.TABLE_TYPE = 'BASE TABLE' <<<< Returns 93
row(s)
2011-05-31 14:51:56.172 MSqlQuery::next(DBManager0) Result:
"TABLE_NAME = `mythconverg`.`archiveitems`"
2011-05-31 14:51:56.172 MSqlQuery::next(DBManager0) Result:
"TABLE_NAME = `mythconverg`.`callsignnetworkmap`"
[...snipped similar lines...]
2011-05-31 14:51:56.176 MSqlQuery::next(DBManager0) Result:
"TABLE_NAME = `mythconverg`.`weathersourcesettings`"
2011-05-31 14:51:56.176 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'databasebackupscript' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.176 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'databasebackupscript' AND hostname IS NULL
<<<< Returns 0 row(s)
2011-05-31 14:51:56.177 MSqlQuery::exec(DBManager1) DELETE FROM
settings WHERE value = 'BackupDBLastRunStart' AND hostname is NULL;
2011-05-31 14:51:56.177 MSqlQuery::exec(DBManager1) INSERT INTO
settings (value,data ) VALUES ( 'BackupDBLastRunStart', '2011-05-31
14:51:56' );
2011-05-31 14:51:56.177 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.178 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:51:56.178 MSqlQuery::next(DBManager1) Result: "data = 1215"
2011-05-31 14:51:56.193 MSqlQuery::exec(DBManager1) SELECT DISTINCT
dirname FROM storagegroup WHERE groupname = 'DB Backups' AND hostname
= 'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.193 SG(DB Backups): Unable to find any directories
for the local storage group 'DB Backups' on 'localhost.localdomain',
trying directories on all hosts!
2011-05-31 14:51:56.193 MSqlQuery::exec(DBManager1) SELECT DISTINCT
dirname FROM storagegroup WHERE groupname = 'DB Backups' <<<< Returns
0 row(s)
2011-05-31 14:51:56.193 SG(DB Backups): Unable to find storage group
'DB Backups', trying 'Default' group!
2011-05-31 14:51:56.193 MSqlQuery::exec(DBManager1) SELECT DISTINCT
dirname FROM storagegroup WHERE groupname = 'Default' AND hostname =
'localhost.localdomain' <<<< Returns 1 row(s)
2011-05-31 14:51:56.193 MSqlQuery::next(DBManager1) Result: "dirname =
/video/myth/"
2011-05-31 14:51:56.209 SG(Default): FindNextDirMostFree: Starting
2011-05-31 14:51:56.233 SG(Default): FindNextDirMostFree:
'/video/myth' has 191498760 KiB free
2011-05-31 14:51:56.233 SG(Default): FindNextDirMostFree: Using '/video/myth'
2011-05-31 14:51:56.233 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdbscriptargs' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.234 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'backupdbscriptargs' AND hostname IS NULL <<<<
Returns 0 row(s)
2011-05-31 14:51:56.244 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:51:56.245 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:51:56.245 MSqlQuery::next(DBManager1) Result: "data = 1215"
2011-05-31 14:51:56.245 Backing up database with script:
'/usr/local/share/mythtv/mythconverg_backup.pl'
2011-05-31 14:51:56.245 Launching:
/usr/local/share/mythtv/mythconverg_backup.pl
/tmp/mythtv_db_backup_conf_pnvjqO
2011-05-31 14:51:56.246 PID 1814: launched
2011-05-31 14:51:56.246 Starting reaper thread
2011-05-31 14:52:04.853 PID 1814: exited: status=0, result=0
2011-05-31 14:52:04.853 Database Backup complete.
2011-05-31 14:52:04.875 Backed up database to file:
'/video/myth/mythconverg-1215-20110531145156.sql.gz'
2011-05-31 14:52:04.875 MSqlQuery::exec(DBManager1) DELETE FROM
settings WHERE value = 'BackupDBLastRunEnd' AND hostname is NULL;
2011-05-31 14:52:04.876 MSqlQuery::exec(DBManager1) INSERT INTO
settings (value,data ) VALUES ( 'BackupDBLastRunEnd', '2011-05-31
14:52:04' );
2011-05-31 14:52:04.876 MSqlQuery::exec(DBManager0) DELETE FROM
housekeeping WHERE tag = 'BackupDB' ;
2011-05-31 14:52:04.876 MSqlQuery::exec(DBManager0) INSERT INTO
housekeeping(tag,lastrun) values('BackupDB' ,now()) ;
2011-05-31 14:52:04.945 DBUtil::CountClients() found 1
2011-05-31 14:52:04.966 (old)Settings::ReadSettings(settings.txt) - No such file
2011-05-31 14:52:04.979 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbmsversionoverride' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:52:04.980 MSqlQuery::exec(DBManager1) SELECT data FROM
settings WHERE value = 'dbmsversionoverride' AND hostname IS NULL <<<<
Returns 0 row(s)
2011-05-31 14:52:04.980 MSqlQuery::exec(DBManager0) SELECT VERSION();
<<<< Returns 1 row(s)
2011-05-31 14:52:04.980 MSqlQuery::next(DBManager0) Result: "VERSION() = 5.5.12"
2011-05-31 14:52:04.980 Console is non-interactive, can't prompt user...
2011-05-31 14:52:04.980 Upgrading.
2011-05-31 14:52:04.980 MSqlQuery::exec(DBManager1) ALTER DATABASE
mythconverg DEFAULT CHARACTER SET utf8 COLLATE utf8_general_ci;
2011-05-31 14:52:04.980 Newest MythTV Schema Version : 1264
2011-05-31 14:52:04.981 MSqlQuery::exec(DBManager1) CREATE TABLE IF
NOT EXISTS schemalock ( schemalock int(1));
2011-05-31 14:52:04.981 MSqlQuery::exec(DBManager1) LOCK TABLE schemalock WRITE;
2011-05-31 14:52:04.981 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname =
'localhost.localdomain' <<<< Returns 0 row(s)
2011-05-31 14:52:04.981 MSqlQuery::exec(DBManager0) SELECT data FROM
settings WHERE value = 'dbschemaver' AND hostname IS NULL <<<< Returns
1 row(s)
2011-05-31 14:52:04.981 MSqlQuery::next(DBManager0) Result: "data = 1215"
2011-05-31 14:52:04.984 MSqlQuery::exec(DBManager0) SELECT CONCAT('`',
INFORMATION_SCHEMA.TABLES.TABLE_SCHEMA, '`.`',
INFORMATION_SCHEMA.TABLES.TABLE_NAME, '`') AS
`TABLE_NAME` FROM INFORMATION_SCHEMA.TABLES WHERE
INFORMATION_SCHEMA.TABLES.TABLE_SCHEMA = DATABASE() AND
INFORMATION_SCHEMA.TABLES.TABLE_TYPE = 'BASE TABLE' <<<< Returns 93
row(s)
2011-05-31 14:52:04.984 MSqlQuery::next(DBManager0) Result:
"TABLE_NAME = `mythconverg`.`archiveitems`"
2011-05-31 14:52:04.984 MSqlQuery::next(DBManager0) Result:
"TABLE_NAME = `mythconverg`.`callsignnetworkmap`"
[...snipped similar lines...]
2011-05-31 14:52:04.987 MSqlQuery::next(DBManager0) Result:
"TABLE_NAME = `mythconverg`.`weathersourcesettings`"
2011-05-31 14:52:05.913 MSqlQuery::exec(DBManager0) CREATE TEMPORARY
TABLE temp_people SELECT * FROM people;
2011-05-31 14:52:06.627 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_people MODIFY name VARBINARY(255) NOT NULL default '';
2011-05-31 14:52:08.470 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_people MODIFY name CHAR(255) CHARACTER SET utf8
COLLATE utf8_bin NOT NULL default '';
2011-05-31 14:52:08.470 MSqlQuery::exec(DBManager0) SHOW COUNT(*)
WARNINGS; <<<< Returns 1 row(s)
2011-05-31 14:52:08.470 MSqlQuery::next(DBManager0) Result:
"@@session.warning_count = 0"
2011-05-31 14:52:10.991 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_people ADD UNIQUE INDEX verify_unique ( name(41) );
2011-05-31 14:52:11.000 MSqlQuery::exec(DBManager0) DROP TEMPORARY
TABLE temp_people;
2011-05-31 14:52:11.066 MSqlQuery::exec(DBManager0) CREATE TEMPORARY
TABLE temp_oldprogram SELECT * FROM oldprogram;
2011-05-31 14:52:11.146 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_oldprogram MODIFY oldtitle VARBINARY(255) NOT NULL default
'';
2011-05-31 14:52:11.379 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_oldprogram MODIFY oldtitle CHAR(255) CHARACTER SET
utf8 COLLATE utf8_bin NOT NULL default '';
2011-05-31 14:52:11.379 MSqlQuery::exec(DBManager0) SHOW COUNT(*)
WARNINGS; <<<< Returns 1 row(s)
2011-05-31 14:52:11.380 MSqlQuery::next(DBManager0) Result:
"@@session.warning_count = 0"
2011-05-31 14:52:11.788 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_oldprogram ADD UNIQUE INDEX verify_unique ( oldtitle );
2011-05-31 14:52:11.790 MSqlQuery::exec(DBManager0) DROP TEMPORARY
TABLE temp_oldprogram;
2011-05-31 14:52:11.818 MSqlQuery::exec(DBManager0) CREATE TEMPORARY
TABLE temp_oldrecorded SELECT * FROM oldrecorded;
2011-05-31 14:52:11.851 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_oldrecorded MODIFY title VARBINARY(255) NOT NULL default '',
MODIFY subtitle VARBINARY(255) NOT NULL default '', MODIFY
description BLOB NOT NULL;
2011-05-31 14:52:11.973 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_oldrecorded MODIFY title CHAR(255) CHARACTER SET utf8
COLLATE utf8_bin NOT NULL default '', MODIFY subtitle
CHAR(255) CHARACTER SET utf8 COLLATE utf8_bin
NOT NULL default '', MODIFY description TEXT CHARACTER SET
utf8 COLLATE utf8_bin;
2011-05-31 14:52:11.973 MSqlQuery::exec(DBManager0) SHOW COUNT(*)
WARNINGS; <<<< Returns 1 row(s)
2011-05-31 14:52:11.973 MSqlQuery::next(DBManager0) Result:
"@@session.warning_count = 0"
2011-05-31 14:52:12.110 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_oldrecorded ADD UNIQUE INDEX verify_unique ( station,
starttime, title );
2011-05-31 14:52:12.112 MSqlQuery::exec(DBManager0) DROP TEMPORARY
TABLE temp_oldrecorded;
2011-05-31 14:52:12.124 MSqlQuery::exec(DBManager0) CREATE TEMPORARY
TABLE temp_recorded SELECT * FROM recorded;
2011-05-31 14:52:12.138 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_recorded MODIFY title VARBINARY(255) NOT NULL default '',
MODIFY subtitle VARBINARY(255) NOT NULL default '', MODIFY
description BLOB NOT NULL;
2011-05-31 14:52:12.156 MSqlQuery::exec(DBManager0) ALTER TABLE
temp_recorded MODIFY title CHAR(255) CHARACTER SET utf8
COLLATE utf8_bin NOT NULL default '', MODIFY subtitle
CHAR(255) CHARACTER SET utf8 COLLATE utf8_bin
NOT NULL default '', MODIFY description TEXT CHARACTER SET
utf8 COLLATE utf8_bin;
2011-05-31 14:52:12.156 MSqlQuery::exec(DBManager0) SHOW COUNT(*)
WARNINGS; <<<< Returns 1 row(s)
2011-05-31 14:52:12.156 MSqlQuery::next(DBManager0) Result:
"@@session.warning_count = 0"
2011-05-31 14:52:12.158 MSqlQuery::exec(DBManager0) DROP TEMPORARY
TABLE temp_recorded;
2011-05-31 14:52:12.158 New DB connection, total: 3
2011-05-31 14:52:12.158 Connected to database 'mythconverg' at host: localhost
2011-05-31 14:52:12.159 Upgrading to MythTV schema version 1216
More information about the mythtv-users
mailing list