Jump to: output_first_mismatch    outcomes_full_history    elapsed_time_chart
Show cross-report outcomes.

Annotation type Annotation details
2 @message
assert   
    Sweep is started by SYSDBA
    Sweep is finished
  + Sweep is started by SYSDBA
  + Sweep is finished

LOG DETAILS:

2024-05-12 19:15:48.475
2024-05-12 19:15:48.481 act = <firebird.qa.plugin.Action object at [hex]>
2024-05-12 19:15:48.487 isql_script = PosixPath('/var/tmp/qa_2024/test_1492/test-script.sql')
2024-05-12 19:15:48.494 isql_output = PosixPath('/var/tmp/qa_2024/test_1492/test-script.out')
2024-05-12 19:15:48.501 capsys = <_pytest.capture.CaptureFixture object at [hex]>
2024-05-12 19:15:48.508
2024-05-12 19:15:48.515 @pytest.mark.version('>=3')
2024-05-12 19:15:48.522 def test_1(act: Action, isql_script: Path, isql_output: Path, capsys):
2024-05-12 19:15:48.528 isql_script.write_text(test_script)
2024-05-12 19:15:48.533 with act.connect_server() as srv:
2024-05-12 19:15:48.538 # Get content of firebird.log BEFORE test
2024-05-12 19:15:48.544 log_before = act.get_firebird_log()
2024-05-12 19:15:48.551 with open(isql_output, mode='w') as isql_out:
2024-05-12 19:15:48.558 p_isql = subprocess.Popen([act.vars['isql'], '-u', act.db.user, '-pas',
2024-05-12 19:15:48.565 act.db.password, act.db.dsn, '-i', str(isql_script)],
2024-05-12 19:15:48.571 stdout=isql_out, stderr=subprocess.STDOUT)
2024-05-12 19:15:48.575 time.sleep(2)
2024-05-12 19:15:48.580 # LAUNCH SWEEP while ISQL is working
2024-05-12 19:15:48.587 srv.database.sweep(database=act.db.db_path)
2024-05-12 19:15:48.593 p_isql.terminate()
2024-05-12 19:15:48.599 # Get content of firebird.log AFTER test
2024-05-12 19:15:48.605 log_after = act.get_firebird_log()
2024-05-12 19:15:48.610 for line in unified_diff(log_before, log_after):
2024-05-12 19:15:48.615 if line.startswith('+') and line.split('+'):
2024-05-12 19:15:48.619 print(line.replace('+', ' '))
2024-05-12 19:15:48.624 act.expected_stdout = expected_stdout
2024-05-12 19:15:48.629 act.stdout = capsys.readouterr().out
2024-05-12 19:15:48.634 >           assert act.clean_stdout == act.clean_expected_stdout
2024-05-12 19:15:48.639 E           assert
2024-05-12 19:15:48.644 E               Sweep is started by SYSDBA
2024-05-12 19:15:48.649 E               Sweep is finished
2024-05-12 19:15:48.653 E             + Sweep is started by SYSDBA
2024-05-12 19:15:48.658 E             + Sweep is finished
2024-05-12 19:15:48.663
2024-05-12 19:15:48.667 tests/bugs/core_2879_test.py:82: AssertionError
2024-05-12 19:15:48.672 ---------------------------- Captured stdout setup -----------------------------
2024-05-12 19:15:48.677 Cached db: db-12.0-None-None-NONE.fdb [page_size=None, sql_dialect=None, charset='NONE'
3 #text
act = <firebird.qa.plugin.Action pytest object at [hex]>
isql_script = PosixPath('/var/tmp/qa_2024/test_1492/test-script.sql')
isql_output = PosixPath('/var/tmp/qa_2024/test_1492/test-script.out')
capsys = <_pytest.capture.CaptureFixture pytest object at [hex]>

    @pytest.mark.version('>=3')
    def test_1(act: Action, isql_script: Path, isql_output: Path, capsys):
        isql_script.write_text(test_script)
        with act.connect_server() as srv:
            # Get content of firebird.log BEFORE test
            log_before = act.get_firebird_log()
            with open(isql_output, mode='w') as isql_out:
                p_isql = subprocess.Popen([act.vars['isql'], '-u', act.db.user, '-pas',
                                           act.db.password, act.db.dsn, '-i', str(isql_script)],
                                          stdout=isql_out, stderr=subprocess.STDOUT)
            time.sleep(2)
            # LAUNCH SWEEP while ISQL is working
            srv.database.sweep(database=act.db.db_path)
            p_isql.terminate()
            # Get content of firebird.log AFTER test
            log_after = act.get_firebird_log()
            for line in unified_diff(log_before, log_after):
                if line.startswith('+') and line.split('+'):
                    print(line.replace('+', ' '))
            act.expected_stdout = expected_stdout
            act.stdout = capsys.readouterr().out
>           assert act.clean_stdout == act.clean_expected_stdout
E           assert   
E               Sweep is started by SYSDBA
E               Sweep is finished
E             + Sweep is started by SYSDBA
E             + Sweep is finished

tests/bugs/core_2879_test.py:82: AssertionError
Full history of outcomes and elapsed time, ms:
NN SNAP_INFO CS_outcome SS_outcome CS_run_time SS_run_time CS_run_beg CS_run_end SS_run_beg SS_run_end
1 3.0.13.33809 2025.05.06 28ad0 P P 3084 2357 2025.06.26 16:09:25.193 2025.06.26 16:09:28.277 2025.06.26 15:24:52.198 2025.06.26 15:24:54.555
2 3.0.13.33808 2025.04.25 28426 P P 3222 2386 2025.05.06 13:28:10.149 2025.05.06 13:28:13.371 2025.05.06 12:35:34.177 2025.05.06 12:35:36.563
3 3.0.13.33803 2025.02.25 08ebd P P 3223 2372 2025.04.25 16:43:26.831 2025.04.25 16:43:30.054 2025.04.25 15:51:06.021 2025.04.25 15:51:08.393
4 3.0.13.33796 2025.01.29 0ab85 P P 1101 533 2025.02.25 15:15:55.464 2025.02.25 15:15:56.565 2025.02.25 14:24:22.781 2025.02.25 14:24:23.314
5 3.0.13.33796 2025.01.14 99b3b P P 1130 515 2025.01.28 17:29:27.434 2025.01.28 17:29:28.564 2025.01.28 16:36:19.781 2025.01.28 16:36:20.296
6 3.0.13.33795 2024.11.24 b8847 P P 1079 513 2025.01.13 15:07:21.143 2025.01.13 15:07:22.222 2025.01.13 14:14:52.859 2025.01.13 14:14:53.372
7 3.0.13.33794 2024.10.15 abe09 P P 756 400 2024.11.23 06:18:18.479 2024.11.23 06:18:19.235 2024.11.23 05:38:33.603 2024.11.23 05:38:34.003
8 3.0.13.33793 2024.10.04 82ccc P P 963 429 2024.10.14 04:12:36.900 2024.10.14 04:12:37.863 2024.10.14 03:29:32.920 2024.10.14 03:29:33.349
9 3.0.13.33792 2024.08.31 d8791 P P 1308 429 2024.10.03 09:17:57.297 2024.10.03 09:17:58.605 2024.10.03 08:32:16.657 2024.10.03 08:32:17.086
10 3.0.13.33787 2024.08.17 2e0d6 P P 2474 1879 2024.08.25 14:47:09.098 2024.08.25 14:47:11.572 2024.08.25 13:07:06.647 2024.08.25 13:07:08.526
11 3.0.13.33787 2024.08.09 df740 P P 1085 569 2024.08.16 12:31:39.878 2024.08.16 12:31:40.963 2024.08.16 11:39:44.871 2024.08.16 11:39:45.440
12 3.0.12.33746 2024.07.15 11dd4 P P 1531 564 2024.08.09 12:28:19.130 2024.08.09 12:28:20.661 2024.08.09 11:34:31.621 2024.08.09 11:34:32.185
13 3.0.12.33746 2024.06.11 8a5eb P P 842 425 2024.07.15 10:22:08.663 2024.07.15 10:22:09.505 2024.07.15 09:37:48.431 2024.07.15 09:37:48.856
14 3.0.12.33744 2024.05.09 9cf37 F P 752 289 2024.05.12 15:30:12.490 2024.05.12 15:30:13.242 2024.05.12 14:50:08.405 2024.05.12 14:50:08.694
15 3.0.12.33744 2024.04.20 af6b2 P P 720 304 2024.05.08 16:46:37.255 2024.05.08 16:46:37.975 2024.05.08 16:08:31.865 2024.05.08 16:08:32.169

Elapsed time, ms. Chart for last 15 runs:

Last commits information (all timestamps in UTC):