mirror of
https://github.com/postgrespro/pg_probackup.git
synced 2024-12-01 09:51:43 +02:00
372 lines
11 KiB
Python
372 lines
11 KiB
Python
import unittest
|
|
import os
|
|
from .helpers.ptrack_helpers import ProbackupTest, ProbackupException
|
|
import datetime
|
|
|
|
module_name = 'logging'
|
|
|
|
|
|
class LogTest(ProbackupTest, unittest.TestCase):
|
|
|
|
# @unittest.skip("skip")
|
|
# @unittest.expectedFailure
|
|
# PGPRO-2154
|
|
def test_log_rotation(self):
|
|
fname = self.id().split('.')[3]
|
|
node = self.make_simple_node(
|
|
base_dir=os.path.join(module_name, fname, 'node'),
|
|
set_replication=True,
|
|
initdb_params=['--data-checksums'])
|
|
|
|
backup_dir = os.path.join(self.tmp_path, module_name, fname, 'backup')
|
|
self.init_pb(backup_dir)
|
|
self.add_instance(backup_dir, 'node', node)
|
|
node.slow_start()
|
|
|
|
self.set_config(
|
|
backup_dir, 'node',
|
|
options=['--log-rotation-age=1s', '--log-rotation-size=1MB'])
|
|
|
|
self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=['--stream', '--log-level-file=verbose'])
|
|
|
|
gdb = self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=['--stream', '--log-level-file=verbose'], gdb=True)
|
|
|
|
gdb.set_breakpoint('open_logfile')
|
|
gdb.run_until_break()
|
|
gdb.continue_execution_until_exit()
|
|
|
|
# Clean after yourself
|
|
self.del_test_dir(module_name, fname)
|
|
|
|
def test_log_filename_strftime(self):
|
|
fname = self.id().split('.')[3]
|
|
node = self.make_simple_node(
|
|
base_dir=os.path.join(module_name, fname, 'node'),
|
|
set_replication=True,
|
|
initdb_params=['--data-checksums'])
|
|
|
|
backup_dir = os.path.join(self.tmp_path, module_name, fname, 'backup')
|
|
self.init_pb(backup_dir)
|
|
self.add_instance(backup_dir, 'node', node)
|
|
node.slow_start()
|
|
|
|
self.set_config(
|
|
backup_dir, 'node',
|
|
options=['--log-rotation-age=1d'])
|
|
|
|
self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=VERBOSE',
|
|
'--log-filename=pg_probackup-%a.log'])
|
|
|
|
day_of_week = datetime.datetime.today().strftime("%a")
|
|
|
|
path = os.path.join(
|
|
backup_dir, 'log', 'pg_probackup-{0}.log'.format(day_of_week))
|
|
|
|
self.assertTrue(os.path.isfile(path))
|
|
|
|
# Clean after yourself
|
|
self.del_test_dir(module_name, fname)
|
|
|
|
def test_truncate_rotation_file(self):
|
|
fname = self.id().split('.')[3]
|
|
node = self.make_simple_node(
|
|
base_dir=os.path.join(module_name, fname, 'node'),
|
|
set_replication=True,
|
|
initdb_params=['--data-checksums'])
|
|
|
|
backup_dir = os.path.join(self.tmp_path, module_name, fname, 'backup')
|
|
self.init_pb(backup_dir)
|
|
self.add_instance(backup_dir, 'node', node)
|
|
node.slow_start()
|
|
|
|
self.set_config(
|
|
backup_dir, 'node',
|
|
options=['--log-rotation-age=1d'])
|
|
|
|
self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=VERBOSE'])
|
|
|
|
rotation_file_path = os.path.join(
|
|
backup_dir, 'log', 'pg_probackup.log.rotation')
|
|
|
|
log_file_path = os.path.join(
|
|
backup_dir, 'log', 'pg_probackup.log')
|
|
|
|
log_file_size = os.stat(log_file_path).st_size
|
|
|
|
self.assertTrue(os.path.isfile(rotation_file_path))
|
|
|
|
# truncate .rotation file
|
|
with open(rotation_file_path, "rb+", 0) as f:
|
|
f.truncate()
|
|
f.flush()
|
|
f.close
|
|
|
|
output = self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=LOG'],
|
|
return_id=False)
|
|
|
|
# check that log file wasn`t rotated
|
|
self.assertGreater(
|
|
os.stat(log_file_path).st_size,
|
|
log_file_size)
|
|
|
|
self.assertIn(
|
|
'WARNING: cannot read creation timestamp from rotation file',
|
|
output)
|
|
|
|
output = self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=LOG'],
|
|
return_id=False)
|
|
|
|
# check that log file wasn`t rotated
|
|
self.assertGreater(
|
|
os.stat(log_file_path).st_size,
|
|
log_file_size)
|
|
|
|
self.assertNotIn(
|
|
'WARNING: cannot read creation timestamp from rotation file',
|
|
output)
|
|
|
|
self.assertTrue(os.path.isfile(rotation_file_path))
|
|
|
|
# Clean after yourself
|
|
self.del_test_dir(module_name, fname)
|
|
|
|
def test_unlink_rotation_file(self):
|
|
fname = self.id().split('.')[3]
|
|
node = self.make_simple_node(
|
|
base_dir=os.path.join(module_name, fname, 'node'),
|
|
set_replication=True,
|
|
initdb_params=['--data-checksums'])
|
|
|
|
backup_dir = os.path.join(self.tmp_path, module_name, fname, 'backup')
|
|
self.init_pb(backup_dir)
|
|
self.add_instance(backup_dir, 'node', node)
|
|
node.slow_start()
|
|
|
|
self.set_config(
|
|
backup_dir, 'node',
|
|
options=['--log-rotation-age=1d'])
|
|
|
|
self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=VERBOSE'])
|
|
|
|
rotation_file_path = os.path.join(
|
|
backup_dir, 'log', 'pg_probackup.log.rotation')
|
|
|
|
log_file_path = os.path.join(
|
|
backup_dir, 'log', 'pg_probackup.log')
|
|
|
|
log_file_size = os.stat(log_file_path).st_size
|
|
|
|
self.assertTrue(os.path.isfile(rotation_file_path))
|
|
|
|
# unlink .rotation file
|
|
os.unlink(rotation_file_path)
|
|
|
|
output = self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=LOG'],
|
|
return_id=False)
|
|
|
|
# check that log file wasn`t rotated
|
|
self.assertGreater(
|
|
os.stat(log_file_path).st_size,
|
|
log_file_size)
|
|
|
|
self.assertIn(
|
|
'WARNING: missing rotation file:',
|
|
output)
|
|
|
|
self.assertTrue(os.path.isfile(rotation_file_path))
|
|
|
|
output = self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=VERBOSE'],
|
|
return_id=False)
|
|
|
|
self.assertNotIn(
|
|
'WARNING: missing rotation file:',
|
|
output)
|
|
|
|
# check that log file wasn`t rotated
|
|
self.assertGreater(
|
|
os.stat(log_file_path).st_size,
|
|
log_file_size)
|
|
|
|
# Clean after yourself
|
|
self.del_test_dir(module_name, fname)
|
|
|
|
def test_garbage_in_rotation_file(self):
|
|
fname = self.id().split('.')[3]
|
|
node = self.make_simple_node(
|
|
base_dir=os.path.join(module_name, fname, 'node'),
|
|
set_replication=True,
|
|
initdb_params=['--data-checksums'])
|
|
|
|
backup_dir = os.path.join(self.tmp_path, module_name, fname, 'backup')
|
|
self.init_pb(backup_dir)
|
|
self.add_instance(backup_dir, 'node', node)
|
|
node.slow_start()
|
|
|
|
self.set_config(
|
|
backup_dir, 'node',
|
|
options=['--log-rotation-age=1d'])
|
|
|
|
self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=VERBOSE'])
|
|
|
|
rotation_file_path = os.path.join(
|
|
backup_dir, 'log', 'pg_probackup.log.rotation')
|
|
|
|
log_file_path = os.path.join(
|
|
backup_dir, 'log', 'pg_probackup.log')
|
|
|
|
log_file_size = os.stat(log_file_path).st_size
|
|
|
|
self.assertTrue(os.path.isfile(rotation_file_path))
|
|
|
|
# mangle .rotation file
|
|
with open(rotation_file_path, "w+b", 0) as f:
|
|
f.write(b"blah")
|
|
f.flush()
|
|
f.close
|
|
|
|
output = self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=LOG'],
|
|
return_id=False)
|
|
|
|
# check that log file wasn`t rotated
|
|
self.assertGreater(
|
|
os.stat(log_file_path).st_size,
|
|
log_file_size)
|
|
|
|
self.assertIn(
|
|
'WARNING: rotation file',
|
|
output)
|
|
|
|
self.assertIn(
|
|
'has wrong creation timestamp',
|
|
output)
|
|
|
|
self.assertTrue(os.path.isfile(rotation_file_path))
|
|
|
|
output = self.backup_node(
|
|
backup_dir, 'node', node,
|
|
options=[
|
|
'--stream',
|
|
'--log-level-file=LOG'],
|
|
return_id=False)
|
|
|
|
self.assertNotIn(
|
|
'WARNING: rotation file',
|
|
output)
|
|
|
|
# check that log file wasn`t rotated
|
|
self.assertGreater(
|
|
os.stat(log_file_path).st_size,
|
|
log_file_size)
|
|
|
|
# Clean after yourself
|
|
self.del_test_dir(module_name, fname)
|
|
|
|
def test_issue_274(self):
|
|
fname = self.id().split('.')[3]
|
|
node = self.make_simple_node(
|
|
base_dir=os.path.join(module_name, fname, 'node'),
|
|
set_replication=True,
|
|
initdb_params=['--data-checksums'])
|
|
|
|
backup_dir = os.path.join(self.tmp_path, module_name, fname, 'backup')
|
|
self.init_pb(backup_dir)
|
|
self.add_instance(backup_dir, 'node', node)
|
|
self.set_archiving(backup_dir, 'node', node)
|
|
node.slow_start()
|
|
|
|
replica = self.make_simple_node(
|
|
base_dir=os.path.join(module_name, fname, 'replica'))
|
|
replica.cleanup()
|
|
|
|
self.backup_node(backup_dir, 'node', node, options=['--stream'])
|
|
self.restore_node(backup_dir, 'node', replica)
|
|
|
|
# Settings for Replica
|
|
self.set_replica(node, replica, synchronous=True)
|
|
self.set_archiving(backup_dir, 'node', replica, replica=True)
|
|
self.set_auto_conf(replica, {'port': replica.port})
|
|
|
|
replica.slow_start(replica=True)
|
|
|
|
node.safe_psql(
|
|
"postgres",
|
|
"create table t_heap as select i as id, md5(i::text) as text, "
|
|
"md5(repeat(i::text,10))::tsvector as tsvector "
|
|
"from generate_series(0,45600) i")
|
|
|
|
log_dir = os.path.join(backup_dir, "somedir")
|
|
|
|
try:
|
|
self.backup_node(
|
|
backup_dir, 'node', replica, backup_type='page',
|
|
options=[
|
|
'--log-level-console=verbose', '--log-level-file=verbose',
|
|
'--log-directory={0}'.format(log_dir), '-j1',
|
|
'--log-filename=somelog.txt', '--archive-timeout=5s',
|
|
'--no-validate', '--log-rotation-size=100KB'])
|
|
# we should die here because exception is what we expect to happen
|
|
self.assertEqual(
|
|
1, 0,
|
|
"Expecting Error because of archiving timeout"
|
|
"\n Output: {0} \n CMD: {1}".format(
|
|
repr(self.output), self.cmd))
|
|
except ProbackupException as e:
|
|
self.assertIn(
|
|
'ERROR: WAL segment',
|
|
e.message,
|
|
'\n Unexpected Error Message: {0}\n CMD: {1}'.format(
|
|
repr(e.message), self.cmd))
|
|
|
|
log_file_path = os.path.join(
|
|
log_dir, 'somelog.txt')
|
|
|
|
self.assertTrue(os.path.isfile(log_file_path))
|
|
|
|
with open(log_file_path, "r+") as f:
|
|
log_content = f.read()
|
|
|
|
self.assertIn('INFO: command:', log_content)
|
|
|
|
# Clean after yourself
|
|
self.del_test_dir(module_name, fname)
|