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

Annotation type Annotation details
2 @message
assert   
  - Found expected line: 1

LOG DETAILS:

2024-08-25 19:42:56.064
2024-08-25 19:42:56.075 act = <firebird.qa.plugin.Action object at [hex]>
2024-08-25 19:42:56.085 tmp_trace_cfg = PosixPath('/var/tmp/qa_2024/test_1659/tmp_trace_3658.cfg')
2024-08-25 19:42:56.100 tmp_trace_log = PosixPath('/var/tmp/qa_2024/test_1659/tmp_trace_3658.log')
2024-08-25 19:42:56.115 capsys = <_pytest.capture.CaptureFixture object at [hex]>
2024-08-25 19:42:56.130
2024-08-25 19:42:56.142 @pytest.mark.trace
2024-08-25 19:42:56.153 @pytest.mark.version('>=3')
2024-08-25 19:42:56.165 def test_1(act: Action, tmp_trace_cfg: Path, tmp_trace_log: Path, capsys):
2024-08-25 19:42:56.179 #print( os.environ.get('ISC_USER', 'UNKNOWN_ISC_USR') )
2024-08-25 19:42:56.193 #print( os.environ.get('ISC_PASSWORD', 'UNKNOWN_ISC_PSW') )
2024-08-25 19:42:56.205
2024-08-25 19:42:56.216 trace_txt = """
2024-08-25 19:42:56.225 services
2024-08-25 19:42:56.234 {
2024-08-25 19:42:56.243 enabled = true
2024-08-25 19:42:56.252 log_initfini = false
2024-08-25 19:42:56.261 log_services = true
2024-08-25 19:42:56.269 log_errors = true
2024-08-25 19:42:56.277 }
2024-08-25 19:42:56.287 """
2024-08-25 19:42:56.295
2024-08-25 19:42:56.305 tmp_trace_cfg.write_text(trace_txt)
2024-08-25 19:42:56.315
2024-08-25 19:42:56.325 with act.envar('ISC_USER', act.db.user), act.envar('ISC_PASSWORD', act.db.password):
2024-08-25 19:42:56.336
2024-08-25 19:42:56.351 with tmp_trace_log.open('w') as f_log:
2024-08-25 19:42:56.363 # EXPLICIT call of FB utility 'fbsvcmgr':
2024-08-25 19:42:56.375 p = subprocess.Popen( [act.vars['fbsvcmgr'], 'localhost:service_mgr', 'action_trace_start', 'trc_cfg', tmp_trace_cfg], stdout = f_log, stderr = subprocess.STDOUT )
2024-08-25 19:42:56.386 time.sleep(2)
2024-08-25 19:42:56.396
2024-08-25 19:42:56.407 # ::: DO NOT USE HERE :::
2024-08-25 19:42:56.417 # with act.trace(svc_events = svc_items, ...):
2024-08-25 19:42:56.426 #    pass
2024-08-25 19:42:56.440
2024-08-25 19:42:56.452 p.terminate()
2024-08-25 19:42:56.468
2024-08-25 19:42:56.480 # Windows: service_mgr, (Service 0000000000C8B140, SYSDBA, TCPv6:::1/60775, ...)
2024-08-25 19:42:56.491 # Linux:   service_mgr, (Service 0x7fc58f6073c0, SYSDBA, TCPv6:::1/35666, ...)
2024-08-25 19:42:56.507 p = re.compile('service_mgr,\\s+\\(\\s*Service\\s+\\w+[,]?\\s+' + act.db.user+ '[,]?', re.IGNORECASE)
2024-08-25 19:42:56.516
2024-08-25 19:42:56.531 expected_stdout = 'Found expected line: 1'
2024-08-25 19:42:56.544 with open(tmp_trace_log,'r') as f:
2024-08-25 19:42:56.558 for line in f:
2024-08-25 19:42:56.579 if line.strip():
2024-08-25 19:42:56.595 if p.search(line):
2024-08-25 19:42:56.609 print(expected_stdout)
2024-08-25 19:42:56.623 break
2024-08-25 19:42:56.637
2024-08-25 19:42:56.649 act.expected_stdout = expected_stdout
2024-08-25 19:42:56.661 act.stdout = capsys.readouterr().out
2024-08-25 19:42:56.672 >       assert act.clean_stdout == act.clean_expected_stdout
2024-08-25 19:42:56.683 E       assert
2024-08-25 19:42:56.692 E         - Found expected line: 1
2024-08-25 19:42:56.702
2024-08-25 19:42:56.711 tests/bugs/core_3658_test.py:89: AssertionError
2024-08-25 19:42:56.720 ---------------------------- Captured stdout setup -----------------------------
2024-08-25 19:42:56.733 Creating db: localhost:/var/tmp/qa_2024/test_1659/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]>
tmp_trace_cfg = PosixPath('/var/tmp/qa_2024/test_1659/tmp_trace_3658.cfg')
tmp_trace_log = PosixPath('/var/tmp/qa_2024/test_1659/tmp_trace_3658.log')
capsys = <_pytest.capture.CaptureFixture pytest object at [hex]>

    @pytest.mark.trace
    @pytest.mark.version('>=3')
    def test_1(act: Action, tmp_trace_cfg: Path, tmp_trace_log: Path, capsys):
        #print( os.environ.get('ISC_USER', 'UNKNOWN_ISC_USR') )
        #print( os.environ.get('ISC_PASSWORD', 'UNKNOWN_ISC_PSW') )
    
        trace_txt = """
            services
            {
                enabled = true
                log_initfini = false
                log_services = true
                log_errors = true
            }
        """
    
        tmp_trace_cfg.write_text(trace_txt)
    
        with act.envar('ISC_USER', act.db.user), act.envar('ISC_PASSWORD', act.db.password):
    
            with tmp_trace_log.open('w') as f_log:
                # EXPLICIT call of FB utility 'fbsvcmgr':
                p = subprocess.Popen( [act.vars['fbsvcmgr'], 'localhost:service_mgr', 'action_trace_start', 'trc_cfg', tmp_trace_cfg], stdout = f_log, stderr = subprocess.STDOUT )
                time.sleep(2)
    
                # ::: DO NOT USE HERE :::
                # with act.trace(svc_events = svc_items, ...):
                #    pass
    
                p.terminate()
    
        # Windows: service_mgr, (Service 0000000000C8B140, SYSDBA, TCPv6:::1/60775, ...)
        # Linux:   service_mgr, (Service 0x7fc58f6073c0, SYSDBA, TCPv6:::1/35666, ...)
        p = re.compile('service_mgr,\\s+\\(\\s*Service\\s+\\w+[,]?\\s+' + act.db.user+ '[,]?', re.IGNORECASE)
    
        expected_stdout = 'Found expected line: 1'
        with open(tmp_trace_log,'r') as f:
            for line in f:
                if line.strip():
                    if p.search(line):
                        print(expected_stdout)
                        break
    
        act.expected_stdout = expected_stdout
        act.stdout = capsys.readouterr().out
>       assert act.clean_stdout == act.clean_expected_stdout
E       assert   
E         - Found expected line: 1

tests/bugs/core_3658_test.py:89: 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 2642 2294 2025.06.30 14:37:26.372 2025.06.30 14:37:29.014 2025.06.30 13:50:58.931 2025.06.30 13:51:01.225
2 3.0.13.33808 2025.04.25 28426 P P 2788 2384 2025.05.06 13:35:05.595 2025.05.06 13:35:08.383 2025.05.06 12:40:25.421 2025.05.06 12:40:27.805
3 3.0.13.33803 2025.02.25 08ebd P P 2781 2378 2025.04.25 16:50:09.430 2025.04.25 16:50:12.211 2025.04.25 15:55:50.103 2025.04.25 15:55:52.481
4 3.0.13.33796 2025.01.29 0ab85 P P 1136 496 2025.02.25 15:22:46.266 2025.02.25 15:22:47.402 2025.02.25 14:28:50.182 2025.02.25 14:28:50.678
5 3.0.13.33796 2025.01.14 99b3b P P 1200 580 2025.01.28 17:36:27.504 2025.01.28 17:36:28.704 2025.01.28 16:41:16.040 2025.01.28 16:41:16.620
6 3.0.13.33795 2024.11.24 b8847 P P 1050 570 2025.01.13 15:14:16.191 2025.01.13 15:14:17.241 2025.01.13 14:19:40.378 2025.01.13 14:19:40.948
7 3.0.13.33794 2024.10.15 abe09 P P 808 392 2024.11.23 06:23:46.566 2024.11.23 06:23:47.374 2024.11.23 05:42:23.578 2024.11.23 05:42:23.970
8 3.0.13.33793 2024.10.04 82ccc P P 976 463 2024.10.14 04:18:39.847 2024.10.14 04:18:40.823 2024.10.14 03:33:36.175 2024.10.14 03:33:36.638
9 3.0.13.33792 2024.08.31 d8791 P P 1137 432 2024.10.03 09:24:39.767 2024.10.03 09:24:40.904 2024.10.03 08:36:31.628 2024.10.03 08:36:32.060
10 3.0.13.33787 2024.08.17 2e0d6 F P 2431 852 2024.08.25 15:00:24.133 2024.08.25 15:00:26.564 2024.08.25 13:15:36.499 2024.08.25 13:15:37.351
11 3.0.13.33787 2024.08.09 df740 P P 1259 577 2024.08.16 12:38:31.854 2024.08.16 12:38:33.113 2024.08.16 11:44:20.692 2024.08.16 11:44:21.269
12 3.0.12.33746 2024.07.15 11dd4 P P 1597 674 2024.08.09 12:35:31.937 2024.08.09 12:35:33.534 2024.08.09 11:39:18.340 2024.08.09 11:39:19.014
13 3.0.12.33746 2024.06.11 8a5eb P P 875 413 2024.07.15 10:28:00.392 2024.07.15 10:28:01.267 2024.07.15 09:41:44.811 2024.07.15 09:41:45.224
14 3.0.12.33744 2024.05.09 9cf37 P P 776 376 2024.05.12 15:36:13.587 2024.05.12 15:36:14.363 2024.05.12 14:54:08.767 2024.05.12 14:54:09.143
15 3.0.12.33744 2024.04.20 af6b2 P P 806 330 2024.05.08 16:51:56.486 2024.05.08 16:51:57.292 2024.05.08 16:12:05.544 2024.05.08 16:12:05.874

Elapsed time, ms. Chart for last 15 runs:

Last commits information (all timestamps in UTC):