Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

[BUG] CI Smoke test fail in HttpServerExtensionTwoExtensions #261

Closed
sunxilin opened this issue Nov 12, 2024 · 0 comments · Fixed by #263
Closed

[BUG] CI Smoke test fail in HttpServerExtensionTwoExtensions #261

sunxilin opened this issue Nov 12, 2024 · 0 comments · Fixed by #263
Labels
bug Something isn't working

Comments

@sunxilin
Copy link
Collaborator

Description

The full log:

[ RUN ] ExtensionTest.HttpServerExtensionTwoExtensions

  • !!! WARNING !!!
  • This is a debug build of libcurl, do not use in production.
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 0. The cache now contains 1 members
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection #0)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=0, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn #0, dead=1)
  • Closing connection 0
  • Expire cleared (transfer 0x622000061908)
    11-12 03:24:44.505 21020(21934) I [email protected]:70 [] App is created.
    11-12 03:24:44.505 21020(21933) D [email protected]:234 Wait app thread ends.
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 1. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection Bump aiohttp from 3.9.5 to 3.10.2 in /packages/example_extensions/aio_http_server_python in the pip group across 1 directory #1)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=1, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn Bump aiohttp from 3.9.5 to 3.10.2 in /packages/example_extensions/aio_http_server_python in the pip group across 1 directory #1, dead=1)
  • Closing connection 1
  • Expire cleared (transfer 0x622000061908)
    11-12 03:24:44.553 21020(21934) W ten_find_app_base_dir@base_dir.c:50 Could not get app home from module path, using TEN_APP_BASE_DIR instead.
    11-12 03:24:44.558 21020(21934) D ten_app_find_and_set_base_dir@base_dir.c:64 Failed to determine app base directory.
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 2. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection docs: update welcome page #2)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=2, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn docs: update welcome page #2, dead=1)
  • Closing connection 2
  • Expire cleared (transfer 0x622000061908)
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 3. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection chore: refine format files #3)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 1 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=3, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn chore: refine format files #3, dead=1)
  • Closing connection 3
  • Expire cleared (transfer 0x622000061908)
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 4. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection docs: wording #4)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=4, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn docs: wording #4, dead=1)
  • Closing connection 4
  • Expire cleared (transfer 0x622000061908)
    11-12 03:24:44.716 21020(21934) D [email protected]:34 The base dir of app [localhost] is not set, skip checking graph.
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 5. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection chore: update README #5)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=5, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn chore: update README #5, dead=1)
  • Closing connection 5
  • Expire cleared (transfer 0x622000061908)
    11-12 03:24:44.786 21020(21934) D ten_app_create_engine@engine_interface.c:84 [localhost] App creates an engine.
    11-12 03:24:44.791 21020(21934) D [email protected]:167 Create engine.
    11-12 03:24:44.803 21020(21934) D ten_engine_handle_cmd_start_graph@start_graph.c:49 No more extensions need to be connected in the graph, enable the extension system now.
    11-12 03:24:44.808 21020(21934) D ten_extension_context_create@extension_context.c:61 [6656f2ac-d337-4fa8-bf42-8246a72df4d7] Create Extension context.
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 6. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection fix: avoid segmentation fault if recv results from multi dest after on_stop_done #6)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=6, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn fix: avoid segmentation fault if recv results from multi dest after on_stop_done #6, dead=1)
  • Closing connection 6
  • Expire cleared (transfer 0x622000061908)
    11-12 03:24:44.813 21020(21934) D [email protected]:404 Try to find addon for default_extension_group
    11-12 03:24:44.821 21020(21935) D ten_extension_thread_main_actual@extension_thread.c:238 Extension thread is started
    11-12 03:24:44.828 21020(21935) D [email protected]:24 [test_extension_group] Load metadata.
    11-12 03:24:44.833 21020(21935) D ten_extension_group_on_init_done@on_xxx.c:74 [test_extension_group] on_init() done.
    11-12 03:24:44.839 21020(21935) I [email protected]:22 Skip the loading of manifest.json because the base_dir of test_extension_group is missing.
    11-12 03:24:44.844 21020(21935) I [email protected]:48 Skip the loading of property.json because the base_dir of test_extension_group is missing.
    11-12 03:24:44.849 21020(21935) D ten_extension_group_create_extensions@extension_group.c:174 [test_extension_group] create_extensions.
    11-12 03:24:44.854 21020(21935) D [email protected]:404 Try to find addon for simple_http_server_cpp
    11-12 03:24:44.859 21020(21935) D [email protected]:404 Try to find addon for http_server_extension_two_extensions__test_extension
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 7. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection chore: update github workflows #7)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=7, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn chore: update github workflows #7, dead=1)
  • Closing connection 7
  • Expire cleared (transfer 0x622000061908)
    11-12 03:24:44.868 21020(21935) D ten_extension_group_on_create_extensions_done@on_xxx.c:137 [test_extension_group] create_extensions() done.
    11-12 03:24:44.876 21020(21934) D ten_engine_on_extension_thread_is_ready@on_xxx.c:52 [6656f2ac-d337-4fa8-bf42-8246a72df4d7] All extension threads are initted.
    11-12 03:24:44.892 21020(21934) D ten_engine_on_extension_thread_is_ready@on_xxx.c:94 [6656f2ac-d337-4fa8-bf42-8246a72df4d7] Engine is ready to handle messages.
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 8. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection doc: general name change from astra to ten agent #8)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=8, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn doc: general name change from astra to ten agent #8, dead=1)
  • Closing connection 8
  • Expire cleared (transfer 0x622000061908)
    11-12 03:24:44.898 21020(21935) D [email protected]:922 [simple_http_server_cpp] Load metadata.
    11-12 03:24:44.918 21020(21935) D [email protected]:767 [simple_http_server_cpp] on_configure().
    11-12 03:24:44.926 21020(21935) D ten_extension_on_configure_done@on_xxx.c:87 [simple_http_server_cpp] on_configure() done.
    11-12 03:24:44.940 21020(21935) I [email protected]:313 [simple_http_server_cpp] _ten section is not found in the property, skip.
    11-12 03:24:44.944 21020(21935) D [email protected]:792 [simple_http_server_cpp] on_init().
    11-12 03:24:44.949 21020(21935) D ten_extension_on_init_done@on_xxx.c:176 [simple_http_server_cpp] on_init() done.
    11-12 03:24:44.954 21020(21935) I [email protected]:806 [simple_http_server_cpp] on_start().
  • STATE: INIT => CONNECT handle 0x622000061908; line 1951 (connection #-5000)
  • Added connection 9. The cache now contains 1 members
  • Hostname 127.0.0.1 was found in DNS cache
  • STATE: CONNECT => CONNECTING handle 0x622000061908; line 2004 (connection docs: general name change from astra to ten agent #9)
  • Trying 127.0.0.1:8001...
  • connect to 127.0.0.1 port 8001 failed: Connection refused
  • Failed to connect to 127.0.0.1 port 8001 after 0 ms: Couldn't connect to server
  • multi_done: status: 7 prem: 1 done: 0
  • multi_done, not re-using connection=9, forbid=0, close=0, premature=1, conn_multiplex=0
  • The cache now contains 0 members
  • Curl_disconnect(conn docs: general name change from astra to ten agent #9, dead=1)
  • Closing connection 9
  • Expire cleared (transfer 0x622000061908)
    [2024/11/12 03:24:44:9643] N: lws_create_context: LWS: 4.3.2-0.4.0-4-g26a9a8ed, NET CLI SRV H1 WS MbedTLS ConMon IPv6-absent
    [2024/11/12 03:24:44:9695] N: __lws_lc_tag: ++ [wsi|0|pipe] (1)
    [2024/11/12 03:24:44:9696] N: __lws_lc_tag: ++ [vh|0|netlink] (1)
    [2024/11/12 03:24:44:9696] N: __lws_lc_tag: ++ [vh|1|default||8001] (2)
    [2024/11/12 03:24:44:9697] N: [vh|1|default||8001]: lws_socket_bind: source ads 0.0.0.0
    [2024/11/12 03:24:44:9697] N: __lws_lc_tag: ++ [wsi|1|listen|default||8001] (2)
    11-12 03:24:44.970 21020(21935) I ten_extension_on_start_done@on_xxx.c:235 [simple_http_server_cpp] on_start() done.
    11-12 03:24:44.975 21020(21935) D [email protected]:922 [test_extension] Load metadata.
    11-12 03:24:44.979 21020(21935) D [email protected]:767 [test_extension] on_configure().
    11-12 03:24:44.984 21020(21935) D ten_extension_on_configure_done@on_xxx.c:87 [test_extension] on_configure() done.
    11-12 03:24:44.992 21020(21935) I [email protected]:313 [test_extension] _ten section is not found in the property, skip.
    11-12 03:24:44.997 21020(21935) D [email protected]:792 [test_extension] on_init().
    11-12 03:24:45.000 21020(21935) D ten_extension_on_init_done@on_xxx.c:176 [test_extension] on_init() done.
    11-12 03:24:45.004 21020(21935) I [email protected]:806 [test_extension] on_start().
    11-12 03:24:45.008 21020(21935) I ten_extension_on_start_done@on_xxx.c:235 [test_extension] on_start() done.
    11-12 03:24:45.014 21020(21020) E ten_test_curl_connect_with_retry@curl_connect.c:29 Failed to connect to server, error code: 7.
    Should not happen.
    11-12 03:24:46.186 21020(21020) E [email protected]:51 ../../../core/src/ten_utils/backtrace/platform/posix/linux/backtrace.c:44 ten_backtrace_dump (0x7fe1d47cb7a0)
    11-12 03:24:46.193 21020(21020) E [email protected]:51 ../../../core/src/ten_utils/backtrace/common.c:96 ten_backtrace_dump_global (0x7fe1d479a98f)
    11-12 03:24:46.203 21020(21020) E [email protected]:51 ../../../tests/common/client/http.c:141 ten_test_http_client_post (0x5651c00c1ed9)
    11-12 03:24:46.327 21020(21020) E [email protected]:51 ../../../tests/ten_runtime/smoke/extension_test/http_server_extension/http_server_extension_two_extensions.cc:117 _ZN51ExtensionTest_HttpServerExtensionTwoExtensions_Test8TestBodyEv (0x5651c04aa2e0)
    11-12 03:24:46.332 21020(21020) E [email protected]:51 (null):0 (null) (0x5651bfebc1cd)
    11-12 03:24:46.337 21020(21020) E [email protected]:51 (null):0 (null) (0x5651bfead0cf)
    11-12 03:24:46.524 21020(21020) E [email protected]:51 ../../../third_party/googletest/src/gtest.cc:2506 _ZN7testing4Test3RunEv (0x5651bfe5c337)
    11-12 03:24:46.528 21020(21020) E [email protected]:51 ../../../third_party/googletest/src/gtest.cc:2682 _ZN7testing8TestInfo3RunEv (0x5651bfe5d7e7)
    11-12 03:24:46.533 21020(21020) E [email protected]:51 ../../../third_party/googletest/src/gtest.cc:2800 _ZN7testing8TestCase3RunEv (0x5651bfe5e408)
    11-12 03:24:46.537 21020(21020) E [email protected]:51 ../../../third_party/googletest/src/gtest.cc:5124 _ZN7testing8internal12UnitTestImpl11RunAllTestsEv (0x5651bfe77d9b)
    11-12 03:24:46.542 21020(21020) E [email protected]:51 (null):0 (null) (0x5651bfebf561)
    11-12 03:24:46.547 21020(21020) E [email protected]:51 ../../../third_party/googletest/src/gtest.cc:2468 _ZN7testing8internal35HandleExceptionsInMethodIfSupportedINS0_12UnitTestImplEbEET0_PT_MS4_FS3_vEPKc (0x5651bfeaf6ec)
    11-12 03:24:46.551 21020(21020) E [email protected]:51 ../../../third_party/googletest/src/gtest.cc:4733 _ZN7testing8UnitTest3RunEv (0x5651bfe74aeb)
    11-12 03:24:46.558 21020(21020) E [email protected]:51 ../../../third_party/googletest/include/gtest/gtest.h:2331 _Z13RUN_ALL_TESTSv (0x5651bfee48a3)
    11-12 03:24:46.562 21020(21020) E [email protected]:51 ../../../third_party/googletest/src/gtest_main.cc:38 main (0x5651bfee476c)
    11-12 03:24:46.567 21020(21020) E [email protected]:51 (null):0 (null) (0x7fe1d2400d8f)
    11-12 03:24:46.571 21020(21020) E [email protected]:51 (null):0 (null) (0x7fe1d2400e3f)
    11-12 03:24:46.576 21020(21020) E [email protected]:51 (null):0 (null) (0x5651bfe15484)
    11-12 03:24:46.581 21020(21020) E [email protected]:51 (null):0 (null) (0xffffffffffffffff)
    ten_runtime_smoke_test: ../../../tests/common/client/http.c:141: ten_test_http_client_post: Assertion `0' failed.
    Aborted (core dumped)

Environment

all

Steps to reproduce

run smoke test in gcc debug

Expected behavior

pass

Actual behavior

fail

Version

0.4.0

Severity

Critical

Additional Information

No response
@sunxilin sunxilin added the bug Something isn't working label Nov 12, 2024
@sunxilin sunxilin linked a pull request Nov 12, 2024 that will close this issue
fix: increase the retry interval time & delay http request in test cases #263
Merged
@github-project-automation github-project-automation bot moved this to Done in Kanban Nov 12, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working
Projects
Kanban
Status: Done
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

fix: increase the retry interval time & delay http request in test cases TEN-framework/ten_framework
1 participant
@sunxilin