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

Annotation type Annotation details
2 @message
assert   
    FAILED EXECUTE_TRIGGER_FINISH
  - ERROR AT purge_attachment
    335544382 : paramètre non trouvé
    335545016 : Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
    335544842 : At trigger 'gâchette de déconnexion'

LOG DETAILS:

2024-10-16 06:02:37.241
2024-10-16 06:02:37.246 act = <firebird.qa.plugin.Action object at [hex]>
2024-10-16 06:02:37.253 tmp_worker = <firebird.qa.plugin.User object at [hex]>
2024-10-16 06:02:37.263 capsys = <_pytest.capture.CaptureFixture object at [hex]>
2024-10-16 06:02:37.268
2024-10-16 06:02:37.273 @pytest.mark.trace
2024-10-16 06:02:37.278 @pytest.mark.version('>=4.0')
2024-10-16 06:02:37.282 def test_1(act: Action, tmp_worker: User, capsys):
2024-10-16 06:02:37.288
2024-10-16 06:02:37.293 init_sql  = f"""
2024-10-16 06:02:37.298 set list on;
2024-10-16 06:02:37.302 set bail on;
2024-10-16 06:02:37.308 create exception "paramètre non trouvé" q'#Paramètre "@1" a une valeur incorrecte ou n'a pas été trouvé dans#';
2024-10-16 06:02:37.312 set term ^;
2024-10-16 06:02:37.317 ^
2024-10-16 06:02:37.323 create trigger "gâchette de déconnexion" on disconnect as
2024-10-16 06:02:37.329 begin
2024-10-16 06:02:37.335 if ( current_user != '{act.db.user}' ) then
2024-10-16 06:02:37.343 begin
2024-10-16 06:02:37.350 exception "paramètre non trouvé" using ('fréquence fermée');
2024-10-16 06:02:37.356 end
2024-10-16 06:02:37.361 end
2024-10-16 06:02:37.366 ^
2024-10-16 06:02:37.372 set term ;^
2024-10-16 06:02:37.379 commit;
2024-10-16 06:02:37.386 """
2024-10-16 06:02:37.391
2024-10-16 06:02:37.396 #tmp_sql.write_bytes( bytes(init_sql.encode('utf-8')) )
2024-10-16 06:02:37.402 #act.isql(switches=['-q'], input_file = tmp_sql, combine_output = True, charset = 'utf8', io_enc = 'utf-8')
2024-10-16 06:02:37.407
2024-10-16 06:02:37.412 act.expected_stdout = ''
2024-10-16 06:02:37.420 act.isql(switches=['-q'], input = init_sql, combine_output = True, charset = 'utf8', io_enc = 'utf-8')
2024-10-16 06:02:37.427 assert act.clean_stdout == act.clean_expected_stdout
2024-10-16 06:02:37.431 act.reset()
2024-10-16 06:02:37.436
2024-10-16 06:02:37.441 # ----------------------------------------------------------------------------------------------
2024-10-16 06:02:37.446 with act.connect_server(encoding = 'utf-8') as srv:
2024-10-16 06:02:37.450 srv.info.get_log()
2024-10-16 06:02:37.456 fb_log_init = srv.readlines()
2024-10-16 06:02:37.460 # ----------------------------------------------------------------------------------------------
2024-10-16 06:02:37.465
2024-10-16 06:02:37.470 trace_cfg_items = [
2024-10-16 06:02:37.475 'log_connections = true',
2024-10-16 06:02:37.480 'log_transactions = true',
2024-10-16 06:02:37.486 'time_threshold = 0',
2024-10-16 06:02:37.490 'log_errors = true',
2024-10-16 06:02:37.495 'log_statement_finish = true',
2024-10-16 06:02:37.500 'log_trigger_finish = true',
2024-10-16 06:02:37.505 'max_sql_length = 32768',
2024-10-16 06:02:37.510 ]
2024-10-16 06:02:37.514
2024-10-16 06:02:37.519 with act.trace(db_events = trace_cfg_items, encoding='utf-8'):
2024-10-16 06:02:37.523 test_sql = f"""
2024-10-16 06:02:37.527 set names utf8;
2024-10-16 06:02:37.531 connect '{act.db.dsn}' user {tmp_worker.name} password '{tmp_worker.password}';
2024-10-16 06:02:37.536 quit;
2024-10-16 06:02:37.540 """
2024-10-16 06:02:37.544 act.isql(switches = ['-q'], input = test_sql, connect_db=False, credentials = False, combine_output = True, io_enc = 'utf-8')
2024-10-16 06:02:37.549 assert act.clean_stdout == act.clean_expected_stdout
2024-10-16 06:02:37.553 act.reset()
2024-10-16 06:02:37.557
2024-10-16 06:02:37.562 # ----------------------------------------------------------------------------------------------
2024-10-16 06:02:37.567
2024-10-16 06:02:37.571 allowed_patterns = \
2024-10-16 06:02:37.576 (
2024-10-16 06:02:37.580 re.escape(') FAILED EXECUTE_TRIGGER_FINISH')
2024-10-16 06:02:37.585 ,re.escape(') ERROR AT purge_attachment')
2024-10-16 06:02:37.590 ,re.escape('335544382 :') # name of exception: paramètre non trouvé (without quotes)
2024-10-16 06:02:37.594 ,re.escape('335545016 :') # message of exception: Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
2024-10-16 06:02:37.599 ,re.escape('335544842 : At trigger')
2024-10-16 06:02:37.604 )
2024-10-16 06:02:37.608 allowed_patterns = [ re.compile(p, re.IGNORECASE) for p in allowed_patterns ]
2024-10-16 06:02:37.613
2024-10-16 06:02:37.618 # Example of trace:
2024-10-16 06:02:37.623 # 2024-09-04T18:57:20.7950 (2184:00000000016B23C0) ERROR AT purge_attachment
2024-10-16 06:02:37.628 # ...
2024-10-16 06:02:37.633 # 335544517 : exception 1
2024-10-16 06:02:37.638 # 335544382 : paramètre non trouvé
2024-10-16 06:02:37.642 # 335545016 : Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
2024-10-16 06:02:37.647 # 335544842 : At trigger 'gâchette de déconnexion' line: 5, col: 17
2024-10-16 06:02:37.651
2024-10-16 06:02:37.656 for line in act.trace_log:
2024-10-16 06:02:37.660 #print(line)
2024-10-16 06:02:37.665 if line.strip():
2024-10-16 06:02:37.670 if act.match_any(line.strip(), allowed_patterns):
2024-10-16 06:02:37.675 print(line.strip())
2024-10-16 06:02:37.680
2024-10-16 06:02:37.684 expected_trace_log = """
2024-10-16 06:02:37.689 FAILED EXECUTE_TRIGGER_FINISH
2024-10-16 06:02:37.694 ERROR AT purge_attachment
2024-10-16 06:02:37.699 335544382 : paramètre non trouvé
2024-10-16 06:02:37.704 335545016 : Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
2024-10-16 06:02:37.709 335544842 : At trigger 'gâchette de déconnexion'
2024-10-16 06:02:37.713 """
2024-10-16 06:02:37.718 act.expected_stdout = expected_trace_log
2024-10-16 06:02:37.723 act.stdout = capsys.readouterr().out
2024-10-16 06:02:37.728 >       assert act.clean_stdout == act.clean_expected_stdout
2024-10-16 06:02:37.733 E       assert
2024-10-16 06:02:37.738 E           FAILED EXECUTE_TRIGGER_FINISH
2024-10-16 06:02:37.742 E         - ERROR AT purge_attachment
2024-10-16 06:02:37.747 E           335544382 : paramètre non trouvé
2024-10-16 06:02:37.752 E           335545016 : Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
2024-10-16 06:02:37.756 E           335544842 : At trigger 'gâchette de déconnexion'
2024-10-16 06:02:37.760
2024-10-16 06:02:37.764 tests/functional/intl/test_non_ascii_firebird_and_trace_utf8.py:135: AssertionError
2024-10-16 06:02:37.769 ---------------------------- Captured stdout setup -----------------------------
2024-10-16 06:02:37.773 Creating db: localhost:/var/tmp/qa_2024/test_12054/test.fdb [page_size=None, sql_dialect=None, charset='UTF8', user=SYSDBA, password=masterkey]
2024-10-16 06:02:37.778 CREATE user: TMP_WORKER PLUGIN: Srp
2024-10-16 06:02:37.786 --------------------------- Captured stdout teardown ---------------------------
2024-10-16 06:02:37.794 DROP user: TMP_WORKER PLUGIN: Srp
3 #text
act = <firebird.qa.plugin.Action pytest object at [hex]>
tmp_worker = <firebird.qa.plugin.User pytest object at [hex]>
capsys = <_pytest.capture.CaptureFixture pytest object at [hex]>

    @pytest.mark.trace
    @pytest.mark.version('>=4.0')
    def test_1(act: Action, tmp_worker: User, capsys):
    
        init_sql  = f"""
            set list on;
            set bail on;
            create exception "paramètre non trouvé" q'#Paramètre "@1" a une valeur incorrecte ou n'a pas été trouvé dans#';
            set term ^;
            ^
            create trigger "gâchette de déconnexion" on disconnect as
            begin
                if ( current_user != '{act.db.user}' ) then
                begin
                    exception "paramètre non trouvé" using ('fréquence fermée');
                end
            end
            ^
            set term ;^
            commit;
        """
    
        #tmp_sql.write_bytes( bytes(init_sql.encode('utf-8')) )
        #act.isql(switches=['-q'], input_file = tmp_sql, combine_output = True, charset = 'utf8', io_enc = 'utf-8')
    
        act.expected_stdout = ''
        act.isql(switches=['-q'], input = init_sql, combine_output = True, charset = 'utf8', io_enc = 'utf-8')
        assert act.clean_stdout == act.clean_expected_stdout
        act.reset()
    
        # ----------------------------------------------------------------------------------------------
        with act.connect_server(encoding = 'utf-8') as srv:
            srv.info.get_log()
            fb_log_init = srv.readlines()
        # ----------------------------------------------------------------------------------------------
    
        trace_cfg_items = [
            'log_connections = true',
            'log_transactions = true',
            'time_threshold = 0',
            'log_errors = true',
            'log_statement_finish = true',
            'log_trigger_finish = true',
            'max_sql_length = 32768',
        ]
    
        with act.trace(db_events = trace_cfg_items, encoding='utf-8'):
            test_sql = f"""
                set names utf8;
                connect '{act.db.dsn}' user {tmp_worker.name} password '{tmp_worker.password}';
                quit;
            """
            act.isql(switches = ['-q'], input = test_sql, connect_db=False, credentials = False, combine_output = True, io_enc = 'utf-8')
            assert act.clean_stdout == act.clean_expected_stdout
            act.reset()
    
        # ----------------------------------------------------------------------------------------------
    
        allowed_patterns = \
        (
             re.escape(') FAILED EXECUTE_TRIGGER_FINISH')
            ,re.escape(') ERROR AT purge_attachment')
            ,re.escape('335544382 :') # name of exception: paramètre non trouvé (without quotes)
            ,re.escape('335545016 :') # message of exception: Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
            ,re.escape('335544842 : At trigger')
        )
        allowed_patterns = [ re.compile(p, re.IGNORECASE) for p in allowed_patterns ]
    
        # Example of trace:
        # 2024-09-04T18:57:20.7950 (2184:00000000016B23C0) ERROR AT purge_attachment
        # ...
        # 335544517 : exception 1
        # 335544382 : paramètre non trouvé
        # 335545016 : Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
        # 335544842 : At trigger 'gâchette de déconnexion' line: 5, col: 17
    
        for line in act.trace_log:
            #print(line)
            if line.strip():
                if act.match_any(line.strip(), allowed_patterns):
                    print(line.strip())
    
        expected_trace_log = """
            FAILED EXECUTE_TRIGGER_FINISH
            ERROR AT purge_attachment
            335544382 : paramètre non trouvé
            335545016 : Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
            335544842 : At trigger 'gâchette de déconnexion'
        """
        act.expected_stdout = expected_trace_log
        act.stdout = capsys.readouterr().out
>       assert act.clean_stdout == act.clean_expected_stdout
E       assert   
E           FAILED EXECUTE_TRIGGER_FINISH
E         - ERROR AT purge_attachment
E           335544382 : paramètre non trouvé
E           335545016 : Paramètre "fréquence fermée" a une valeur incorrecte ou n'a pas été trouvé dans
E           335544842 : At trigger 'gâchette de déconnexion'

tests/functional/intl/test_non_ascii_firebird_and_trace_utf8.py:135: 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 4.0.6.3189 2025.02.25 1a1a5 P P 3649 3267 2025.06.29 16:01:33.159 2025.06.29 16:01:36.808 2025.06.29 14:46:13.476 2025.06.29 14:46:16.743
2 4.0.6.3184 2025.02.24 9388c F F 656 431 2025.02.25 13:57:14.915 2025.02.25 13:57:15.571 2025.02.25 12:36:17.415 2025.02.25 12:36:17.846
3 4.0.6.3183 2025.02.16 cf6ca F F 682 474 2025.02.17 07:51:29.518 2025.02.17 07:51:30.200 2025.02.17 06:29:54.379 2025.02.17 06:29:54.853
4 4.0.6.3169 2024.12.13 42cc1 F F 691 443 2025.02.16 04:48:24.698 2025.02.16 04:48:25.389 2025.02.16 03:29:03.798 2025.02.16 03:29:04.241
5 4.0.6.3169 2024.12.08 50eb6 F F 545 324 2024.12.12 19:03:45.390 2024.12.12 19:03:45.935 2024.12.12 17:59:56.571 2024.12.12 17:59:56.895
6 4.0.6.3168 2024.11.28 48149 F F 517 363 2024.12.06 03:24:18.397 2024.12.06 03:24:18.914 2024.12.06 02:22:41.237 2024.12.06 02:22:41.600
7 4.0.6.3163 2024.10.16 2bb10 F F 524 362 2024.11.27 13:49:14.078 2024.11.27 13:49:14.602 2024.11.27 10:46:18.965 2024.11.27 10:46:19.327
8 4.0.6.3163 2024.10.15 f387e F F 506 342 2024.10.16 04:54:17.294 2024.10.16 04:54:17.800 2024.10.16 03:55:17.687 2024.10.16 03:55:18.029
9 4.0.6.3147 2024.08.31 4655b F F 573 363 2024.09.30 12:11:01.055 2024.09.30 12:11:01.628 2024.09.30 11:05:04.513 2024.09.30 11:05:04.876

Elapsed time, ms. Chart for last 9 runs:

Last commits information (all timestamps in UTC):