Skip to content

Blink: tests.cache.test_conditional.TestConditionalHttp.test_response_without_last_modified_and_date #976

@RomanBelozerov

Description

@RomanBelozerov

Test setup
Only remote setup

Python output

FAIL: tests.cache.test_conditional.TestConditionalHttp.test_response_without_last_modified_and_date
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/usr/lib/python3.12/unittest/async_case.py", line 90, in _callTestMethod
    if self._callMaybeAsync(method) is not None:
       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.12/unittest/async_case.py", line 112, in _callMaybeAsync
    return self._asyncioRunner.run(
           ^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.12/asyncio/runners.py", line 118, in run
    return self._loop.run_until_complete(task)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.12/asyncio/base_events.py", line 687, in run_until_complete
    return future.result()
           ^^^^^^^^^^^^^^^
  File "/home/jenkins/workspace/_PR_tempesta-test_PR-971/tempesta-test/venv/lib/python3.12/site-packages/parameterized/parameterized.py", line 616, in standalone_func
    return await func(*(a + p.args), **p.kwargs, **kw)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/jenkins/workspace/_PR_tempesta-test_PR-971/tempesta-test/tests/cache/test_conditional.py", line 355, in test_response_without
    await client.send_request(
  File "/home/jenkins/workspace/_PR_tempesta-test_PR-971/tempesta-test/framework/deproxy/deproxy_client.py", line 293, in send_request
    assert expected_status_code in self.last_response.status, (
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
AssertionError: HTTP response status codes mismatch. Expected - 304. Received - 200

Version or commit hash
Version of the build or hash of the git commit.
tempesta-test - 602a11e
tempesta - 0205834d0c9473cff16389379f49bf5332a738cd

Test log

Start test 'tests.cache.test_conditional.TestConditionalHttp.test_response_without_last_modified_and_date'
----------------------------------------------------------------------------------------------------
15:54:42.451 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'echo "Start test: tests.cache.test_conditional.TestConditionalHttp.test_response_without_last_modified_and_date" > /dev/kmsg'
15:54:42.502 |     INFO |       test |              tester | 386 |                                         | setUp 'tests.cache.test_conditional.TestConditionalHttp.test_response_without_last_modified_and_date'
15:54:42.502 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'echo -n check'
15:54:42.553 |     INFO |        env |              remote | 486 |             RemoteNode(192.168.122.191) | 'echo -n check'
15:54:42.565 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'date +%s.%N'
15:54:42.606 |     INFO |       test |              tester | 338 |                                         | Creating Tempesta service...
15:54:42.606 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'mkdir -p /tmp/tempesta'
15:54:42.658 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'mkdir -p /tmp/tempesta'
15:54:42.710 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'mkdir -p /tmp/tempesta'
15:54:42.849 |     INFO |       test |              tester | 261 |                                         | Creating backend service with ID='deproxy' and TYPE='deproxy'.
15:54:42.849 |     INFO |       test |              tester | 233 |                                         | Creating client service with ID='deproxy' and TYPE='deproxy'.
15:54:42.851 |     INFO |       test |              tester | 415 |                                         | setUp completed 'tests.cache.test_conditional.TestConditionalHttp.test_response_without_last_modified_and_date'
15:54:42.853 |     INFO |    service |            stateful | 076 | StaticDeproxyServer(192.168.122.187:8000) | Starting...
15:54:42.854 |     INFO |        tcp |        deproxy_base | 069 | StaticDeproxyServer(192.168.122.187:8000) | Trying to bind ('192.168.122.187', 8000) for StaticDeproxyServer
15:54:42.854 |     INFO |    service |            stateful | 083 | StaticDeproxyServer(192.168.122.187:8000) | Start completed
15:54:42.854 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'date +%s.%N'
15:54:42.901 |     INFO |    service |            stateful | 076 |                Tempesta(192.168.122.93) | Starting...
15:54:42.902 |     INFO |    service |            tempesta | 474 |                Tempesta(192.168.122.93) | Tempesta config content:

listen 80;
listen 443 proto=h2;

server 192.168.122.187:8000;

tls_certificate /tmp/tempesta/tempesta.crt;
tls_certificate_key /tmp/tempesta/tempesta.key;
tls_match_any_server_name;

cache 2;
cache_fulfill * *;
cache_methods GET HEAD POST;

15:54:42.959 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'env TFW_CFG_PATH='/tmp/tempesta/tempesta.conf' TFW_CFG_TMPL='/tmp/tempesta/tempesta_tmp.conf' /root/tempesta/scripts/tempesta.sh --time-output --start'
15:54:43.833 |     INFO |    service |            stateful | 083 |                Tempesta(192.168.122.93) | Start completed
15:54:43.833 |     INFO |       test |               dmesg | 162 |                                         | Finding pattern '\[tempesta\ fw\]\ Tempesta\ FW\ is\ ready' in dmesg.
15:54:43.833 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'journalctl -k -o cat --since=@1773849283.185433'
15:54:43.908 |     INFO |    service |            stateful | 076 |                        DeproxyManager() | Starting...
15:54:43.910 |     INFO |    service |            stateful | 083 |                        DeproxyManager() | Start completed
15:54:43.923 |     INFO |    service |            stateful | 076 |       DeproxyClient(192.168.122.187:80) | Starting...
15:54:43.923 |     INFO |        tcp |        deproxy_base | 069 |       DeproxyClient(192.168.122.187:80) | Trying to bind ('192.168.122.187', 0) for DeproxyClient
15:54:43.924 |     INFO |        tcp |      deproxy_client | 262 |       DeproxyClient(192.168.122.187:80) | Trying to connect to 192.168.122.93:80.
15:54:43.924 |     INFO |    service |            stateful | 083 |       DeproxyClient(192.168.122.187:80) | Start completed
15:54:43.925 |     INFO |        dap | deproxy_auto_parser | 132 |                     DeproxyAutoParser() | Prepare expected request
15:54:43.925 |     INFO |        dap | deproxy_auto_parser | 247 |                     DeproxyAutoParser() | Remove hop-by-hop headers from expected response/request
15:54:43.926 |     INFO |       http |      deproxy_client | 186 |       DeproxyClient(192.168.122.187:80) | A request was send. The current number of a request - 1
15:54:43.927 |     INFO |       http |      deproxy_server | 116 |  ServerConnection(192.168.122.93:35858) | Receive request
15:54:43.927 |     INFO |       http |      deproxy_server | 300 | StaticDeproxyServer(192.168.122.187:8000) | A request was receive. The current number of requests - 1
15:54:43.927 |     INFO |        dap | deproxy_auto_parser | 078 |                     DeproxyAutoParser() | Check expected request.
15:54:43.927 |     INFO |        dap | deproxy_auto_parser | 090 |                     DeproxyAutoParser() | Received request is valid.
15:54:43.927 |     INFO |        dap | deproxy_auto_parser | 155 |                     DeproxyAutoParser() | Prepare expected response
15:54:43.929 |     INFO |       http |      deproxy_server | 150 |  ServerConnection(192.168.122.93:35858) | A response was send. The current number of a response - 1
15:54:43.929 |     INFO |       http |      deproxy_client | 360 |       DeproxyClient(192.168.122.187:80) | A response was receive. The response status=200. The current number of responses - 1.
15:54:43.929 |     INFO |        dap | deproxy_auto_parser | 110 |                     DeproxyAutoParser() | Check expected response
15:54:43.930 |     INFO |        dap | deproxy_auto_parser | 247 |                     DeproxyAutoParser() | Remove hop-by-hop headers from expected response/request
15:54:43.930 |     INFO |        dap | deproxy_auto_parser | 125 |                     DeproxyAutoParser() | Received response is valid.
15:54:43.931 |     INFO |        dap | deproxy_auto_parser | 132 |                     DeproxyAutoParser() | Prepare expected request
15:54:43.931 |     INFO |        dap | deproxy_auto_parser | 247 |                     DeproxyAutoParser() | Remove hop-by-hop headers from expected response/request
15:54:43.932 |     INFO |       http |      deproxy_client | 186 |       DeproxyClient(192.168.122.187:80) | A request was send. The current number of a request - 2
15:54:43.933 |     INFO |       http |      deproxy_client | 360 |       DeproxyClient(192.168.122.187:80) | A response was receive. The response status=200. The current number of responses - 2.
15:54:43.933 |     INFO |        dap | deproxy_auto_parser | 110 |                     DeproxyAutoParser() | Check expected response
15:54:43.933 |     INFO |        dap | deproxy_auto_parser | 247 |                     DeproxyAutoParser() | Remove hop-by-hop headers from expected response/request
15:54:43.933 |     INFO |        dap | deproxy_auto_parser | 125 |                     DeproxyAutoParser() | Received response is valid.
15:54:43.942 |     INFO |       test |              tester | 418 |                                         | Cleanup: stopping all services...
15:54:43.942 |     INFO |    service |            stateful | 100 |       DeproxyClient(192.168.122.187:80) | Stopping...
15:54:43.942 |     INFO |    service |            stateful | 088 |       DeproxyClient(192.168.122.187:80) | Stop procedures list: ['__stop']
15:54:43.942 |     INFO |        tcp |      deproxy_client | 213 |       DeproxyClient(192.168.122.187:80) | Close with errno=0
15:54:43.943 |     INFO |    service |            stateful | 105 |       DeproxyClient(192.168.122.187:80) | Stop completed
15:54:43.943 |     INFO |    service |            stateful | 100 |                Tempesta(192.168.122.93) | Stopping...
15:54:43.943 |     INFO |    service |            stateful | 088 |                Tempesta(192.168.122.93) | Stop procedures list: ['stop_tempesta', 'remove_config_files', 'drop_access_log_table']
15:54:43.943 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | '/root/tempesta/scripts/tempesta.sh --time-output --stop'
15:54:44.579 |     INFO |    service |            stateful | 105 |                Tempesta(192.168.122.93) | Stop completed
15:54:44.580 |     INFO |    service |            stateful | 100 | StaticDeproxyServer(192.168.122.187:8000) | Stopping...
15:54:44.580 |     INFO |    service |            stateful | 088 | StaticDeproxyServer(192.168.122.187:8000) | Stop procedures list: ['__stop']
15:54:44.580 |     INFO |    service |            stateful | 105 | StaticDeproxyServer(192.168.122.187:8000) | Stop completed
15:54:44.580 |     INFO |    service |            stateful | 100 |                        DeproxyManager() | Stopping...
15:54:44.580 |     INFO |    service |            stateful | 088 |                        DeproxyManager() | Stop procedures list: ['__stop']
15:54:44.581 |     INFO |    service |            stateful | 105 |                        DeproxyManager() | Stop completed
15:54:44.581 |     INFO |       test |              tester | 429 |                                         | Cleanup: all services are stopped.
15:54:44.584 |     INFO |       test |              tester | 435 |                                         | Cleanup: finish all deproxy sockets...
15:54:44.584 |     INFO |       test |              tester | 441 |                                         | Cleanup: all deproxy sockets are closed.
15:54:44.584 |     INFO |       test |              tester | 444 |                                         | Cleanup: Removing interfaces
15:54:44.584 |     INFO |        env |              remote | 234 |                    LocalNode(localhost) | 'LANG=C ip route get to 192.168.122.93 | grep -o 'dev [a-zA-Z0-9_-]*''
15:54:44.590 |     INFO |        env |              remote | 234 |                    LocalNode(localhost) | 'ip -6 addr show dev enp1s0'
15:54:44.595 |     INFO |        env |              remote | 234 |                    LocalNode(localhost) | 'LANG=C ip addr show enp1s0 |grep -o 'mtu [0-9]*''
15:54:44.599 |     INFO |        env |              remote | 234 |                    LocalNode(localhost) | 'sysctl --values net.ipv4.route.mtu_expires'
15:54:44.603 |     INFO |        env |              remote | 234 |                    LocalNode(localhost) | 'sysctl --values net.ipv4.ip_no_pmtu_disc'
15:54:44.607 |     INFO |        env |              remote | 234 |                    LocalNode(localhost) | 'ethtool --show-features enp1s0 | grep tcp-segmentation-offload'
15:54:44.612 |     INFO |        env |              remote | 234 |                    LocalNode(localhost) | 'ethtool --show-features enp1s0 | grep generic-receive-offload'
15:54:44.618 |     INFO |        env |              remote | 234 |                    LocalNode(localhost) | 'ethtool --show-features enp1s0 | grep generic-segmentation-offload'
15:54:44.624 |     INFO |       test |              tester | 451 |                                         | Cleanup: stopping tcpdump
15:54:44.625 |     INFO |       test |              tester | 455 |                                         | Cleanup: checking dmesg
15:54:44.625 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'journalctl -k -o cat --since=@1773849282.889394'
15:54:44.654 |     INFO |       test |              tester | 458 |                                         | 
----------------------dmesg---------------------
[tempesta sc] OS configuration or hardware is not suitable/ (Total 1 errors)
[tempesta sc] 	1. The minimal amount of RAM required by Tempesta is 8192 MB, but you have 7433 MB
[tempesta sc] Kernel config params are missing or have different values. Please, check configuration of `/boot/config-6.12.12.tfw-4ef6f26`/ (Total 2 errors)
[tempesta sc] 	1. `CONFIG_BOOTPARAM_SOFTLOCKUP_PANIC_VALUE={'1'}` is not defined.
[tempesta sc] 	2. `CONFIG_BOOTPARAM_HARDLOCKUP_PANIC_VALUE={'1'}` is not defined.
[tempesta sc] Sysctl config params are missing or have different values. Please, check configuration of `/etc/sysctl.conf`/ (Total 1 errors)
[tempesta sc] 	1. `kernel.softlockup_panic={'1'}` is not defined.
[tempesta sc] Verification passed with errors!
[tempesta sc] Skipped Netconsole configuration and Support Server connection
[tdb] Start Tempesta DB
[tempesta fw] Initializing Tempesta FW kernel module...
[tempesta fw] Warning: Vhost default doesn't have certificate with matching SAN/CN.
    Maybe that's fine, but it's worth checking the
    config - if there is no relations between the
    names, then host name confusion attack is possible.
[tempesta fw] Configuration processing is completed.
[tdb] Opened table /opt/tempesta/db/filter0.tdb: size=16777216 rec_size=32 base=00000000f7eb146b
[tdb] Opened table /opt/tempesta/db/cache0.tdb: size=268435456 rec_size=0 base=000000002965e121
[tdb] Opened table /opt/tempesta/db/sessions0.tdb: size=16777216 rec_size=312 base=00000000b4407373
[tdb] Opened table /opt/tempesta/db/client0.tdb: size=16777216 rec_size=624 base=000000001606a62a
[tempesta fw] Open listen socket on: 0.0.0.0:443
[tempesta fw] Open listen socket on: 0.0.0.0
[tempesta fw] Tempesta FW is ready
[tdb] Close table 'client0.tdb'
[tdb] Close table 'sessions0.tdb'
[tdb] Close table 'cache0.tdb'
[tdb] Close table 'filter0.tdb'
[tempesta fw] modules are stopped
[tempesta fw] exiting...
[tdb] Shutdown Tempesta DB
-------------------end dmesg--------------------
15:54:44.656 |     INFO |       test |              tester | 482 |                                         | Cleanup: Check exceptions in the deproxy auto parser.
15:54:44.657 |     INFO |       test |              tester | 477 |                                         | Cleanup: Cleanup the deproxy auto parser.
15:54:44.659 |     INFO |        env |              remote | 486 |              RemoteNode(192.168.122.93) | 'echo "End test:   tests.cache.test_conditional.TestConditionalHttp.test_response_without_last_modified_and_date" > /dev/kmsg'
15:54:44.705 |     INFO |       test |               shell | 188 |                                         | 

----------------------------------------------------------------------------------------------------
End test 'tests.cache.test_conditional.TestConditionalHttp.test_response_without_last_modified_and_date'

Metadata

Metadata

Assignees

No one assigned

    Labels

    CIgood to startStart form this tasks if you're new in the frameworkstability

    Type

    No type

    Projects

    No projects

    Milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions