caplog for log messages in PyTest - Build an Automation Script

Verify log messages using caplog in pytest

Preconditions (2)

Step 1: Run the function that generates log messages

Step 2: Capture the log messages using the caplog fixture

Step 3: Check that the expected log message appears in the captured logs

✅ Expected Result: The expected log message is found in the captured logs during the test run

Automation Requirements - pytest

Assertions Needed:

Assert that the expected log message is present in caplog.text

Assert the log level of the captured message if applicable

Best Practices:

Use caplog fixture parameter in the test function

Set caplog level explicitly if needed

Avoid hardcoding log message substrings; use exact or partial matching carefully

Automated Solution

PyTest

import logging
import pytest

# Function to test

def greet(name):
    logger = logging.getLogger('myapp')
    logger.info(f'Greeting {name}')
    return f'Hello, {name}!'

# Test function using caplog

def test_greet_logs_message(caplog):
    with caplog.at_level(logging.INFO):
        result = greet('Alice')
    assert result == 'Hello, Alice!'
    assert 'Greeting Alice' in caplog.text
    # Optional: check log level of the first record
    assert caplog.records[0].levelname == 'INFO'

The greet function logs an info message with the name. The test test_greet_logs_message uses the caplog fixture to capture logs.

Inside the test, caplog.at_level(logging.INFO) sets the log level to INFO to capture info messages. The function is called with 'Alice'.

Assertions check that the function returns the expected greeting string and that the log message 'Greeting Alice' appears in the captured logs. Additionally, it verifies the log level of the first captured record is INFO.

This approach ensures logs are captured and verified without printing them, making tests clean and reliable.

Common Mistakes - 3 Pitfalls

Not using caplog fixture parameter in the test function

Not setting the log level with caplog.at_level()

Asserting on printed output instead of caplog.text

Bonus Challenge

Now add data-driven testing with 3 different names to verify log messages for each

Show Hint

Practice

1. What is the main purpose of using caplog in pytest tests?

easy

A. To capture and check log messages generated during test execution

B. To mock external API calls in tests

C. To measure test execution time

D. To generate test reports automatically

Solution

1. Step 1: Understand caplog's role in pytest

   caplog is a pytest fixture used to capture log messages during tests.

2. Step 2: Identify the main use

   It allows assertions on log output to verify correct logging behavior.

3. Final Answer:

   To capture and check log messages generated during test execution -> Option A

4. Quick Check:

   caplog = capture logs [OK]

Hint: caplog captures logs so you can check messages easily [OK]

Common Mistakes:

• Confusing caplog with mocking tools
• Thinking caplog measures time
• Assuming caplog generates reports

2. Which of the following is the correct way to use caplog to capture only WARNING level logs in a test function?

easy

A. caplog.log_level(logging.WARNING)

B. caplog.set_level(logging.WARNING)

C. caplog.capture_level = logging.WARNING

D. with caplog.at_level(logging.WARNING):

Solution

1. Step 1: Recall caplog syntax for setting log level

   caplog uses a context manager method at_level() to set the log level temporarily.

2. Step 2: Match correct syntax

   The correct syntax is with caplog.at_level(logging.WARNING): to capture warnings.

3. Final Answer:

   with caplog.at_level(logging.WARNING): -> Option D

4. Quick Check:

   Use caplog.at_level() for log level [OK]

Hint: Use with caplog.at_level(level) to set capture level [OK]

Common Mistakes:

• Trying to set level by assignment
• Using non-existent caplog methods
• Forgetting to use 'with' for context

3. Given the following test code, what will be the output of the assertion?

import logging

def test_log_message(caplog):
    with caplog.at_level(logging.INFO):
        logging.info('Test started')
    assert 'Test started' in caplog.text

medium

A. The assertion passes because the log message is captured

B. The assertion fails because caplog.text is empty

C. The test raises a NameError due to missing import

D. The assertion fails because logging level is too low

Solution

1. Step 1: Analyze log capture level

   The code sets caplog to capture INFO level logs, which includes the info message logged.

2. Step 2: Check assertion on caplog.text

   The message 'Test started' is logged and appears in caplog.text, so the assertion passes.

3. Final Answer:

   The assertion passes because the log message is captured -> Option A

4. Quick Check:

   Logged message in caplog.text = assertion pass [OK]

Hint: caplog.text holds captured logs as a string [OK]

Common Mistakes:

• Assuming caplog.text is empty without at_level
• Confusing log levels and capture
• Forgetting to import logging

4. Identify the error in the following pytest test using caplog:

import logging

def test_error_log(caplog):
    caplog.at_level(logging.ERROR)
    logging.error('An error occurred')
    assert 'An error occurred' in caplog.text

medium

A. logging.error() is called before caplog is imported

B. caplog.at_level() must be used as a context manager with 'with'

C. assertion should check caplog.records instead of caplog.text

D. logging level ERROR is too high to capture

Solution

1. Step 1: Check usage of caplog.at_level()

   caplog.at_level() is a context manager and must be used with 'with' keyword.

2. Step 2: Identify missing 'with' statement

   The code calls caplog.at_level() without 'with', so it does not set the level properly.

3. Final Answer:

   caplog.at_level() must be used as a context manager with 'with' -> Option B

4. Quick Check:

   Use 'with caplog.at_level()' not just call [OK]

Hint: Always use 'with' for caplog.at_level() context [OK]

Common Mistakes:

• Calling caplog.at_level() without 'with'
• Checking wrong caplog attribute
• Misunderstanding log level capture

5. You want to test that your function logs a WARNING message only when a certain condition is true. Which pytest test code correctly uses caplog to check this behavior?

hard

A. def test_warn_log(caplog):
with caplog.at_level(logging.WARNING):
my_function(True)
assert 'Warning triggered' in caplog.text
with caplog.at_level(logging.WARNING):
my_function(False)
assert 'Warning triggered' not in caplog.text

B. def test_warn_log(caplog):
with caplog.at_level(logging.INFO):
my_function(True)
assert 'Warning triggered' in caplog.text

C. def test_warn_log(caplog):
with caplog.at_level(logging.WARNING):
my_function(True)
my_function(False)
assert caplog.text.count('Warning triggered') == 1

D. def test_warn_log(caplog):
caplog.set_level(logging.WARNING)
my_function(True)
assert 'Warning triggered' in caplog.text
my_function(False)
assert 'Warning triggered' not in caplog.text

Solution

1. Step 1: Understand the requirement

   We want to check that the warning message logs only once when condition is true, not when false.

2. Step 2: Analyze options for correct caplog usage

   def test_warn_log(caplog): with caplog.at_level(logging.WARNING): my_function(True) my_function(False) assert caplog.text.count('Warning triggered') == 1 uses one context capturing both calls and asserts the warning appears exactly once, matching the requirement.

3. Step 3: Why other options fail

   def test_warn_log(caplog): with caplog.at_level(logging.WARNING): my_function(True) assert 'Warning triggered' in caplog.text with caplog.at_level(logging.WARNING): my_function(False) assert 'Warning triggered' not in caplog.text: separate contexts but caplog.text accumulates logs, so second assert 'Warning triggered' not in caplog.text fails (message from first call persists); def test_warn_log(caplog): caplog.set_level(logging.WARNING) my_function(True) assert 'Warning triggered' in caplog.text my_function(False) assert 'Warning triggered' not in caplog.text: caplog.set_level does not exist (AttributeError), and accumulation would fail second assert; def test_warn_log(caplog): with caplog.at_level(logging.INFO): my_function(True) assert 'Warning triggered' in caplog.text: tests only True case with INFO level (captures WARNING but no False verification).

4. Final Answer:

   def test_warn_log(caplog): with caplog.at_level(logging.WARNING): my_function(True) my_function(False) assert caplog.text.count('Warning triggered') == 1 -> Option C

5. Quick Check:

   Use one caplog context and count messages [OK]

Hint: Use one with caplog.at_level() block and count messages [OK]

Common Mistakes:

• Using multiple caplog contexts losing logs
• Using wrong log level in at_level
• Assuming caplog.set_level exists