Check firebird.log [no messages found for interval when this test was running]
Jump to: output_first_mismatch    outcomes_full_history    elapsed_time_chart
Show cross-report outcomes.

Annotation type Annotation details
2 @message
assert   
    Select Expression
    ....-> Nested Loop Join (semi)
  - ........-> Table "T1" Full Scan
  + ........-> Table "PUBLIC"."T1" Full Scan
    ........-> Filter
  - ............-> Table "T2" Full Scan
  + ............-> Table "PUBLIC"."T2" Full Scan
    1

LOG DETAILS:

2025-07-03 06:40:53.485
2025-07-03 06:40:53.485 act = <firebird.qa.plugin.Action object at [hex]>
2025-07-03 06:40:53.485 capsys = <_pytest.capture.CaptureFixture object at [hex]>
2025-07-03 06:40:53.485
2025-07-03 06:40:53.485     @pytest.mark.version('>=5.0.2')
2025-07-03 06:40:53.485     def test_1(act: Action, capsys):
2025-07-03 06:40:53.485
2025-07-03 06:40:53.485         test_sql = """
2025-07-03 06:40:53.486             select 1 as x
2025-07-03 06:40:53.486             from t1
2025-07-03 06:40:53.486             where exists (select 1 from t2 where t1.fld = t2.fld)
2025-07-03 06:40:53.486         """
2025-07-03 06:40:53.486
2025-07-03 06:40:53.486         srv_cfg = driver_config.register_server(name = f'srv_cfg_8231', config = '')
2025-07-03 06:40:53.486         db_cfg_name = f'db_cfg_8231'
2025-07-03 06:40:53.486         db_cfg_object = driver_config.register_database(name = db_cfg_name)
2025-07-03 06:40:53.486         db_cfg_object.server.value = srv_cfg.name
2025-07-03 06:40:53.486         db_cfg_object.database.value = str(act.db.db_path)
2025-07-03 06:40:53.486         if act.is_version('<6'):
2025-07-03 06:40:53.486             db_cfg_object.config.value = f"""
2025-07-03 06:40:53.486                 SubQueryConversion = true
2025-07-03 06:40:53.486             """
2025-07-03 06:40:53.486
2025-07-03 06:40:53.486         with connect(db_cfg_name, user = act.db.user, password = act.db.password) as con:
2025-07-03 06:40:53.486             cur = con.cursor()
2025-07-03 06:40:53.486             ps, rs = None, None
2025-07-03 06:40:53.486             try:
2025-07-03 06:40:53.486                 ps = cur.prepare(test_sql)
2025-07-03 06:40:53.487
2025-07-03 06:40:53.487                 # Print explained plan with padding eash line by dots in order to see indentations:
2025-07-03 06:40:53.487                 print( '\n'.join([replace_leading(s) for s in ps.detailed_plan.split('\n')]) )
2025-07-03 06:40:53.487
2025-07-03 06:40:53.487                 # ::: NB ::: 'ps' returns data, i.e. this is SELECTABLE expression.
2025-07-03 06:40:53.487                 # We have to store result of cur.execute(<psInstance>) in order to
2025-07-03 06:40:53.487                 # close it explicitly.
2025-07-03 06:40:53.487                 # Otherwise AV can occur during Python garbage collection and this
2025-07-03 06:40:53.487                 # causes pytest to hang on its final point.
2025-07-03 06:40:53.487                 # Explained by hvlad, email 26.10.24 17:42
2025-07-03 06:40:53.487                 rs = cur.execute(ps)
2025-07-03 06:40:53.487                 for r in rs:
2025-07-03 06:40:53.487                     print(r[0])
2025-07-03 06:40:53.487             except DatabaseError as e:
2025-07-03 06:40:53.487                 print(e.__str__())
2025-07-03 06:40:53.487                 print(e.gds_codes)
2025-07-03 06:40:53.487             finally:
2025-07-03 06:40:53.487                 if rs:
2025-07-03 06:40:53.487                     rs.close() # <<< EXPLICITLY CLOSING CURSOR RESULTS
2025-07-03 06:40:53.487                 if ps:
2025-07-03 06:40:53.488                     ps.free()
2025-07-03 06:40:53.488
2025-07-03 06:40:53.488             con.rollback()
2025-07-03 06:40:53.488
2025-07-03 06:40:53.488         act.expected_stdout = f"""
2025-07-03 06:40:53.488             Select Expression
2025-07-03 06:40:53.488             ....-> Nested Loop Join (semi)
2025-07-03 06:40:53.488             ........-> Table "T1" Full Scan
2025-07-03 06:40:53.488             ........-> Filter
2025-07-03 06:40:53.488             ............-> Table "T2" Full Scan
2025-07-03 06:40:53.488             1
2025-07-03 06:40:53.488         """
2025-07-03 06:40:53.488         act.stdout = capsys.readouterr().out
2025-07-03 06:40:53.488 >       assert act.clean_stdout == act.clean_expected_stdout
2025-07-03 06:40:53.488 E       assert
2025-07-03 06:40:53.488 E           Select Expression
2025-07-03 06:40:53.488 E           ....-> Nested Loop Join (semi)
2025-07-03 06:40:53.488 E         - ........-> Table "T1" Full Scan
2025-07-03 06:40:53.488 E         + ........-> Table "PUBLIC"."T1" Full Scan
2025-07-03 06:40:53.489 E           ........-> Filter
2025-07-03 06:40:53.489 E         - ............-> Table "T2" Full Scan
2025-07-03 06:40:53.489 E         + ............-> Table "PUBLIC"."T2" Full Scan
2025-07-03 06:40:53.489 E           1
2025-07-03 06:40:53.489
2025-07-03 06:40:53.489 tests\bugs\gh_8231_test.py:112: AssertionError
2025-07-03 06:40:53.489 ---------------------------- Captured stdout setup ----------------------------
2025-07-03 06:40:53.489 Creating db: localhost:H:\QA\temp\qa2024.tmp\fbqa\test_11737\test.fdb [page_size=None, sql_dialect=None, charset='NONE', user=SYSDBA, password=masterkey]
3 #text
act = <firebird.qa.plugin.Action pytest object at [hex]>
capsys = <_pytest.capture.CaptureFixture pytest object at [hex]>

    @pytest.mark.version('>=5.0.2')
    def test_1(act: Action, capsys):
    
        test_sql = """
            select 1 as x
            from t1
            where exists (select 1 from t2 where t1.fld = t2.fld)
        """
    
        srv_cfg = driver_config.register_server(name = f'srv_cfg_8231', config = '')
        db_cfg_name = f'db_cfg_8231'
        db_cfg_object = driver_config.register_database(name = db_cfg_name)
        db_cfg_object.server.value = srv_cfg.name
        db_cfg_object.database.value = str(act.db.db_path)
        if act.is_version('<6'):
            db_cfg_object.config.value = f"""
                SubQueryConversion = true
            """
    
        with connect(db_cfg_name, user = act.db.user, password = act.db.password) as con:
            cur = con.cursor()
            ps, rs = None, None
            try:
                ps = cur.prepare(test_sql)
    
                # Print explained plan with padding eash line by dots in order to see indentations:
                print( '\n'.join([replace_leading(s) for s in ps.detailed_plan.split('\n')]) )
    
                # ::: NB ::: 'ps' returns data, i.e. this is SELECTABLE expression.
                # We have to store result of cur.execute(<psInstance>) in order to
                # close it explicitly.
                # Otherwise AV can occur during Python garbage collection and this
                # causes pytest to hang on its final point.
                # Explained by hvlad, email 26.10.24 17:42
                rs = cur.execute(ps)
                for r in rs:
                    print(r[0])
            except DatabaseError as e:
                print(e.__str__())
                print(e.gds_codes)
            finally:
                if rs:
                    rs.close() # <<< EXPLICITLY CLOSING CURSOR RESULTS
                if ps:
                    ps.free()
    
            con.rollback()
    
        act.expected_stdout = f"""
            Select Expression
            ....-> Nested Loop Join (semi)
            ........-> Table "T1" Full Scan
            ........-> Filter
            ............-> Table "T2" Full Scan
            1
        """
        act.stdout = capsys.readouterr().out
>       assert act.clean_stdout == act.clean_expected_stdout
E       assert   
E           Select Expression
E           ....-> Nested Loop Join (semi)
E         - ........-> Table "T1" Full Scan
E         + ........-> Table "PUBLIC"."T1" Full Scan
E           ........-> Filter
E         - ............-> Table "T2" Full Scan
E         + ............-> Table "PUBLIC"."T2" Full Scan
E           1

tests\bugs\gh_8231_test.py:112: 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 6.0.0.892 2025.07.02 10f7d F F 666 445 2025.07.03 03:15:05.654 2025.07.03 03:15:06.320 2025.07.03 01:30:27.408 2025.07.03 01:30:27.853
2 6.0.0.889 2025.07.02 4b4e6 F F 672 437 2025.07.02 11:12:37.724 2025.07.02 11:12:38.396 2025.07.02 09:31:02.352 2025.07.02 09:31:02.789
3 6.0.0.889 2025.07.01 969ac F F 655 454 2025.07.02 03:18:12.894 2025.07.02 03:18:13.549 2025.07.02 01:33:19.903 2025.07.02 01:33:20.357
4 6.0.0.881 2025.06.27 7035d F F 665 445 2025.07.01 03:12:02.125 2025.07.01 03:12:02.790 2025.07.01 01:30:11.367 2025.07.01 01:30:11.812
5 6.0.0.877 2025.06.26 8e38f F F 673 448 2025.06.27 03:13:23.676 2025.06.27 03:13:24.349 2025.06.27 01:30:56.821 2025.06.27 01:30:57.269
6 6.0.0.876 2025.06.25 b1bec F F 652 459 2025.06.26 03:18:42.120 2025.06.26 03:18:42.772 2025.06.26 01:36:35.734 2025.06.26 01:36:36.193
7 6.0.0.863 2025.06.24 c3c20 F F 661 448 2025.06.25 08:02:51.580 2025.06.25 08:02:52.241 2025.06.25 01:30:37.217 2025.06.25 01:30:37.665
8 6.0.0.858 2025.06.24 cbbbf F F 689 448 2025.06.24 17:45:23.847 2025.06.24 17:45:24.536 2025.06.24 16:02:35.019 2025.06.24 16:02:35.467
9 6.0.0.858 2025.06.23 d377c F F 661 446 2025.06.24 08:57:25.548 2025.06.24 08:57:26.209 2025.06.24 07:14:57.416 2025.06.24 07:14:57.862
10 6.0.0.854 2025.06.23 10b58 F F 644 448 2025.06.23 15:38:17.637 2025.06.23 15:38:18.281 2025.06.23 13:54:30.126 2025.06.23 13:54:30.574
11 6.0.0.849 2025.06.20 7b79c F F 647 465 2025.06.21 03:16:36.434 2025.06.21 03:16:37.081 2025.06.21 01:31:09.693 2025.06.21 01:31:10.158
12 6.0.0.848 2025.06.19 c483c F F 656 449 2025.06.20 03:16:14.005 2025.06.20 03:16:14.661 2025.06.20 01:30:55.019 2025.06.20 01:30:55.468
13 6.0.0.845 2025.06.18 20191 F F 660 445 2025.06.19 03:15:52.086 2025.06.19 03:15:52.746 2025.06.19 01:30:42.419 2025.06.19 01:30:42.864
14 6.0.0.843 2025.06.16 995f4 F F 647 447 2025.06.18 03:15:39.351 2025.06.18 03:15:39.998 2025.06.18 01:30:24.566 2025.06.18 01:30:25.013
15 6.0.0.840 2025.06.14 29bca F F 655 461 2025.06.15 03:24:09.471 2025.06.15 03:24:10.126 2025.06.15 01:33:27.854 2025.06.15 01:33:28.315
16 6.0.0.838 2025.06.13 0e28a F F 656 458 2025.06.14 03:21:53.072 2025.06.14 03:21:53.728 2025.06.14 01:31:47.095 2025.06.14 01:31:47.553
17 6.0.0.834 2025.06.11 e889f F F 659 442 2025.06.12 03:19:25.180 2025.06.12 03:19:25.839 2025.06.12 01:32:18.447 2025.06.12 01:32:18.889
18 6.0.0.800 2025.06.10 1f226 P P 615 401 2025.06.11 02:04:23.228 2025.06.11 02:04:23.843 2025.06.11 00:55:34.138 2025.06.11 00:55:34.539
19 6.0.0.799 2025.06.07 be644 P P 595 396 2025.06.10 02:04:01.803 2025.06.10 02:04:02.398 2025.06.10 00:55:11.327 2025.06.10 00:55:11.723
20 6.0.0.797 2025.06.06 303e8 P P 634 407 2025.06.07 02:02:50.454 2025.06.07 02:02:51.088 2025.06.07 00:54:59.393 2025.06.07 00:54:59.800
21 6.0.0.795 2025.05.29 7a71a P P 607 398 2025.06.06 02:02:40.774 2025.06.06 02:02:41.381 2025.06.06 00:54:57.366 2025.06.06 00:54:57.764
22 6.0.0.792 2025.05.28 ee5a8 P P 604 403 2025.05.29 02:01:46.643 2025.05.29 02:01:47.247 2025.05.29 00:55:03.700 2025.05.29 00:55:04.103
23 6.0.0.791 2025.05.27 02db8 P P 602 397 2025.05.28 02:02:15.585 2025.05.28 02:02:16.187 2025.05.28 00:54:46.633 2025.05.28 00:54:47.030
24 6.0.0.789 2025.05.21 64051 P P 593 397 2025.05.25 02:01:58.549 2025.05.25 02:01:59.142 2025.05.25 00:55:12.167 2025.05.25 00:55:12.564
25 6.0.0.787 2025.05.20 230ad P P 595 405 2025.05.21 02:00:54.625 2025.05.21 02:00:55.220 2025.05.21 00:54:21.628 2025.05.21 00:54:22.033
26 6.0.0.783 2025.05.12 37320 P P 592 415 2025.05.19 02:00:41.100 2025.05.19 02:00:41.692 2025.05.19 00:54:28.788 2025.05.19 00:54:29.203
27 6.0.0.779 2025.05.11 136fa P P 611 412 2025.05.12 02:02:49.379 2025.05.12 02:02:49.990 2025.05.12 00:55:06.459 2025.05.12 00:55:06.871
28 6.0.0.778 2025.05.07 d735e P P 603 402 2025.05.11 02:02:14.010 2025.05.11 02:02:14.613 2025.05.11 00:55:20.162 2025.05.11 00:55:20.564
29 6.0.0.776 2025.05.06 007cd P P 611 399 2025.05.07 02:01:28.130 2025.05.07 02:01:28.741 2025.05.07 00:54:57.592 2025.05.07 00:54:57.991
30 6.0.0.770 2025.05.05 82c4a P P 616 404 2025.05.06 02:01:46.804 2025.05.06 02:01:47.420 2025.05.06 00:55:04.097 2025.05.06 00:55:04.501
31 6.0.0.767 2025.05.01 cdd29 P P 604 422 2025.05.02 02:02:23.030 2025.05.02 02:02:23.634 2025.05.02 00:55:54.518 2025.05.02 00:55:54.940
32 6.0.0.762 2025.04.30 5cb15 P P 609 423 2025.05.01 02:00:49.952 2025.05.01 02:00:50.561 2025.05.01 00:55:15.735 2025.05.01 00:55:16.158
33 6.0.0.755 2025.04.29 739c6 P P 633 399 2025.04.30 02:01:03.129 2025.04.30 02:01:03.762 2025.04.30 00:54:58.182 2025.04.30 00:54:58.581
34 6.0.0.753 2025.04.27 29ab3 P P 600 446 2025.04.29 02:01:13.528 2025.04.29 02:01:14.128 2025.04.29 00:55:06.506 2025.04.29 00:55:06.952
35 6.0.0.745 2025.04.21 78ad8 P P 615 396 2025.04.26 02:02:39.229 2025.04.26 02:02:39.844 2025.04.26 00:55:23.164 2025.04.26 00:55:23.560
36 6.0.0.744 2025.04.19 e883a P P 622 409 2025.04.20 02:02:06.060 2025.04.20 02:02:06.682 2025.04.20 00:55:15.925 2025.04.20 00:55:16.334
37 6.0.0.742 2025.04.17 abc3b P P 603 401 2025.04.19 02:01:34.102 2025.04.19 02:01:34.705 2025.04.19 00:54:50.386 2025.04.19 00:54:50.787
38 6.0.0.737 2025.04.16 fe52b P P 606 413 2025.04.17 02:01:57.863 2025.04.17 02:01:58.469 2025.04.17 00:55:03.866 2025.04.17 00:55:04.279

Elapsed time, ms. Chart for last 38 runs:

Last commits information (all timestamps in UTC):