The xbm.log is
==========================================================
2012-01-04 13:55:11 [DEBUG] : [ hostGetter->getByName: Sending SQL:
SELECT host_id FROM hosts WHERE hostname='
blr-cos-ppt01.digi.com' ]
2012-01-04 13:55:11 [DEBUG] : [ hostGetter->getByName: Query took
7.9870223999023E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackupGetter-
>getByHostnameAndName: Sending SQL: SELECT scheduled_backup_id FROM
scheduled_backups
WHERE host_id=41 AND name='NightlyBackup' ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackupGetter-
>getByHostnameAndName: Query took 3.6954879760742E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.3869018554688E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.1007995605469E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.2915344238281E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.2915344238281E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.1007995605469E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Sending SQL:
SELECT queue_ticket_id, pid FROM queue_tickets WHERE
queue_name='scheduledBackup:31' ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Query took
0.0002288818359375 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Sending SQL:
DELETE FROM queue_tickets WHERE queue_ticket_id=801 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Query took
0.0027148723602295 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->getTicketNumber: Sending
SQL: INSERT INTO queue_tickets (queue_ticket_id, queue_name,
entry_time, pid) VALUES (NULL, 'scheduledBackup:31', NOW(), 20108) ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->getTicketNumber: Query
took 0.0025548934936523 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Sending SQL:
SELECT queue_ticket_id, pid FROM queue_tickets WHERE
queue_name='hostBackup:41' ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Query took
0.00010204315185547 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->getTicketNumber: Sending
SQL: INSERT INTO queue_tickets (queue_ticket_id, queue_name,
entry_time, pid) VALUES (NULL, 'hostBackup:41', NOW(), 20108) ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->getTicketNumber: Query
took 0.0015039443969727 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Sending SQL:
SELECT queue_ticket_id, pid FROM queue_tickets WHERE
queue_name='scheduledBackup:31' ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Query took
9.9897384643555E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue:
Sending SQL: SELECT queue_ticket_id FROM queue_tickets WHERE
queue_name='scheduledBackup:31' AND queue_ticket_id=831 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue: Query
took 9.8943710327148E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue:
Sending SQL: SELECT queue_ticket_id FROM queue_tickets WHERE
queue_name='scheduledBackup:31' ORDER BY entry_time ASC LIMIT 1 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue: Query
took 0.00010919570922852 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Sending SQL:
SELECT queue_ticket_id, pid FROM queue_tickets WHERE
queue_name='hostBackup:41' ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Query took
0.00010013580322266 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue:
Sending SQL: SELECT queue_ticket_id FROM queue_tickets WHERE
queue_name='hostBackup:41' AND queue_ticket_id=841 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue: Query
took 9.2029571533203E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue:
Sending SQL: SELECT queue_ticket_id FROM queue_tickets WHERE
queue_name='hostBackup:41' ORDER BY entry_time ASC LIMIT 1 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue: Query
took 0.00010204315185547 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Sending SQL:
SELECT queue_ticket_id, pid FROM queue_tickets WHERE
queue_name='scheduledBackup:GLOBAL' ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Query took
0.00010204315185547 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->getTicketNumber: Sending
SQL: INSERT INTO queue_tickets (queue_ticket_id, queue_name,
entry_time, pid) VALUES (NULL, 'scheduledBackup:GLOBAL', NOW(),
20108) ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->getTicketNumber: Query
took 0.0017471313476562 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Sending SQL:
SELECT queue_ticket_id, pid FROM queue_tickets WHERE
queue_name='scheduledBackup:GLOBAL' ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->cleanQueue: Query took
0.00010800361633301 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue:
Sending SQL: SELECT queue_ticket_id FROM queue_tickets WHERE
queue_name='scheduledBackup:GLOBAL' AND queue_ticket_id=851 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue: Query
took 9.0837478637695E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue:
Sending SQL: SELECT queue_ticket_id FROM queue_tickets WHERE
queue_name='scheduledBackup:GLOBAL' ORDER BY entry_time ASC LIMIT 1 ]
2012-01-04 13:55:11 [DEBUG] : [ queueManager->checkFrontOfQueue: Query
took 0.00010418891906738 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.2915344238281E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.4107437133789E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getParameters:
Sending SQL: SELECT bsp.param_name, sbp.param_value FROM
scheduled_backups sb JOIN backup_strategy_params bsp
ON sb.backup_strategy_id = bsp.backup_strategy_id
JOIN scheduled_backup_params sbp
ON sbp.scheduled_backup_id = sb.scheduled_backup_id AND
bsp.backup_strategy_param_id = sbp.backup_strategy_param_id
WHERE
sb.scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getParameters: Query
took 4.0054321289062E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup-
>getSnapshotGroupsNewestToOldest: Sending SQL: SELECT DISTINCT
snapshot_group_num FROM backup_snapshots WHERE scheduled_backup_id=31
AND status='COMPLETED' ORDER BY snapshot_group_num DESC ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup-
>getSnapshotGroupsNewestToOldest: Query took 0.00015497207641602 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.2915344238281E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.5061111450195E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
5.3167343139648E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.2200088500977E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.1961669921875E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.1007995605469E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 13:55:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
7.7009201049805E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ mysqlTypeGetter->getById: Sending SQL:
SELECT mysql_type_id FROM mysql_types WHERE mysql_type_id=1 ]
2012-01-04 13:55:11 [DEBUG] : [ mysqlTypeGetter->getById: Query took
4.0054321289062E-05 ]
2012-01-04 13:55:11 [DEBUG] : [ mysqlType->getInfo: Sending SQL:
SELECT * FROM mysql_types WHERE mysql_type_id=1 ]
2012-01-04 13:55:11 [DEBUG] : [ mysqlType->getInfo: Query took
4.887580871582E-05 ]
2012-01-04 14:00:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 14:00:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
8.9883804321289E-05 ]
2012-01-04 14:00:11 [DEBUG] : [ scheduledBackup->getInfo: Sending SQL:
SELECT sb.*, bs.strategy_code, bs.strategy_name FROM scheduled_backups
sb JOIN backup_strategies bs ON
sb.backup_strategy_id=bs.backup_strategy_id WHERE
scheduled_backup_id=31 ]
2012-01-04 14:00:11 [DEBUG] : [ scheduledBackup->getInfo: Query took
4.3869018554688E-05 ]
==========================================================