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   
  - Number of indexed reads: EXPECTED
  + Number of indexed reads: UNEXPECTED: 500002 - greater than threshold = 1000.

LOG DETAILS:

2024-11-18 11:11:26.279
2024-11-18 11:11:26.284 act = <firebird.qa.plugin.Action object at [hex]>
2024-11-18 11:11:26.289 capsys = <_pytest.capture.CaptureFixture object at [hex]>
2024-11-18 11:11:26.294
2024-11-18 11:11:26.299 @pytest.mark.version('>=5.0.2')
2024-11-18 11:11:26.304 def test_1(act: Action, capsys):
2024-11-18 11:11:26.313 msg_prefix = 'Number of indexed reads:'
2024-11-18 11:11:26.320 expected_txt = 'EXPECTED'
2024-11-18 11:11:26.326 idx_reads = {}
2024-11-18 11:11:26.331 with act.db.connect() as con:
2024-11-18 11:11:26.337 cur = con.cursor()
2024-11-18 11:11:26.342 cur.execute("select rdb$relation_id from rdb$relations where rdb$relation_name = 'TEST'")
2024-11-18 11:11:26.348 test_rel_id = cur.fetchone()[0]
2024-11-18 11:11:26.353 idx_reads[test_rel_id] = 0
2024-11-18 11:11:26.360
2024-11-18 11:11:26.368 for x_table in con.info.get_table_access_stats():
2024-11-18 11:11:26.375 if x_table.table_id == test_rel_id:
2024-11-18 11:11:26.382 idx_reads[test_rel_id] = -x_table.indexed
2024-11-18 11:11:26.388
2024-11-18 11:11:26.395 cur.execute('select count(*) from (select id from test where id < 3 order by id)')
2024-11-18 11:11:26.401 data = cur.fetchall()
2024-11-18 11:11:26.408
2024-11-18 11:11:26.415 for x_table in con.info.get_table_access_stats():
2024-11-18 11:11:26.422 if x_table.table_id == test_rel_id:
2024-11-18 11:11:26.429 idx_reads[test_rel_id] += x_table.indexed
2024-11-18 11:11:26.435
2024-11-18 11:11:26.443 # BEFORE fix value of indexed was 500002. After fix: 886
2024-11-18 11:11:26.449 if idx_reads[test_rel_id] < MAX_ALLOWED_IDX_READS:
2024-11-18 11:11:26.455 print(f'{msg_prefix} {expected_txt}')
2024-11-18 11:11:26.461 else:
2024-11-18 11:11:26.466 print(f'{msg_prefix} UNEXPECTED: {idx_reads[test_rel_id]} - greater than threshold = {MAX_ALLOWED_IDX_READS}.')
2024-11-18 11:11:26.472
2024-11-18 11:11:26.477 act.expected_stdout = f"""
2024-11-18 11:11:26.482 {msg_prefix} {expected_txt}
2024-11-18 11:11:26.490 """
2024-11-18 11:11:26.498
2024-11-18 11:11:26.504 act.stdout = capsys.readouterr().out
2024-11-18 11:11:26.509 >       assert act.clean_stdout == act.clean_expected_stdout
2024-11-18 11:11:26.514 E       assert
2024-11-18 11:11:26.520 E         - Number of indexed reads: EXPECTED
2024-11-18 11:11:26.525 E         + Number of indexed reads: UNEXPECTED: 500002 - greater than threshold = 1000.
2024-11-18 11:11:26.529
2024-11-18 11:11:26.534 tests/bugs/gh_8291_test.py:82: AssertionError
2024-11-18 11:11:26.539 ---------------------------- Captured stdout setup -----------------------------
2024-11-18 11:11:26.544 Creating db: localhost:/var/tmp/qa_2024/test_11671/test.fdb [page_size=8192, 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):
        msg_prefix = 'Number of indexed reads:'
        expected_txt = 'EXPECTED'
        idx_reads = {}
        with act.db.connect() as con:
            cur = con.cursor()
            cur.execute("select rdb$relation_id from rdb$relations where rdb$relation_name = 'TEST'")
            test_rel_id = cur.fetchone()[0]
            idx_reads[test_rel_id] = 0
    
            for x_table in con.info.get_table_access_stats():
                if x_table.table_id == test_rel_id:
                    idx_reads[test_rel_id] = -x_table.indexed
    
            cur.execute('select count(*) from (select id from test where id < 3 order by id)')
            data = cur.fetchall()
    
            for x_table in con.info.get_table_access_stats():
                if x_table.table_id == test_rel_id:
                    idx_reads[test_rel_id] += x_table.indexed
    
            # BEFORE fix value of indexed was 500002. After fix: 886
            if idx_reads[test_rel_id] < MAX_ALLOWED_IDX_READS:
                print(f'{msg_prefix} {expected_txt}')
            else:
                print(f'{msg_prefix} UNEXPECTED: {idx_reads[test_rel_id]} - greater than threshold = {MAX_ALLOWED_IDX_READS}.')
    
        act.expected_stdout = f"""
            {msg_prefix} {expected_txt}
        """
    
        act.stdout = capsys.readouterr().out
>       assert act.clean_stdout == act.clean_expected_stdout
E       assert   
E         - Number of indexed reads: EXPECTED
E         + Number of indexed reads: UNEXPECTED: 500002 - greater than threshold = 1000.

tests/bugs/gh_8291_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 5.0.3.1674 2025.06.27 3ee5c P P 2818 2387 2025.07.03 10:57:26.669 2025.07.03 10:57:29.487 2025.07.03 09:36:57.127 2025.07.03 09:36:59.514
2 5.0.3.1657 2025.06.19 4bd4c P P 3172 2691 2025.06.27 12:21:22.098 2025.06.27 12:21:25.270 2025.06.27 11:02:13.441 2025.06.27 11:02:16.132
3 5.0.3.1657 2025.06.11 dae6f P P 3102 2850 2025.06.17 07:26:26.096 2025.06.17 07:26:29.198 2025.06.17 06:06:05.853 2025.06.17 06:06:08.703
4 5.0.3.1657 2025.06.10 dbc92 P P 3205 3489 2025.06.11 12:16:57.233 2025.06.11 12:17:00.438 2025.06.11 10:47:52.689 2025.06.11 10:47:56.178
5 5.0.3.1656 2025.05.20 c4b11 P P 3257 2818 2025.06.10 12:10:43.067 2025.06.10 12:10:46.324 2025.06.10 10:46:20.900 2025.06.10 10:46:23.718
6 5.0.3.1652 2025.05.13 f51c6 P P 3938 3206 2025.05.20 05:14:58.129 2025.05.20 05:15:02.067 2025.05.20 03:38:39.622 2025.05.20 03:38:42.828
7 5.0.3.1651 2025.04.30 141ef P P 3980 3191 2025.05.13 12:13:00.268 2025.05.13 12:13:04.248 2025.05.13 10:35:38.184 2025.05.13 10:35:41.375
8 5.0.3.1650 2025.04.28 4cbff P P 4068 3723 2025.05.01 11:46:47.071 2025.05.01 11:46:51.139 2025.05.01 10:10:23.178 2025.05.01 10:10:26.901
9 5.0.3.1649 2025.04.21 5b2d0 P P 3299 3244 2025.04.28 05:44:34.461 2025.04.28 05:44:37.760 2025.04.28 04:10:03.441 2025.04.28 04:10:06.685
10 5.0.3.1648 2025.04.18 2f4c5 P P 3611 2781 2025.04.20 05:36:18.988 2025.04.20 05:36:22.599 2025.04.20 04:04:25.054 2025.04.20 04:04:27.835
11 5.0.3.1635 2025.03.31 22ec6 P P 3807 3453 2025.04.18 11:59:23.264 2025.04.18 11:59:27.071 2025.04.18 10:23:34.944 2025.04.18 10:23:38.397
12 5.0.3.1633 2025.03.28 3123a P P 3527 3288 2025.03.31 10:33:19.199 2025.03.31 10:33:22.726 2025.03.31 09:03:48.587 2025.03.31 09:03:51.875
13 5.0.3.1633 2025.03.27 e0fb8 P P 3647 3390 2025.03.28 11:08:40.865 2025.03.28 11:08:44.512 2025.03.28 09:36:24.718 2025.03.28 09:36:28.108
14 5.0.3.1631 2025.03.21 1925b P P 3969 3196 2025.03.27 10:56:25.986 2025.03.27 10:56:29.955 2025.03.27 09:25:01.775 2025.03.27 09:25:04.971
15 5.0.3.1628 2025.03.14 16d05 P P 848 404 2025.03.17 08:29:33.297 2025.03.17 08:29:34.145 2025.03.17 06:49:16.191 2025.03.17 06:49:16.595
16 5.0.3.1627 2025.02.26 4e218 P P 888 502 2025.03.14 11:43:55.379 2025.03.14 11:43:56.267 2025.03.14 10:02:34.890 2025.03.14 10:02:35.392
17 5.0.3.1624 2025.02.25 dc3b2 P P 852 462 2025.02.26 10:34:28.474 2025.02.26 10:34:29.326 2025.02.26 08:58:38.842 2025.02.26 08:58:39.304
18 5.0.2.1615 2025.02.20 4a726 P P 680 396 2025.02.25 11:01:50.503 2025.02.25 11:01:51.183 2025.02.25 09:24:13.008 2025.02.25 09:24:13.404
19 5.0.2.1615 2025.02.14 9cb76 P P 790 455 2025.02.20 07:51:03.903 2025.02.20 07:51:04.693 2025.02.20 06:17:09.052 2025.02.20 06:17:09.507
20 5.0.2.1577 2024.12.24 3c80e P P 834 423 2025.02.14 11:04:58.458 2025.02.14 11:04:59.292 2025.02.14 09:30:42.792 2025.02.14 09:30:43.215
21 5.0.2.1576 2024.12.17 646b0 P P 579 356 2024.12.24 20:27:25.858 2024.12.24 20:27:26.437 2024.12.24 17:16:02.228 2024.12.24 17:16:02.584
22 5.0.2.1575 2024.12.08 63d39 P P 614 296 2024.12.16 08:08:49.467 2024.12.16 08:08:50.081 2024.12.16 06:58:45.732 2024.12.16 06:58:46.028
23 5.0.2.1567 2024.11.26 56e63 P P 629 350 2024.12.04 07:49:56.240 2024.12.04 07:49:56.869 2024.12.04 06:38:08.708 2024.12.04 06:38:09.058
24 5.0.2.1567 2024.11.21 96f61 P P 578 338 2024.11.26 05:31:43.326 2024.11.26 05:31:43.904 2024.11.26 04:23:16.687 2024.11.26 04:23:17.025
25 5.0.2.1567 2024.11.18 e1289 P P 626 362 2024.11.21 07:36:04.340 2024.11.21 07:36:04.966 2024.11.21 06:28:22.358 2024.11.21 06:28:22.720
26 5.0.2.1533 2024.10.23 0ec43 F F 651 335 2024.11.18 07:52:26.528 2024.11.18 07:52:27.179 2024.11.18 06:42:46.799 2024.11.18 06:42:47.134

Elapsed time, ms. Chart for last 26 runs:

Last commits information (all timestamps in UTC):