We read every piece of feedback, and take your input very seriously.
To see all available qualifiers, see our documentation.
Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.
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
OS: MacOS arm64 Compiler: AppleClang
MacOS arm64
AppleClang
Install userver
userver
cmake -S./ -B./build_debug \ -DCMAKE_BUILD_TYPE=Debug -DUSERVER_FEATURE_UBOOST_CORO=OFF \ -DUSERVER_INSTALL=ON \ -DUSERVER_SANITIZE="ub addr" \ -DUSERVER_FEATURE_GRPC=ON \ -DUSERVER_FEATURE_POSTGRESQL=ON \ -DUSERVER_PG_LIBRARY_DIR=$(pg_config --libdir) -DUSERVER_PG_INCLUDE_DIR=$(pg_config --includedir) \ -DUSERVER_PG_SERVER_LIBRARY_DIR=$(pg_config --pkglibdir) -DUSERVER_PG_SERVER_INCLUDE_DIR=$(pg_config --includedir-server) \ -GNinja cmake --build build_debug/ sudo cmake --install build_debug/
Run pg_grpc_service_template make test-debug
pg_grpc_service_template
make test-debug
cmake -B build_debug -DCMAKE_BUILD_TYPE=Debug -DCMAKE_EXPORT_COMPILE_COMMANDS=ON -DUSERVER_PG_LIBRARY_DIR=/opt/homebrew/lib/postgresql@14 -DUSERVER_PG_INCLUDE_DIR=/opt/homebrew/include/postgresql@14 -DUSERVER_PG_SERVER_LIBRARY_DIR=/opt/homebrew/lib/postgresql@14 -DUSERVER_PG_SERVER_INCLUDE_DIR=/opt/homebrew/include/postgresql@14/server -DUSERVER_FEATURE_UBOOST_CORO=OFF -- Userver components: core;grpc;postgresql -- Found Boost: /opt/homebrew/lib/cmake/Boost-1.85.0/BoostConfig.cmake (found version "1.85.0") found components: program_options filesystem regex stacktrace_basic context missing components: stacktrace_backtrace -- cctz include directories: /opt/homebrew/include -- cctz libraries: /opt/homebrew/lib/libcctz.dylib -- CryptoPP include directories: /opt/homebrew/include -- CryptoPP libraries: /opt/homebrew/lib/libcryptopp.dylib -- libyamlcpp include directories: /opt/homebrew/include -- libyamlcpp libraries: /opt/homebrew/lib/libyaml-cpp.dylib -- LTO: disabled (user request) -- Using the default system linker, probably GNU ld -- Attempting to include userver-core-config.cmake -- Found Boost: /opt/homebrew/lib/cmake/Boost-1.85.0/BoostConfig.cmake (found version "1.85.0") found components: locale iostreams -- Nghttp2 include directories: /opt/homebrew/include -- Nghttp2 libraries: /opt/homebrew/lib/libnghttp2.dylib -- LibEv include directories: /opt/homebrew/include -- LibEv libraries: /opt/homebrew/lib/libev.dylib -- UserverGTest include directories: /opt/homebrew/include;/opt/homebrew/include -- UserverGTest libraries: /opt/homebrew/lib/libgtest.a;/opt/homebrew/lib/libgtest_main.a;/opt/homebrew/lib/libgmock.a -- UserverGBench include directories: /opt/homebrew/include -- UserverGBench libraries: /opt/homebrew/lib/libbenchmark_main.a;/opt/homebrew/lib/libbenchmark.a -- Python: python3 CMake Warning (dev) at /opt/homebrew/Cellar/cmake/3.29.2/share/cmake/Modules/FindPackageHandleStandardArgs.cmake:438 (message): The package name passed to `find_package_handle_standard_args` (c-ares) does not match the name of the calling package (userver). This can lead to problems in calling code that expects `find_package` result variables (e.g., `_FOUND`) to follow a certain pattern. Call Stack (most recent call first): /usr/local/lib/cmake/userver/Findc-ares.cmake:70 (find_package_handle_standard_args) /usr/local/lib/cmake/userver/userver-core-config.cmake:22 (include) /usr/local/lib/cmake/userver/userverConfig.cmake:54 (include) CMakeLists.txt:5 (find_package) This warning is for project developers. Use -Wno-dev to suppress it. -- c-ares include directories: /opt/homebrew/include -- c-ares libraries: /opt/homebrew/lib/libcares.dylib -- Attempting to include userver-grpc-config.cmake -- Found RE2 via CMake. -- Protobuf version: 26.1.0 -- gRPC version: 1.62.2 -- Setting up the venv at /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/venv-userver-grpc -- Attempting to include userver-postgresql-config.cmake -- PostgreSQLInternal include directories: /opt/homebrew/include/postgresql@14/server;/opt/homebrew/include/postgresql@14/internal;/opt/homebrew/include/postgresql@14 -- PostgreSQLInternal libraries: /opt/homebrew/lib/postgresql@14/libpq.a;/opt/homebrew/lib/postgresql@14/libpgcommon.a;/opt/homebrew/lib/postgresql@14/libpgport.a -- GssApi include directories: /Library/Developer/CommandLineTools/SDKs/MacOSX14.4.sdk/System/Library/Frameworks/GSS.framework/Headers -- GssApi libraries: /Library/Developer/CommandLineTools/SDKs/MacOSX14.4.sdk/usr/lib/libgssapi_krb5.tbd -- C compiler: -- C++ compiler: /Library/Developer/CommandLineTools/usr/bin/c++ -- C++ standard 17 -- Using the default system linker, probably GNU ld -- LTO: disabled (user request) -- ccache: enabled, but not found -- Reused previously generated sources for protos in /Users/nikitatabakaev/Downloads/pg_grpc_service_template/proto -- Setting up the venv at /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/venv-testsuite-pg_grpc_service_template -- Configuring done (0.2s) -- Generating done (0.0s) -- Build files have been written to: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug make: nproc: Command not found cmake --build build_debug -j --target pg_grpc_service_template [ 55%] Built target pg_grpc_service_template_proto [ 77%] Built target pg_grpc_service_template_objs [100%] Built target pg_grpc_service_template make: nproc: Command not found make: nproc: Command not found cmake --build build_debug -j --target pg_grpc_service_template_unittest [ 55%] Built target pg_grpc_service_template_proto [ 77%] Built target pg_grpc_service_template_objs [100%] Built target pg_grpc_service_template_unittest cmake --build build_debug -j --target pg_grpc_service_template_benchmark [ 55%] Built target pg_grpc_service_template_proto [ 77%] Built target pg_grpc_service_template_objs [100%] Built target pg_grpc_service_template_benchmark cd build_debug && ((test -t 1 && GTEST_COLOR=1 PYTEST_ADDOPTS="--color=yes" ctest -V) || ctest -V) UpdateCTestConfiguration from :/Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/DartConfiguration.tcl Parse Config file:/Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/DartConfiguration.tcl UpdateCTestConfiguration from :/Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/DartConfiguration.tcl Parse Config file:/Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/DartConfiguration.tcl Test project /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug Constructing a list of tests Done constructing a list of tests Updating test list for fixtures Added 0 tests to meet fixture requirements Checking test dependency graph... Checking test dependency graph end test 1 Start 1: pg_grpc_service_template_unittest 1: Test command: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/pg_grpc_service_template_unittest "--gtest_output=xml:/Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/test-results/pg_grpc_service_template_unittest.xml" 1: Working Directory: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug 1: Test timeout computed to be: 1500 1: [==========] Running 1 test from 1 test suite. 1: [----------] Global test environment set-up. 1: [----------] 1 test from SayHelloTo 1: [ RUN ] SayHelloTo.Basic 1: tskv timestamp=2024-05-04T17:29:15.464355 level=NONE module=AcquireEvDefaultLoop ( userver/core/src/engine/ev/thread.cpp:51 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=Acquire ev_default_loop for thread_name=ev_0 1: tskv timestamp=2024-05-04T17:29:15.464659 level=NONE module=TaskProcessor ( userver/core/src/engine/task/task_processor.cpp:76 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=creating task_processor worker_threads=1 thread_name=coro-runner 1: tskv timestamp=2024-05-04T17:29:15.464743 level=NONE module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=task with task_id=0 created task with task_id=13C607C30 1: tskv timestamp=2024-05-04T17:29:15.464772 level=NONE module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=task with task_id=0 created task with task_id=13C6081D0 1: tskv timestamp=2024-05-04T17:29:15.464813 level=NONE module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=0 thread_id=0x000000016F457000 text=Task with task_id=13C6081D0 stopped 1: tskv timestamp=2024-05-04T17:29:15.464819 level=NONE module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=Task with task_id=13C607C30 stopped 1: tskv timestamp=2024-05-04T17:29:15.464870 level=NONE module=ReleaseEvDefaultLoop ( userver/core/src/engine/ev/thread.cpp:56 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=Release ev_default_loop 1/3 Test #1: pg_grpc_service_template_unittest ....Bus error***Exception: 0.05 sec test 2 Start 2: pg_grpc_service_template_benchmark 2: Test command: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/pg_grpc_service_template_benchmark "--benchmark_min_time=0" "--benchmark_color=no" 2: Working Directory: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug 2: Test timeout computed to be: 1500 2: Unable to determine clock rate from sysctl: hw.cpufrequency: No such file or directory 2: This does not affect benchmark measurements, only the metadata output. 2: ***WARNING*** Failed to set thread affinity. Estimated CPU frequency may be incorrect. 2: 2024-05-04T17:29:16+07:00 2: Running /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/pg_grpc_service_template_benchmark 2: Run on (8 X 24 MHz CPU s) 2: CPU Caches: 2: L1 Data 64 KiB 2: L1 Instruction 128 KiB 2: L2 Unified 4096 KiB (x8) 2: Load Average: 2.66, 2.11, 2.26 2: -- LOG(0): Value passed to --benchmark_min_time should have a suffix. Eg., `30s` for 30-seconds. 2/3 Test #2: pg_grpc_service_template_benchmark ...Bus error***Exception: 1.05 sec test 3 Start 3: testsuite-pg_grpc_service_template 3: Test command: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/runtests-testsuite-pg_grpc_service_template "--service-logs-pretty" "-vv" "/Users/nikitatabakaev/Downloads/pg_grpc_service_template/tests" 3: Working Directory: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug 3: Test timeout computed to be: 1500 3: ============================= test session starts ============================== 3: platform darwin -- Python 3.12.3, pytest-8.2.0, pluggy-1.5.0 -- /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/venv-testsuite-pg_grpc_service_template/bin/python 3: cachedir: .pytest_cache 3: testsuite env: new, dir: /private/tmp/.yasuite-nikitatabakaev 3: rootdir: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/tests 3: configfile: pytest.ini 3: plugins: aiohttp-1.0.5, asyncio-0.21.2 3: asyncio: mode=Mode.AUTO 3: collecting ... collected 4 items 3: 3: ../tests/test_hello.py::test_grpc_client PASSED [ 25%] 3: ../tests/test_hello.py::test_first_time_users PASSED [ 50%] 3: ../tests/test_hello.py::test_db_updates PASSED [ 75%] 3: ../tests/test_hello.py::test_db_initial_data PASSED [100%] 3: ../tests/test_hello.py::test_db_initial_data ERROR [100%] 3: INFO Stopping postgresql services... 3: INFO Service postgresql stopped. 3: 3: 3: ==================================== ERRORS ==================================== 3: __________________ ERROR at teardown of test_db_initial_data ___________________ 3: 3: def finalizer() -> None: 3: """Yield again, to finalize.""" 3: 3: async def async_finalizer() -> None: 3: try: 3: await gen_obj.__anext__() 3: except StopAsyncIteration: 3: pass 3: else: 3: msg = "Async generator fixture didn't stop." 3: msg += "Yield only once." 3: raise ValueError(msg) 3: 3: > event_loop.run_until_complete(async_finalizer()) 3: 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/pytest_asyncio/plugin.py:300: 3: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 3: uvloop/loop.pyx:1517: in uvloop.loop.Loop.run_until_complete 3: ??? 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/pytest_asyncio/plugin.py:292: in async_finalizer 3: await gen_obj.__anext__() 3: /usr/local/lib/cmake/userver/testsuite/pytest_plugins/pytest_userver/plugins/service.py:187: in service_daemon 3: async with create_daemon_scope( 3: /opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/contextlib.py:217: in __aexit__ 3: await anext(self.gen) 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/pytest_plugin.py:79: in scope 3: await self._close_daemon(daemon) 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/pytest_plugin.py:101: in _close_daemon 3: await daemon.aclose() 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/pytest_plugin.py:56: in aclose 3: await self._daemon.__aexit__(None, None, None) 3: /opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/contextlib.py:217: in __aexit__ 3: await anext(self.gen) 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/service_daemon.py:49: in start 3: async with _service_daemon( 3: /opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/contextlib.py:217: in __aexit__ 3: await anext(self.gen) 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/service_daemon.py:225: in _service_daemon 3: async with spawn.spawned( 3: /opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/contextlib.py:217: in __aexit__ 3: await anext(self.gen) 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/spawn.py:102: in spawned 3: async with _shutdown_service( 3: /opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/contextlib.py:217: in __aexit__ 3: await anext(self.gen) 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/spawn.py:129: in _shutdown_service 3: await _do_service_shutdown(*args, **kwargs) 3: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 3: 3: process = <Popen: returncode: -10 args: ('/Users/nikitatabakaev/Downloads/pg_grpc_serv...> 3: 3: async def _do_service_shutdown(process, *, shutdown_signal, shutdown_timeout): 3: allowed_exit_codes = (-shutdown_signal, 0) 3: 3: retcode = process.poll() 3: if retcode is not None: 3: logger.info( 3: '[%d] Process already finished with code %d', process.pid, retcode 3: ) 3: if retcode not in allowed_exit_codes: 3: raise exit_code_error(retcode) 3: return retcode 3: 3: try: 3: process.send_signal(shutdown_signal) 3: except OSError: 3: pass 3: else: 3: logger.info( 3: '[%d] Trying to stop process with signal %s', 3: process.pid, 3: _pretty_signal(shutdown_signal), 3: ) 3: poll_start = time.monotonic() 3: while True: 3: retcode = process.poll() 3: if retcode is not None: 3: if retcode not in allowed_exit_codes: 3: > raise exit_code_error(retcode) 3: E testsuite.daemons.spawn.ExitCodeError: Service terminated by SIGBUS signal 3: 3: venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/spawn.py:159: ExitCodeError 3: ---------------------------- Captured stderr setup ----------------------------- 3: DEBUG userver fixture "auto_client_deps" resolved dependencies ['pgsql'] 3: ------------------------------ Captured log setup ------------------------------ 3: DEBUG pytest_userver.plugins.service_client:service_client.py:72 userver fixture "auto_client_deps" resolved dependencies ['pgsql'] 3: --------------------------- Captured stderr teardown --------------------------- 3: INFO [45247] Trying to stop process with signal SIGINT 3: DEBUG Using selector: KqueueSelector 3: ---------------------------- Captured log teardown ----------------------------- 3: INFO testsuite.daemons.spawn:spawn.py:149 [45247] Trying to stop process with signal SIGINT 3: DEBUG asyncio:selector_events.py:64 Using selector: KqueueSelector 3: =============================== warnings summary =============================== 3: <frozen importlib._bootstrap>:488 3: <frozen importlib._bootstrap>:488: DeprecationWarning: Type google._upb._message.MessageMapContainer uses PyType_Spec with a metaclass that has custom tp_new. This is deprecated and will no longer be allowed in Python 3.14. 3: 3: <frozen importlib._bootstrap>:488 3: <frozen importlib._bootstrap>:488: DeprecationWarning: Type google._upb._message.ScalarMapContainer uses PyType_Spec with a metaclass that has custom tp_new. This is deprecated and will no longer be allowed in Python 3.14. 3: 3: -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 3: =========================== short test summary info ============================ 3: ERROR ../tests/test_hello.py::test_db_initial_data - testsuite.daemons.spawn.ExitCodeError: Service terminated by SIGBUS signal 3: ==================== 4 passed, 2 warnings, 1 error in 1.38s ==================== 3: ERROR Task exception was never retrieved 3: future: <Task finished name='Task-15' coro=<AioReaders.add.<locals>.data_handler() done, defined at /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/spawn.py:61> exception=ValueError('Separator is found, but chunk is longer than limit')> 3: Traceback (most recent call last): 3: File "/opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/asyncio/streams.py", line 568, in readline 3: line = await self.readuntil(sep) 3: ^^^^^^^^^^^^^^^^^^^^^^^^^ 3: File "/opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/asyncio/streams.py", line 663, in readuntil 3: raise exceptions.LimitOverrunError( 3: asyncio.exceptions.LimitOverrunError: Separator is found, but chunk is longer than limit 3: 3: During handling of the above exception, another exception occurred: 3: 3: Traceback (most recent call last): 3: File "/Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/venv-testsuite-pg_grpc_service_template/lib/python3.12/site-packages/testsuite/daemons/spawn.py", line 62, in data_handler 3: async for line in reader: 3: File "/opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/asyncio/streams.py", line 767, in __anext__ 3: val = await self.readline() 3: ^^^^^^^^^^^^^^^^^^^^^ 3: File "/opt/homebrew/Cellar/[email protected]/3.12.3/Frameworks/Python.framework/Versions/3.12/lib/python3.12/asyncio/streams.py", line 577, in readline 3: raise ValueError(e.args[0]) 3: ValueError: Separator is found, but chunk is longer than limit 3/3 Test #3: testsuite-pg_grpc_service_template ...***Failed 1.64 sec 0% tests passed, 3 tests failed out of 3 Total Test time (real) = 2.74 sec The following tests FAILED: 1 - pg_grpc_service_template_unittest (Bus error) 2 - pg_grpc_service_template_benchmark (Bus error) 3 - testsuite-pg_grpc_service_template (Failed) Errors while running CTest Output from these tests are in: /Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/Testing/Temporary/LastTest.log Use "--rerun-failed --output-on-failure" to re-run the failed cases verbosely. make: *** [test-debug] Error 8
lldb ./pg_grpc_service_template -- -c ../configs/static_config.yaml --config_vars ../configs/config_vars.yaml
(lldb) target create "./pg_grpc_service_template" Current executable set to '/Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/pg_grpc_service_template' (arm64). (lldb) settings set -- target.run-args "-c" "../configs/static_config.yaml" "--config_vars" "../configs/config_vars.yaml" Process 45420 launched: '/Users/nikitatabakaev/Downloads/pg_grpc_service_template/build_debug/pg_grpc_service_template' (arm64) tskv timestamp=2024-05-04T17:33:53.135430 level=INFO module=ParseManagerConfigAndSetupLogging ( userver/core/src/components/run.cpp:177 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=Parsed configs from file '../configs/static_config.yaml' using config_vars from cmdline in file '../configs/config_vars.yaml' tskv timestamp=2024-05-04T17:33:53.136062 level=WARNING module=HandleJemallocSettings ( userver/core/src/components/run.cpp:75 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=Failed to set max_background_threads to 1 tskv timestamp=2024-05-04T17:33:53.144416 level=INFO module=PreheatStacktraceCollector ( userver/core/src/components/run.cpp:100 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=Initialized stacktrace collector within 8ms tskv timestamp=2024-05-04T17:33:53.148187 level=INFO module=AcquireEvDefaultLoop ( userver/core/src/engine/ev/thread.cpp:51 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=Acquire ev_default_loop for thread_name=event-worker_0 tskv timestamp=2024-05-04T17:33:53.148271 level=INFO module=Manager ( userver/core/src/components/manager.cpp:140 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=Starting components manager tskv timestamp=2024-05-04T17:33:53.148888 level=INFO module=TaskProcessor ( userver/core/src/engine/task/task_processor.cpp:76 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=creating task_processor main-task-processor worker_threads=4 thread_name=main-worker tskv timestamp=2024-05-04T17:33:53.149331 level=INFO module=TaskProcessor ( userver/core/src/engine/task/task_processor.cpp:76 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=creating task_processor fs-task-processor worker_threads=2 thread_name=fs-worker tskv timestamp=2024-05-04T17:33:53.149414 level=INFO module=TaskProcessor ( userver/core/src/engine/task/task_processor.cpp:76 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=creating task_processor grpc-blocking-task-processor worker_threads=2 thread_name=grpc-worker tskv timestamp=2024-05-04T17:33:53.149538 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=task with task_id=0 created task with task_id=14E606EB0 tskv timestamp=2024-05-04T17:33:53.149563 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=0 thread_id=0x00000001EE9B3AC0 text=task with task_id=0 created task with task_id=14E607900 tskv timestamp=2024-05-04T17:33:53.149584 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E607900 thread_id=0x00000001700B7000 text=Append waiter=(0x14e607900, 0) use_count=3 tskv timestamp=2024-05-04T17:33:53.150188 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E60A050 tskv timestamp=2024-05-04T17:33:53.150459 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FE87000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.150546 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FE87000 text=time_left=9.999743542 tskv timestamp=2024-05-04T17:33:53.161018 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14F018600 tskv timestamp=2024-05-04T17:33:53.161036 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E825200 tskv timestamp=2024-05-04T17:33:53.161055 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E826600 tskv timestamp=2024-05-04T17:33:53.161067 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=61681d43776c4d3386e9d37b616bb699 span_id=320619ef5cdf4e6f parent_id= task_id=14F018600 thread_id=0x0000000170143000 text=Starting component alerts-storage tskv timestamp=2024-05-04T17:33:53.161067 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=25a3209e20794b839332e687244bdd80 span_id=f7979329bae3cdce parent_id= task_id=14E825200 thread_id=0x00000001700B7000 text=Starting component auth-checker-settings tskv timestamp=2024-05-04T17:33:53.161078 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E827A00 tskv timestamp=2024-05-04T17:33:53.161089 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E828E00 tskv timestamp=2024-05-04T17:33:53.161100 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E82A200 tskv timestamp=2024-05-04T17:33:53.161089 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=7c0ee0324d834aa5b391b5617717e1d6 span_id=55d3a68fe8caa459 parent_id= task_id=14E826600 thread_id=0x000000017002B000 text=Starting component default-handler-middleware-pipeline-builder tskv timestamp=2024-05-04T17:33:53.161111 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E82B600 tskv timestamp=2024-05-04T17:33:53.161122 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E82CA00 tskv timestamp=2024-05-04T17:33:53.161128 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=25a3209e20794b839332e687244bdd80 span_id=f7979329bae3cdce parent_id= task_id=14E825200 thread_id=0x00000001700B7000 text=Resolving dependency auth-checker-settings -> logging tskv timestamp=2024-05-04T17:33:53.161127 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=61681d43776c4d3386e9d37b616bb699 span_id=320619ef5cdf4e6f parent_id= task_id=14F018600 thread_id=0x0000000170143000 text=component deps: "alerts-storage" [label="alerts-storage\nuserver::v2_0_rc::alerts::StorageComponent"]; tskv timestamp=2024-05-04T17:33:53.161135 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E82DE00 tskv timestamp=2024-05-04T17:33:53.161147 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E82F200 tskv timestamp=2024-05-04T17:33:53.161146 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=c8ba9bb15cef46beb647a4bae8579328 span_id=57c89480dcdb878c parent_id= task_id=14E827A00 thread_id=0x000000017002B000 text=Starting component default-server-middleware-pipeline-builder tskv timestamp=2024-05-04T17:33:53.161152 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x0000000170143000 text=Starting component dns-client tskv timestamp=2024-05-04T17:33:53.161162 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=14E830600 tskv timestamp=2024-05-04T17:33:53.161164 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=25a3209e20794b839332e687244bdd80 span_id=f7979329bae3cdce parent_id= task_id=14E825200 thread_id=0x00000001700B7000 text=component logging is not loaded yet, component auth-checker-settings is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161176 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x0000000170143000 text=Starting component dynamic-config tskv timestamp=2024-05-04T17:33:53.161175 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=c8ba9bb15cef46beb647a4bae8579328 span_id=57c89480dcdb878c parent_id= task_id=14E827A00 thread_id=0x000000017002B000 text=Resolving dependency default-server-middleware-pipeline-builder -> logging tskv timestamp=2024-05-04T17:33:53.161186 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=Starting component grpc-client-factory tskv timestamp=2024-05-04T17:33:53.161190 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=c8ba9bb15cef46beb647a4bae8579328 span_id=57c89480dcdb878c parent_id= task_id=14E827A00 thread_id=0x000000017002B000 text=component logging is not loaded yet, component default-server-middleware-pipeline-builder is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161194 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x0000000170143000 text=Starting component grpc-server tskv timestamp=2024-05-04T17:33:53.161204 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x00000001700B7000 text=Starting component handler-hello tskv timestamp=2024-05-04T17:33:53.161206 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x0000000170143000 text=Resolving dependency grpc-server -> logging tskv timestamp=2024-05-04T17:33:53.161212 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=bc840b34786949909e180db182d70eb5 span_id=d173054c2aa7bcf0 parent_id= task_id=14E82F200 thread_id=0x000000017002B000 text=Starting component handler-ping tskv timestamp=2024-05-04T17:33:53.161225 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=bc840b34786949909e180db182d70eb5 span_id=d173054c2aa7bcf0 parent_id= task_id=14E82F200 thread_id=0x000000017002B000 text=Resolving dependency handler-ping -> logging tskv timestamp=2024-05-04T17:33:53.161239 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=bc840b34786949909e180db182d70eb5 span_id=d173054c2aa7bcf0 parent_id= task_id=14E82F200 thread_id=0x000000017002B000 text=component logging is not loaded yet, component handler-ping is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161251 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=15000B600 tskv timestamp=2024-05-04T17:33:53.161256 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x0000000170143000 text=component logging is not loaded yet, component grpc-server is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161263 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x000000017002B000 text=Starting component hello-client tskv timestamp=2024-05-04T17:33:53.161268 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=15000CA00 tskv timestamp=2024-05-04T17:33:53.161277 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x0000000170143000 text=Resolving dependency dynamic-config -> logging tskv timestamp=2024-05-04T17:33:53.161286 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:393 ) trace_id=12b6ed4a4c644cbda2e82f6e958abf82 span_id=c48147fcfdb6e760 parent_id= task_id=15000B600 thread_id=0x000000017002B000 text=Component http-client load disabled in config.yaml, skipping tskv timestamp=2024-05-04T17:33:53.161288 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=15000DE00 tskv timestamp=2024-05-04T17:33:53.161293 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x0000000170143000 text=component logging is not loaded yet, component dynamic-config is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161299 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=15000F200 tskv timestamp=2024-05-04T17:33:53.161310 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=Resolving dependency dns-client -> logging tskv timestamp=2024-05-04T17:33:53.161313 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Starting component logging tskv timestamp=2024-05-04T17:33:53.161320 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=7e7c3ae4489746d9bbd93a6fb465a3cb span_id=6bd337e03914e7bf parent_id= task_id=15000DE00 thread_id=0x0000000170143000 text=Starting component manager-controller tskv timestamp=2024-05-04T17:33:53.161323 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=component logging is not loaded yet, component dns-client is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161337 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=61681d43776c4d3386e9d37b616bb699 span_id=320619ef5cdf4e6f parent_id= task_id=14F018600 thread_id=0x0000000170143000 text=Started component alerts-storage tskv timestamp=2024-05-04T17:33:53.161345 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=Resolving dependency grpc-client-factory -> logging tskv timestamp=2024-05-04T17:33:53.161357 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=component logging is not loaded yet, component grpc-client-factory is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161370 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x0000000170143000 text=Resolving dependency hello-client -> logging tskv timestamp=2024-05-04T17:33:53.161385 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=c9a886a254a44a3dad211898bf7d6aa0 span_id=8857d11bbec26e60 parent_id= task_id=15000F200 thread_id=0x00000001700B7000 text=Starting component os-signal-processor tskv timestamp=2024-05-04T17:33:53.161385 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x0000000170143000 text=component logging is not loaded yet, component hello-client is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161385 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E80E200 tskv timestamp=2024-05-04T17:33:53.161401 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E80F600 tskv timestamp=2024-05-04T17:33:53.161406 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=Starting component postgres-db-1 tskv timestamp=2024-05-04T17:33:53.161405 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=c9a886a254a44a3dad211898bf7d6aa0 span_id=8857d11bbec26e60 parent_id= task_id=15000F200 thread_id=0x00000001700B7000 text=component deps: "os-signal-processor" [label="os-signal-processor\nuserver::v2_0_rc::os_signals::ProcessorComponent"]; tskv timestamp=2024-05-04T17:33:53.161417 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E810A00 tskv timestamp=2024-05-04T17:33:53.161417 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=c9a886a254a44a3dad211898bf7d6aa0 span_id=8857d11bbec26e60 parent_id= task_id=15000F200 thread_id=0x00000001700B7000 text=Started component os-signal-processor tskv timestamp=2024-05-04T17:33:53.161422 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=Resolving dependency postgres-db-1 -> logging tskv timestamp=2024-05-04T17:33:53.161432 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E811E00 tskv timestamp=2024-05-04T17:33:53.161434 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=Starting component server tskv timestamp=2024-05-04T17:33:53.161436 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=component logging is not loaded yet, component postgres-db-1 is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161448 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E813200 tskv timestamp=2024-05-04T17:33:53.161451 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=Resolving dependency server -> logging tskv timestamp=2024-05-04T17:33:53.161455 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=31e37489e9b642678cab2e3a81c44f77 span_id=fbeaf3b6d11527eb parent_id= task_id=13E810A00 thread_id=0x0000000170143000 text=Starting component statistics-storage tskv timestamp=2024-05-04T17:33:53.161460 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E814600 tskv timestamp=2024-05-04T17:33:53.161473 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E815A00 tskv timestamp=2024-05-04T17:33:53.161480 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:393 ) trace_id=bcf01886ec734f13906aa4d02c7237f9 span_id=3534ed650d49c4bb parent_id= task_id=13E811E00 thread_id=0x0000000170143000 text=Component tests-control load disabled in config.yaml, skipping tskv timestamp=2024-05-04T17:33:53.161488 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E816E00 tskv timestamp=2024-05-04T17:33:53.161501 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E818200 tskv timestamp=2024-05-04T17:33:53.161466 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=component logging is not loaded yet, component server is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161502 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=6ea05082dfad417caefc1b58ef809436 span_id=f4f5bce8b558fef1 parent_id= task_id=13E813200 thread_id=0x0000000170143000 text=Starting component testsuite-exceptions-handling-middleware tskv timestamp=2024-05-04T17:33:53.161514 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=6ea05082dfad417caefc1b58ef809436 span_id=f4f5bce8b558fef1 parent_id= task_id=13E813200 thread_id=0x0000000170143000 text=Resolving dependency testsuite-exceptions-handling-middleware -> logging tskv timestamp=2024-05-04T17:33:53.161518 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E819600 tskv timestamp=2024-05-04T17:33:53.161518 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=7d8cb20549f94900bb7dd5fea9a30aee span_id=e93547b273509063 parent_id= task_id=13E814600 thread_id=0x00000001700B7000 text=Starting component testsuite-support tskv timestamp=2024-05-04T17:33:53.161528 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E81AA00 tskv timestamp=2024-05-04T17:33:53.161532 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=6ea05082dfad417caefc1b58ef809436 span_id=f4f5bce8b558fef1 parent_id= task_id=13E813200 thread_id=0x0000000170143000 text=component logging is not loaded yet, component testsuite-exceptions-handling-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161536 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x00000001700B7000 text=Starting component tracer tskv timestamp=2024-05-04T17:33:53.161542 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E81BE00 tskv timestamp=2024-05-04T17:33:53.161549 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x00000001700B7000 text=Resolving dependency tracer -> logging tskv timestamp=2024-05-04T17:33:53.161553 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=12d4800d004b41c29e7282e41643886e span_id=672db58328612a69 parent_id= task_id=13E816E00 thread_id=0x0000000170143000 text=Starting component tracing-manager-locator tskv timestamp=2024-05-04T17:33:53.161556 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E81D200 tskv timestamp=2024-05-04T17:33:53.161564 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x00000001700B7000 text=component logging is not loaded yet, component tracer is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161566 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E81E600 tskv timestamp=2024-05-04T17:33:53.161573 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=02fb604ce7244680a6ce8d8df5e61eac span_id=ddcc56ee20af143f parent_id= task_id=13E818200 thread_id=0x0000000170143000 text=Starting component userver-auth-middleware tskv timestamp=2024-05-04T17:33:53.161577 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E81FA00 tskv timestamp=2024-05-04T17:33:53.161587 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=02fb604ce7244680a6ce8d8df5e61eac span_id=ddcc56ee20af143f parent_id= task_id=13E818200 thread_id=0x0000000170143000 text=Resolving dependency userver-auth-middleware -> logging tskv timestamp=2024-05-04T17:33:53.161590 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E820E00 tskv timestamp=2024-05-04T17:33:53.161590 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=cd730a12ab5e488dbb5226c6785d469e span_id=bdd9b4706dcf408f parent_id= task_id=13E819600 thread_id=0x00000001700B7000 text=Starting component userver-baggage-middleware tskv timestamp=2024-05-04T17:33:53.161600 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E822200 tskv timestamp=2024-05-04T17:33:53.161605 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=02fb604ce7244680a6ce8d8df5e61eac span_id=ddcc56ee20af143f parent_id= task_id=13E818200 thread_id=0x0000000170143000 text=component logging is not loaded yet, component userver-auth-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161606 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=238f6b975b204e9ebebffcc70e027eb7 span_id=1192281642da3598 parent_id= task_id=13E81AA00 thread_id=0x00000001700B7000 text=Starting component userver-deadline-propagation-middleware tskv timestamp=2024-05-04T17:33:53.161614 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E823600 tskv timestamp=2024-05-04T17:33:53.161629 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=task with task_id=14E606EB0 created task with task_id=13E824A00 tskv timestamp=2024-05-04T17:33:53.161630 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=37c4ede6bbf241b6b98c92034dcb9383 span_id=5007b614f013f651 parent_id= task_id=13E81BE00 thread_id=0x00000001700B7000 text=Starting component userver-decompression-middleware tskv timestamp=2024-05-04T17:33:53.161638 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=dbd90f7b800b455db398cbd7fb815ee3 span_id=b3c272d1cb3a919b parent_id= task_id=13E81D200 thread_id=0x0000000170143000 text=Starting component userver-exceptions-handling-middleware tskv timestamp=2024-05-04T17:33:53.161640 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=Task with task_id=14F018600 stopped tskv timestamp=2024-05-04T17:33:53.161644 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=37c4ede6bbf241b6b98c92034dcb9383 span_id=5007b614f013f651 parent_id= task_id=13E81BE00 thread_id=0x00000001700B7000 text=Resolving dependency userver-decompression-middleware -> logging tskv timestamp=2024-05-04T17:33:53.161651 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E606EB0 thread_id=0x000000016FF9F000 text=Append waiter=(0x14e606eb0, 0) use_count=3 tskv timestamp=2024-05-04T17:33:53.161651 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=1622e5a5624d4e5fa7b87c39668ebeab span_id=a81a85ce6958a8f1 parent_id= task_id=13E81E600 thread_id=0x000000017002B000 text=Starting component userver-handler-adapter-middleware tskv timestamp=2024-05-04T17:33:53.161656 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=37c4ede6bbf241b6b98c92034dcb9383 span_id=5007b614f013f651 parent_id= task_id=13E81BE00 thread_id=0x00000001700B7000 text=component logging is not loaded yet, component userver-decompression-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161665 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=6971f2621df449939629ebdb260b59d5 span_id=73fffe9a3fa6fdea parent_id= task_id=13E81FA00 thread_id=0x0000000170143000 text=Starting component userver-handler-metrics-middleware tskv timestamp=2024-05-04T17:33:53.161668 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=83d06fc155144e78a1c8cf86953a005c span_id=efac8c4ac871c517 parent_id= task_id=13E820E00 thread_id=0x000000017002B000 text=Starting component userver-rate-limit-middleware tskv timestamp=2024-05-04T17:33:53.161678 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=0691fe19deed405d8eef016e40f08ca3 span_id=f1ab7433b01a0be7 parent_id= task_id=13E822200 thread_id=0x00000001700B7000 text=Starting component userver-set-accept-encoding-middleware tskv timestamp=2024-05-04T17:33:53.161680 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=83d06fc155144e78a1c8cf86953a005c span_id=efac8c4ac871c517 parent_id= task_id=13E820E00 thread_id=0x000000017002B000 text=Resolving dependency userver-rate-limit-middleware -> logging tskv timestamp=2024-05-04T17:33:53.161689 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=be942d63b3eb426b82fe4b86c2a08086 span_id=ea463f3792113742 parent_id= task_id=13E823600 thread_id=0x0000000170143000 text=Starting component userver-tracing-middleware tskv timestamp=2024-05-04T17:33:53.161695 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=83d06fc155144e78a1c8cf86953a005c span_id=efac8c4ac871c517 parent_id= task_id=13E820E00 thread_id=0x000000017002B000 text=component logging is not loaded yet, component userver-rate-limit-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161709 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:398 ) trace_id=82f2a09cabbd4e4ab170ab1cb597185c span_id=9fca5362fd9c282d parent_id= task_id=13E824A00 thread_id=0x0000000170143000 text=Starting component userver-unknown-exceptions-handling-middleware tskv timestamp=2024-05-04T17:33:53.161723 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=0691fe19deed405d8eef016e40f08ca3 span_id=f1ab7433b01a0be7 parent_id= task_id=13E822200 thread_id=0x00000001700B7000 text=Resolving dependency userver-set-accept-encoding-middleware -> logging tskv timestamp=2024-05-04T17:33:53.161740 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=0691fe19deed405d8eef016e40f08ca3 span_id=f1ab7433b01a0be7 parent_id= task_id=13E822200 thread_id=0x00000001700B7000 text=component logging is not loaded yet, component userver-set-accept-encoding-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161757 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000017002B000 text=Resolving dependency handler-hello -> logging tskv timestamp=2024-05-04T17:33:53.161773 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000017002B000 text=component logging is not loaded yet, component handler-hello is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161793 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=cd730a12ab5e488dbb5226c6785d469e span_id=bdd9b4706dcf408f parent_id= task_id=13E819600 thread_id=0x00000001700B7000 text=Resolving dependency userver-baggage-middleware -> logging tskv timestamp=2024-05-04T17:33:53.161807 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=cd730a12ab5e488dbb5226c6785d469e span_id=bdd9b4706dcf408f parent_id= task_id=13E819600 thread_id=0x00000001700B7000 text=component logging is not loaded yet, component userver-baggage-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161824 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=238f6b975b204e9ebebffcc70e027eb7 span_id=1192281642da3598 parent_id= task_id=13E81AA00 thread_id=0x0000000170143000 text=Resolving dependency userver-deadline-propagation-middleware -> logging tskv timestamp=2024-05-04T17:33:53.161841 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=238f6b975b204e9ebebffcc70e027eb7 span_id=1192281642da3598 parent_id= task_id=13E81AA00 thread_id=0x0000000170143000 text=component logging is not loaded yet, component userver-deadline-propagation-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161863 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=7e7c3ae4489746d9bbd93a6fb465a3cb span_id=6bd337e03914e7bf parent_id= task_id=15000DE00 thread_id=0x000000017002B000 text=Resolving dependency manager-controller -> statistics-storage tskv timestamp=2024-05-04T17:33:53.161878 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=7e7c3ae4489746d9bbd93a6fb465a3cb span_id=6bd337e03914e7bf parent_id= task_id=15000DE00 thread_id=0x000000017002B000 text=component statistics-storage is not loaded yet, component manager-controller is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161896 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=12d4800d004b41c29e7282e41643886e span_id=672db58328612a69 parent_id= task_id=13E816E00 thread_id=0x0000000170143000 text=Resolving dependency tracing-manager-locator -> logging tskv timestamp=2024-05-04T17:33:53.161911 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=12d4800d004b41c29e7282e41643886e span_id=672db58328612a69 parent_id= task_id=13E816E00 thread_id=0x0000000170143000 text=component logging is not loaded yet, component tracing-manager-locator is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161928 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=dbd90f7b800b455db398cbd7fb815ee3 span_id=b3c272d1cb3a919b parent_id= task_id=13E81D200 thread_id=0x000000017002B000 text=Resolving dependency userver-exceptions-handling-middleware -> logging tskv timestamp=2024-05-04T17:33:53.161944 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=dbd90f7b800b455db398cbd7fb815ee3 span_id=b3c272d1cb3a919b parent_id= task_id=13E81D200 thread_id=0x000000017002B000 text=component logging is not loaded yet, component userver-exceptions-handling-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.161968 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Resolving dependency logging -> alerts-storage tskv timestamp=2024-05-04T17:33:53.161969 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=31e37489e9b642678cab2e3a81c44f77 span_id=fbeaf3b6d11527eb parent_id= task_id=13E810A00 thread_id=0x000000016FF9F000 text=component deps: "statistics-storage" [label="statistics-storage\nuserver::v2_0_rc::components::StatisticsStorage"]; tskv timestamp=2024-05-04T17:33:53.162017 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=7c0ee0324d834aa5b391b5617717e1d6 span_id=55d3a68fe8caa459 parent_id= task_id=14E826600 thread_id=0x000000016FF9F000 text=Resolving dependency default-handler-middleware-pipeline-builder -> logging tskv timestamp=2024-05-04T17:33:53.162033 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=7c0ee0324d834aa5b391b5617717e1d6 span_id=55d3a68fe8caa459 parent_id= task_id=14E826600 thread_id=0x000000016FF9F000 text=component logging is not loaded yet, component default-handler-middleware-pipeline-builder is waiting for it to load tskv timestamp=2024-05-04T17:33:53.162053 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=82f2a09cabbd4e4ab170ab1cb597185c span_id=9fca5362fd9c282d parent_id= task_id=13E824A00 thread_id=0x000000017002B000 text=Resolving dependency userver-unknown-exceptions-handling-middleware -> logging tskv timestamp=2024-05-04T17:33:53.162068 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=82f2a09cabbd4e4ab170ab1cb597185c span_id=9fca5362fd9c282d parent_id= task_id=13E824A00 thread_id=0x000000017002B000 text=component logging is not loaded yet, component userver-unknown-exceptions-handling-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.162085 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=31e37489e9b642678cab2e3a81c44f77 span_id=fbeaf3b6d11527eb parent_id= task_id=13E810A00 thread_id=0x000000016FF9F000 text=Started component statistics-storage tskv timestamp=2024-05-04T17:33:53.162091 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Resolving dependency logging -> os-signal-processor tskv timestamp=2024-05-04T17:33:53.162104 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:225 ) trace_id=7d8cb20549f94900bb7dd5fea9a30aee span_id=e93547b273509063 parent_id= task_id=13E814600 thread_id=0x00000001700B7000 text=Start writing ptr=0x000060000318C000 with custom initial value tskv timestamp=2024-05-04T17:33:53.162116 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=7d8cb20549f94900bb7dd5fea9a30aee span_id=e93547b273509063 parent_id= task_id=13E814600 thread_id=0x00000001700B7000 text=Committing ptr=0x000060000318C000 tskv timestamp=2024-05-04T17:33:53.162129 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=7d8cb20549f94900bb7dd5fea9a30aee span_id=e93547b273509063 parent_id= task_id=13E814600 thread_id=0x00000001700B7000 text=Retiring ptr=0x0000600003184190 tskv timestamp=2024-05-04T17:33:53.162140 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=7d8cb20549f94900bb7dd5fea9a30aee span_id=e93547b273509063 parent_id= task_id=13E814600 thread_id=0x00000001700B7000 text=Retire, not used ptr=0x0000600003184190 tskv timestamp=2024-05-04T17:33:53.162142 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=1622e5a5624d4e5fa7b87c39668ebeab span_id=a81a85ce6958a8f1 parent_id= task_id=13E81E600 thread_id=0x000000016FF9F000 text=Resolving dependency userver-handler-adapter-middleware -> logging tskv timestamp=2024-05-04T17:33:53.162155 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=1622e5a5624d4e5fa7b87c39668ebeab span_id=a81a85ce6958a8f1 parent_id= task_id=13E81E600 thread_id=0x000000016FF9F000 text=component logging is not loaded yet, component userver-handler-adapter-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.162155 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=7d8cb20549f94900bb7dd5fea9a30aee span_id=e93547b273509063 parent_id= task_id=13E814600 thread_id=0x00000001700B7000 text=task with task_id=13E814600 created task with task_id=14F804C20 tskv timestamp=2024-05-04T17:33:53.162176 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=0 thread_id=0x000000016FF9F000 text=Task with task_id=14F804C20 stopped tskv timestamp=2024-05-04T17:33:53.162179 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=task with task_id=15000CA00 created task with task_id=13E604940 tskv timestamp=2024-05-04T17:33:53.162187 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=7e7c3ae4489746d9bbd93a6fb465a3cb span_id=6bd337e03914e7bf parent_id= task_id=15000DE00 thread_id=0x0000000170143000 text=Resolving dependency manager-controller -> dynamic-config tskv timestamp=2024-05-04T17:33:53.162201 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=7e7c3ae4489746d9bbd93a6fb465a3cb span_id=6bd337e03914e7bf parent_id= task_id=15000DE00 thread_id=0x0000000170143000 text=component dynamic-config is not loaded yet, component manager-controller is waiting for it to load tskv timestamp=2024-05-04T17:33:53.162221 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=be942d63b3eb426b82fe4b86c2a08086 span_id=ea463f3792113742 parent_id= task_id=13E823600 thread_id=0x0000000170143000 text=Resolving dependency userver-tracing-middleware -> logging tskv timestamp=2024-05-04T17:33:53.162234 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=be942d63b3eb426b82fe4b86c2a08086 span_id=ea463f3792113742 parent_id= task_id=13E823600 thread_id=0x0000000170143000 text=component logging is not loaded yet, component userver-tracing-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.162254 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=6971f2621df449939629ebdb260b59d5 span_id=73fffe9a3fa6fdea parent_id= task_id=13E81FA00 thread_id=0x000000016FF9F000 text=Resolving dependency userver-handler-metrics-middleware -> logging tskv timestamp=2024-05-04T17:33:53.162269 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=6971f2621df449939629ebdb260b59d5 span_id=73fffe9a3fa6fdea parent_id= task_id=13E81FA00 thread_id=0x000000016FF9F000 text=component logging is not loaded yet, component userver-handler-metrics-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.162321 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=7d8cb20549f94900bb7dd5fea9a30aee span_id=e93547b273509063 parent_id= task_id=13E814600 thread_id=0x00000001700B7000 text=component deps: "testsuite-support" [label="testsuite-support\nuserver::v2_0_rc::components::TestsuiteSupport"]; tskv timestamp=2024-05-04T17:33:53.162336 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=7d8cb20549f94900bb7dd5fea9a30aee span_id=e93547b273509063 parent_id= task_id=13E814600 thread_id=0x00000001700B7000 text=Started component testsuite-support tskv timestamp=2024-05-04T17:33:53.162711 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:215 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Start writing ptr=0x00006000012A0B20 tskv timestamp=2024-05-04T17:33:53.162732 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Committing ptr=0x00006000012A0B20 tskv timestamp=2024-05-04T17:33:53.162742 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Retiring ptr=0x0000600001281E40 tskv timestamp=2024-05-04T17:33:53.162752 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Retire, not used ptr=0x0000600001281E40 tskv timestamp=2024-05-04T17:33:53.162762 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:225 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Start writing ptr=0x0000600001C92070 with custom initial value tskv timestamp=2024-05-04T17:33:53.162771 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Committing ptr=0x0000600001C92070 tskv timestamp=2024-05-04T17:33:53.162791 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Retiring ptr=0x0000600001C915F0 tskv timestamp=2024-05-04T17:33:53.162800 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Retire, not used ptr=0x0000600001C915F0 tskv timestamp=2024-05-04T17:33:53.162937 level=INFO module=DoStart ( userver/core/src/utils/periodic_task.cpp:54 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Starting PeriodicTask with name=log_flusher tskv timestamp=2024-05-04T17:33:53.162948 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=task with task_id=15000CA00 created task with task_id=14E60AE10 tskv timestamp=2024-05-04T17:33:53.162962 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=Resolving dependency logging -> statistics-storage tskv timestamp=2024-05-04T17:33:53.162966 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:47 ) task_id=14E60AE10 thread_id=0x0000000170143000 text=WaitForEventUntil() tskv timestamp=2024-05-04T17:33:53.162972 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:56 ) task_id=14E60AE10 thread_id=0x0000000170143000 text=iteration() tskv timestamp=2024-05-04T17:33:53.162979 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E60AE10 thread_id=0x0000000170143000 text=Append waiter=(0x14e60ae10, 0) use_count=3 tskv timestamp=2024-05-04T17:33:53.163022 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.163036 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FF13000 text=time_left=1.999928291 tskv timestamp=2024-05-04T17:33:53.163034 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x000000017002B000 text=component deps: "logging" [label="logging\nuserver::v2_0_rc::components::Logging"]; "logging" -> "alerts-storage"; "logging" -> "os-signal-processor"; "logging" -> "statistics-storage" tskv timestamp=2024-05-04T17:33:53.163041 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=c8ba9bb15cef46beb647a4bae8579328 span_id=57c89480dcdb878c parent_id= task_id=14E827A00 thread_id=0x0000000170143000 text=Resolving dependency default-server-middleware-pipeline-builder -> tracer tskv timestamp=2024-05-04T17:33:53.163054 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=c8ba9bb15cef46beb647a4bae8579328 span_id=57c89480dcdb878c parent_id= task_id=14E827A00 thread_id=0x0000000170143000 text=component tracer is not loaded yet, component default-server-middleware-pipeline-builder is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163078 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=bc840b34786949909e180db182d70eb5 span_id=d173054c2aa7bcf0 parent_id= task_id=14E82F200 thread_id=0x00000001700B7000 text=Resolving dependency handler-ping -> tracer tskv timestamp=2024-05-04T17:33:53.163093 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=bc840b34786949909e180db182d70eb5 span_id=d173054c2aa7bcf0 parent_id= task_id=14E82F200 thread_id=0x00000001700B7000 text=component tracer is not loaded yet, component handler-ping is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163118 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=37c4ede6bbf241b6b98c92034dcb9383 span_id=5007b614f013f651 parent_id= task_id=13E81BE00 thread_id=0x000000016FF9F000 text=Resolving dependency userver-decompression-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163131 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=37c4ede6bbf241b6b98c92034dcb9383 span_id=5007b614f013f651 parent_id= task_id=13E81BE00 thread_id=0x000000016FF9F000 text=component tracer is not loaded yet, component userver-decompression-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163144 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=82f2a09cabbd4e4ab170ab1cb597185c span_id=9fca5362fd9c282d parent_id= task_id=13E824A00 thread_id=0x000000017002B000 text=Resolving dependency userver-unknown-exceptions-handling-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163156 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=82f2a09cabbd4e4ab170ab1cb597185c span_id=9fca5362fd9c282d parent_id= task_id=13E824A00 thread_id=0x000000017002B000 text=component tracer is not loaded yet, component userver-unknown-exceptions-handling-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163172 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=25a3209e20794b839332e687244bdd80 span_id=f7979329bae3cdce parent_id= task_id=14E825200 thread_id=0x0000000170143000 text=Resolving dependency auth-checker-settings -> tracer tskv timestamp=2024-05-04T17:33:53.163173 level=INFO module=Tracer ( userver/core/src/tracing/component.cpp:31 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x000000016FF9F000 text=Opentracing logger is not registered tskv timestamp=2024-05-04T17:33:53.163183 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:225 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x000000016FF9F000 text=Start writing ptr=0x0000600001081150 with custom initial value tskv timestamp=2024-05-04T17:33:53.163184 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=25a3209e20794b839332e687244bdd80 span_id=f7979329bae3cdce parent_id= task_id=14E825200 thread_id=0x0000000170143000 text=component tracer is not loaded yet, component auth-checker-settings is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163191 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x000000016FF9F000 text=Committing ptr=0x0000600001081150 tskv timestamp=2024-05-04T17:33:53.163200 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x000000016FF9F000 text=Retiring ptr=0x000060000108CCA0 tskv timestamp=2024-05-04T17:33:53.163209 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x000000016FF9F000 text=Retire, not used ptr=0x000060000108CCA0 tskv timestamp=2024-05-04T17:33:53.163212 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=02fb604ce7244680a6ce8d8df5e61eac span_id=ddcc56ee20af143f parent_id= task_id=13E818200 thread_id=0x00000001700B7000 text=Resolving dependency userver-auth-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163221 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x000000016FF9F000 text=task with task_id=13E815A00 created task with task_id=14E60B080 tskv timestamp=2024-05-04T17:33:53.163225 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=02fb604ce7244680a6ce8d8df5e61eac span_id=ddcc56ee20af143f parent_id= task_id=13E818200 thread_id=0x00000001700B7000 text=component tracer is not loaded yet, component userver-auth-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163236 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=0 thread_id=0x0000000170143000 text=Task with task_id=14E60B080 stopped tskv timestamp=2024-05-04T17:33:53.163235 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x000000016FF9F000 text=component deps: "tracer" [label="tracer\nuserver::v2_0_rc::components::Tracer"]; "tracer" -> "logging" tskv timestamp=2024-05-04T17:33:53.163238 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=02fb604ce7244680a6ce8d8df5e61eac span_id=ddcc56ee20af143f parent_id= task_id=13E818200 thread_id=0x00000001700B7000 text=component deps: "userver-auth-middleware" [label="userver-auth-middleware\nuserver::v2_0_rc::server::middlewares::AuthFactory"]; "userver-auth-middleware" -> "logging"; "userver-auth-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163250 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=c8ba9bb15cef46beb647a4bae8579328 span_id=57c89480dcdb878c parent_id= task_id=14E827A00 thread_id=0x0000000170143000 text=component deps: "default-server-middleware-pipeline-builder" [label="default-server-middleware-pipeline-builder\nuserver::v2_0_rc::server::middlewares::PipelineBuilder"]; "default-server-middleware-pipeline-builder" -> "logging"; "default-server-middleware-pipeline-builder" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163252 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x000000017002B000 text=Resolving dependency hello-client -> tracer tskv timestamp=2024-05-04T17:33:53.163328 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x000000017002B000 text=Resolving dependency hello-client -> grpc-client-factory tskv timestamp=2024-05-04T17:33:53.163342 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x000000017002B000 text=component grpc-client-factory is not loaded yet, component hello-client is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163357 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x000000016FF9F000 text=Resolving dependency server -> tracer tskv timestamp=2024-05-04T17:33:53.163373 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=cd730a12ab5e488dbb5226c6785d469e span_id=bdd9b4706dcf408f parent_id= task_id=13E819600 thread_id=0x000000017002B000 text=Resolving dependency userver-baggage-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163386 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=cd730a12ab5e488dbb5226c6785d469e span_id=bdd9b4706dcf408f parent_id= task_id=13E819600 thread_id=0x000000017002B000 text=component deps: "userver-baggage-middleware" [label="userver-baggage-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::Baggage>"]; "userver-baggage-middleware" -> "logging"; "userver-baggage-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163399 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=be942d63b3eb426b82fe4b86c2a08086 span_id=ea463f3792113742 parent_id= task_id=13E823600 thread_id=0x0000000170143000 text=Resolving dependency userver-tracing-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163448 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=be942d63b3eb426b82fe4b86c2a08086 span_id=ea463f3792113742 parent_id= task_id=13E823600 thread_id=0x0000000170143000 text=Resolving dependency userver-tracing-middleware -> tracing-manager-locator tskv timestamp=2024-05-04T17:33:53.163461 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=be942d63b3eb426b82fe4b86c2a08086 span_id=ea463f3792113742 parent_id= task_id=13E823600 thread_id=0x0000000170143000 text=component tracing-manager-locator is not loaded yet, component userver-tracing-middleware is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163477 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=1622e5a5624d4e5fa7b87c39668ebeab span_id=a81a85ce6958a8f1 parent_id= task_id=13E81E600 thread_id=0x000000017002B000 text=Resolving dependency userver-handler-adapter-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163490 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=dbd90f7b800b455db398cbd7fb815ee3 span_id=b3c272d1cb3a919b parent_id= task_id=13E81D200 thread_id=0x0000000170143000 text=Resolving dependency userver-exceptions-handling-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163490 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=1622e5a5624d4e5fa7b87c39668ebeab span_id=a81a85ce6958a8f1 parent_id= task_id=13E81E600 thread_id=0x000000017002B000 text=component deps: "userver-handler-adapter-middleware" [label="userver-handler-adapter-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::HandlerAdapter>"]; "userver-handler-adapter-middleware" -> "logging"; "userver-handler-adapter-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163505 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=1622e5a5624d4e5fa7b87c39668ebeab span_id=a81a85ce6958a8f1 parent_id= task_id=13E81E600 thread_id=0x000000017002B000 text=Started component userver-handler-adapter-middleware tskv timestamp=2024-05-04T17:33:53.163508 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=6a07d93de6f748a1854f1a0d209adedc span_id=e40a76f038949eec parent_id= task_id=13E815A00 thread_id=0x00000001700B7000 text=Started component tracer tskv timestamp=2024-05-04T17:33:53.163503 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=dbd90f7b800b455db398cbd7fb815ee3 span_id=b3c272d1cb3a919b parent_id= task_id=13E81D200 thread_id=0x0000000170143000 text=component deps: "userver-exceptions-handling-middleware" [label="userver-exceptions-handling-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::ExceptionsHandling>"]; "userver-exceptions-handling-middleware" -> "logging"; "userver-exceptions-handling-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163519 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000017002B000 text=Resolving dependency handler-hello -> tracer tskv timestamp=2024-05-04T17:33:53.163553 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=02fb604ce7244680a6ce8d8df5e61eac span_id=ddcc56ee20af143f parent_id= task_id=13E818200 thread_id=0x000000017002B000 text=Started component userver-auth-middleware tskv timestamp=2024-05-04T17:33:53.163557 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=83d06fc155144e78a1c8cf86953a005c span_id=efac8c4ac871c517 parent_id= task_id=13E820E00 thread_id=0x0000000170143000 text=Resolving dependency userver-rate-limit-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163631 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=83d06fc155144e78a1c8cf86953a005c span_id=efac8c4ac871c517 parent_id= task_id=13E820E00 thread_id=0x0000000170143000 text=component deps: "userver-rate-limit-middleware" [label="userver-rate-limit-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::RateLimit>"]; "userver-rate-limit-middleware" -> "logging"; "userver-rate-limit-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163655 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=82f2a09cabbd4e4ab170ab1cb597185c span_id=9fca5362fd9c282d parent_id= task_id=13E824A00 thread_id=0x000000017002B000 text=component deps: "userver-unknown-exceptions-handling-middleware" [label="userver-unknown-exceptions-handling-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::UnknownExceptionsHandling>"]; "userver-unknown-exceptions-handling-middleware" -> "logging"; "userver-unknown-exceptions-handling-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163670 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=82f2a09cabbd4e4ab170ab1cb597185c span_id=9fca5362fd9c282d parent_id= task_id=13E824A00 thread_id=0x000000017002B000 text=Started component userver-unknown-exceptions-handling-middleware tskv timestamp=2024-05-04T17:33:53.163684 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=c8ba9bb15cef46beb647a4bae8579328 span_id=57c89480dcdb878c parent_id= task_id=14E827A00 thread_id=0x000000017002B000 text=Started component default-server-middleware-pipeline-builder tskv timestamp=2024-05-04T17:33:53.163665 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=83d06fc155144e78a1c8cf86953a005c span_id=efac8c4ac871c517 parent_id= task_id=13E820E00 thread_id=0x0000000170143000 text=Started component userver-rate-limit-middleware tskv timestamp=2024-05-04T17:33:53.163694 level=INFO module=ServerImpl ( userver/core/src/server/server.cpp:150 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x000000016FF9F000 text=Creating server tskv timestamp=2024-05-04T17:33:53.163658 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=b21a80bda18c4a5da310a89f2e5eadfa span_id=f022a14ab90a9f93 parent_id= task_id=15000CA00 thread_id=0x00000001700B7000 text=Started component logging tskv timestamp=2024-05-04T17:33:53.163703 level=INFO module=Init ( userver/core/src/server/server.cpp:52 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x000000016FF9F000 text=Creating listener tskv timestamp=2024-05-04T17:33:53.163701 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=25a3209e20794b839332e687244bdd80 span_id=f7979329bae3cdce parent_id= task_id=14E825200 thread_id=0x000000017002B000 text=component deps: "auth-checker-settings" [label="auth-checker-settings\nuserver::v2_0_rc::components::AuthCheckerSettings"]; "auth-checker-settings" -> "logging"; "auth-checker-settings" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163717 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x000000016FF9F000 text=Resolving dependency server -> statistics-storage tskv timestamp=2024-05-04T17:33:53.163726 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=25a3209e20794b839332e687244bdd80 span_id=f7979329bae3cdce parent_id= task_id=14E825200 thread_id=0x000000017002B000 text=Started component auth-checker-settings tskv timestamp=2024-05-04T17:33:53.163731 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x000000016FF9F000 text=Resolving dependency server -> dynamic-config tskv timestamp=2024-05-04T17:33:53.163737 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000017002B000 text=WakeupOne waiter=(0x14e606eb0, 0) use_count=2 tskv timestamp=2024-05-04T17:33:53.163747 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x000000017002B000 text=Task with task_id=14E825200 stopped tskv timestamp=2024-05-04T17:33:53.163755 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E606EB0 thread_id=0x000000017002B000 text=Append waiter=(0x14e606eb0, 1) use_count=3 tskv timestamp=2024-05-04T17:33:53.163770 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=7c0ee0324d834aa5b391b5617717e1d6 span_id=55d3a68fe8caa459 parent_id= task_id=14E826600 thread_id=0x000000017002B000 text=Resolving dependency default-handler-middleware-pipeline-builder -> tracer tskv timestamp=2024-05-04T17:33:53.163786 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=Resolving dependency grpc-client-factory -> tracer tskv timestamp=2024-05-04T17:33:53.163784 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=7c0ee0324d834aa5b391b5617717e1d6 span_id=55d3a68fe8caa459 parent_id= task_id=14E826600 thread_id=0x000000017002B000 text=component deps: "default-handler-middleware-pipeline-builder" [label="default-handler-middleware-pipeline-builder\nuserver::v2_0_rc::server::middlewares::HandlerPipelineBuilder"]; "default-handler-middleware-pipeline-builder" -> "logging"; "default-handler-middleware-pipeline-builder" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163804 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=dbd90f7b800b455db398cbd7fb815ee3 span_id=b3c272d1cb3a919b parent_id= task_id=13E81D200 thread_id=0x000000017002B000 text=Started component userver-exceptions-handling-middleware tskv timestamp=2024-05-04T17:33:53.163809 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=Resolving dependency postgres-db-1 -> tracer tskv timestamp=2024-05-04T17:33:53.163824 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='18' cpp='char' tskv timestamp=2024-05-04T17:33:53.163826 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=0691fe19deed405d8eef016e40f08ca3 span_id=f1ab7433b01a0be7 parent_id= task_id=13E822200 thread_id=0x000000017002B000 text=Resolving dependency userver-set-accept-encoding-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163823 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=37c4ede6bbf241b6b98c92034dcb9383 span_id=5007b614f013f651 parent_id= task_id=13E81BE00 thread_id=0x00000001700B7000 text=component deps: "userver-decompression-middleware" [label="userver-decompression-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::Decompression>"]; "userver-decompression-middleware" -> "logging"; "userver-decompression-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163834 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='18' cpp='char' tskv timestamp=2024-05-04T17:33:53.163840 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=37c4ede6bbf241b6b98c92034dcb9383 span_id=5007b614f013f651 parent_id= task_id=13E81BE00 thread_id=0x00000001700B7000 text=Started component userver-decompression-middleware tskv timestamp=2024-05-04T17:33:53.163839 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=0691fe19deed405d8eef016e40f08ca3 span_id=f1ab7433b01a0be7 parent_id= task_id=13E822200 thread_id=0x000000017002B000 text=component deps: "userver-set-accept-encoding-middleware" [label="userver-set-accept-encoding-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::SetAcceptEncoding>"]; "userver-set-accept-encoding-middleware" -> "logging"; "userver-set-accept-encoding-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163843 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1002' cpp='char[]' tskv timestamp=2024-05-04T17:33:53.163852 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1002' cpp='char[]' tskv timestamp=2024-05-04T17:33:53.163860 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1115' cpp='std::chrono::system_clock::time_point[]' tskv timestamp=2024-05-04T17:33:53.163868 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=238f6b975b204e9ebebffcc70e027eb7 span_id=1192281642da3598 parent_id= task_id=13E81AA00 thread_id=0x000000016FF9F000 text=Resolving dependency userver-deadline-propagation-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163869 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1185' cpp='std::chrono::system_clock::time_point[]' tskv timestamp=2024-05-04T17:33:53.163877 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='25' cpp='std::__1::basic_string_view<char, std::__1::char_traits<char>>' tskv timestamp=2024-05-04T17:33:53.163882 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=cd730a12ab5e488dbb5226c6785d469e span_id=bdd9b4706dcf408f parent_id= task_id=13E819600 thread_id=0x00000001700B7000 text=Started component userver-baggage-middleware tskv timestamp=2024-05-04T17:33:53.163883 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=0691fe19deed405d8eef016e40f08ca3 span_id=f1ab7433b01a0be7 parent_id= task_id=13E822200 thread_id=0x000000017002B000 text=Started component userver-set-accept-encoding-middleware tskv timestamp=2024-05-04T17:33:53.163881 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=238f6b975b204e9ebebffcc70e027eb7 span_id=1192281642da3598 parent_id= task_id=13E81AA00 thread_id=0x000000016FF9F000 text=component deps: "userver-deadline-propagation-middleware" [label="userver-deadline-propagation-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::DeadlinePropagation>"]; "userver-deadline-propagation-middleware" -> "logging"; "userver-deadline-propagation-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163886 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1028' cpp='unsigned int[]' tskv timestamp=2024-05-04T17:33:53.163894 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1028' cpp='unsigned int[]' tskv timestamp=2024-05-04T17:33:53.163896 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=238f6b975b204e9ebebffcc70e027eb7 span_id=1192281642da3598 parent_id= task_id=13E81AA00 thread_id=0x000000016FF9F000 text=Started component userver-deadline-propagation-middleware tskv timestamp=2024-05-04T17:33:53.163900 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=6971f2621df449939629ebdb260b59d5 span_id=73fffe9a3fa6fdea parent_id= task_id=13E81FA00 thread_id=0x000000017002B000 text=Resolving dependency userver-handler-metrics-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163903 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1010' cpp='unsigned int[]' tskv timestamp=2024-05-04T17:33:53.163911 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1011' cpp='unsigned int[]' tskv timestamp=2024-05-04T17:33:53.163915 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x00000001700B7000 text=Resolving dependency dynamic-config -> tracer tskv timestamp=2024-05-04T17:33:53.163913 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=6971f2621df449939629ebdb260b59d5 span_id=73fffe9a3fa6fdea parent_id= task_id=13E81FA00 thread_id=0x000000017002B000 text=component deps: "userver-handler-metrics-middleware" [label="userver-handler-metrics-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::server::middlewares::HandlerMetrics>"]; "userver-handler-metrics-middleware" -> "logging"; "userver-handler-metrics-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163920 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1012' cpp='unsigned int[]' tskv timestamp=2024-05-04T17:33:53.163929 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='26' cpp='unsigned int' tskv timestamp=2024-05-04T17:33:53.163931 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x000000017002B000 text=Resolving dependency grpc-client-factory -> grpc-server tskv timestamp=2024-05-04T17:33:53.163938 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='26' cpp='unsigned int' tskv timestamp=2024-05-04T17:33:53.163944 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x000000017002B000 text=component grpc-server is not loaded yet, component grpc-client-factory is waiting for it to load tskv timestamp=2024-05-04T17:33:53.163947 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='27' cpp='unsigned int' tskv timestamp=2024-05-04T17:33:53.163956 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='28' cpp='unsigned int' tskv timestamp=2024-05-04T17:33:53.163956 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=6ea05082dfad417caefc1b58ef809436 span_id=f4f5bce8b558fef1 parent_id= task_id=13E813200 thread_id=0x000000016FF9F000 text=Resolving dependency testsuite-exceptions-handling-middleware -> tracer tskv timestamp=2024-05-04T17:33:53.163964 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='29' cpp='unsigned int' tskv timestamp=2024-05-04T17:33:53.163970 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=12d4800d004b41c29e7282e41643886e span_id=672db58328612a69 parent_id= task_id=13E816E00 thread_id=0x000000017002B000 text=Resolving dependency tracing-manager-locator -> tracer tskv timestamp=2024-05-04T17:33:53.163973 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='25' cpp='std::string' tskv timestamp=2024-05-04T17:33:53.163969 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=6ea05082dfad417caefc1b58ef809436 span_id=f4f5bce8b558fef1 parent_id= task_id=13E813200 thread_id=0x000000016FF9F000 text=component deps: "testsuite-exceptions-handling-middleware" [label="testsuite-exceptions-handling-middleware\nuserver::v2_0_rc::server::middlewares::SimpleHttpMiddlewareFactory<userver::v2_0_rc::testsuite::ExceptionsHandlingMiddleware>"]; "testsuite-exceptions-handling-middleware" -> "logging"; "testsuite-exceptions-handling-middleware" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163981 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1042' cpp='std::string' tskv timestamp=2024-05-04T17:33:53.163984 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=6ea05082dfad417caefc1b58ef809436 span_id=f4f5bce8b558fef1 parent_id= task_id=13E813200 thread_id=0x000000016FF9F000 text=Started component testsuite-exceptions-handling-middleware tskv timestamp=2024-05-04T17:33:53.163990 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1043' cpp='std::string' tskv timestamp=2024-05-04T17:33:53.163990 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=12d4800d004b41c29e7282e41643886e span_id=672db58328612a69 parent_id= task_id=13E816E00 thread_id=0x000000017002B000 text=component deps: "tracing-manager-locator" [label="tracing-manager-locator\nuserver::v2_0_rc::tracing::DefaultTracingManagerLocator"]; "tracing-manager-locator" -> "logging"; "tracing-manager-locator" -> "tracer" tskv timestamp=2024-05-04T17:33:53.163998 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='19' cpp='std::string' tskv timestamp=2024-05-04T17:33:53.164006 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=bc840b34786949909e180db182d70eb5 span_id=d173054c2aa7bcf0 parent_id= task_id=14E82F200 thread_id=0x00000001700B7000 text=Resolving dependency handler-ping -> server tskv timestamp=2024-05-04T17:33:53.164007 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1021' cpp='float[]' tskv timestamp=2024-05-04T17:33:53.164016 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1000' cpp='bool[]' tskv timestamp=2024-05-04T17:33:53.164018 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=bc840b34786949909e180db182d70eb5 span_id=d173054c2aa7bcf0 parent_id= task_id=14E82F200 thread_id=0x00000001700B7000 text=component server is not loaded yet, component handler-ping is waiting for it to load tskv timestamp=2024-05-04T17:33:53.164024 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='701' cpp='double' tskv timestamp=2024-05-04T17:33:53.164033 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1009' cpp='std::string[]' tskv timestamp=2024-05-04T17:33:53.164037 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=6971f2621df449939629ebdb260b59d5 span_id=73fffe9a3fa6fdea parent_id= task_id=13E81FA00 thread_id=0x000000016FF9F000 text=Started component userver-handler-metrics-middleware tskv timestamp=2024-05-04T17:33:53.164038 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Resolving dependency dynamic-config -> alerts-storage tskv timestamp=2024-05-04T17:33:53.164042 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1014' cpp='std::string[]' tskv timestamp=2024-05-04T17:33:53.164050 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1015' cpp='std::string[]' tskv timestamp=2024-05-04T17:33:53.164056 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x00000001700B7000 text=Resolving dependency handler-hello -> grpc-server tskv timestamp=2024-05-04T17:33:53.164059 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1003' cpp='std::string[]' tskv timestamp=2024-05-04T17:33:53.164067 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x00000001700B7000 text=component grpc-server is not loaded yet, component handler-hello is waiting for it to load tskv timestamp=2024-05-04T17:33:53.164068 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1022' cpp='double[]' tskv timestamp=2024-05-04T17:33:53.164076 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='700' cpp='float' tskv timestamp=2024-05-04T17:33:53.164082 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=Resolving dependency dns-client -> tracer tskv timestamp=2024-05-04T17:33:53.164079 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=be942d63b3eb426b82fe4b86c2a08086 span_id=ea463f3792113742 parent_id= task_id=13E823600 thread_id=0x000000016FF9F000 text=component deps: "userver-tracing-middleware" [label="userver-tracing-middleware\nuserver::v2_0_rc::server::middlewares::TracingFactory"]; "userver-tracing-middleware" -> "logging"; "userver-tracing-middleware" -> "tracer"; "userver-tracing-middleware" -> "tracing-manager-locator" tskv timestamp=2024-05-04T17:33:53.164084 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='20' cpp='long long' tskv timestamp=2024-05-04T17:33:53.164093 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='3221' cpp='userver::v2_0_rc::utils::StrongTypedef<userver::v2_0_rc::storages::postgres::LsnTag, unsigned long long, (userver::v2_0_rc::utils::StrongTypedefOps)1, void>[]' tskv timestamp=2024-05-04T17:33:53.164094 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x000000016FF9F000 text=component dynamic-config is not loaded yet, component server is waiting for it to load tskv timestamp=2024-05-04T17:33:53.164102 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1016' cpp='long[]' tskv timestamp=2024-05-04T17:33:53.164107 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=7c0ee0324d834aa5b391b5617717e1d6 span_id=55d3a68fe8caa459 parent_id= task_id=14E826600 thread_id=0x000000016FF9F000 text=Started component default-handler-middleware-pipeline-builder tskv timestamp=2024-05-04T17:33:53.164111 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='21' cpp='short' tskv timestamp=2024-05-04T17:33:53.164118 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF9F000 text=WakeupOne waiter=(0x14e606eb0, 1) use_count=2 tskv timestamp=2024-05-04T17:33:53.164119 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='23' cpp='int' tskv timestamp=2024-05-04T17:33:53.164127 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=12d4800d004b41c29e7282e41643886e span_id=672db58328612a69 parent_id= task_id=13E816E00 thread_id=0x000000016FF9F000 text=Started component tracing-manager-locator tskv timestamp=2024-05-04T17:33:53.164128 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='16' cpp='bool' tskv timestamp=2024-05-04T17:33:53.164136 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='3220' cpp='userver::v2_0_rc::utils::StrongTypedef<userver::v2_0_rc::storages::postgres::LsnTag, unsigned long long, (userver::v2_0_rc::utils::StrongTypedefOps)1, void>' tskv timestamp=2024-05-04T17:33:53.164139 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x000000016FF9F000 text=Resolving dependency grpc-server -> tracer tskv timestamp=2024-05-04T17:33:53.164145 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1005' cpp='short[]' tskv timestamp=2024-05-04T17:33:53.164154 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1016' cpp='long long[]' tskv timestamp=2024-05-04T17:33:53.164162 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1009' cpp='std::__1::basic_string_view<char, std::__1::char_traits<char>>[]' tskv timestamp=2024-05-04T17:33:53.164170 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1007' cpp='int[]' tskv timestamp=2024-05-04T17:33:53.164178 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='20' cpp='long' tskv timestamp=2024-05-04T17:33:53.164187 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='2278' cpp='void' tskv timestamp=2024-05-04T17:33:53.164196 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1114' cpp='std::chrono::system_clock::time_point' tskv timestamp=2024-05-04T17:33:53.164205 level=INFO module=LogRegisteredTypes ( userver/postgresql/src/storages/postgres/io/type_mapping.cpp:59 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=pg type mapping: oid='1184' cpp='std::chrono::system_clock::time_point' tskv timestamp=2024-05-04T17:33:53.164215 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=Resolving dependency postgres-db-1 -> dynamic-config tskv timestamp=2024-05-04T17:33:53.164233 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x0000000170143000 text=component dynamic-config is not loaded yet, component postgres-db-1 is waiting for it to load tskv timestamp=2024-05-04T17:33:53.164245 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=be942d63b3eb426b82fe4b86c2a08086 span_id=ea463f3792113742 parent_id= task_id=13E823600 thread_id=0x0000000170143000 text=Started component userver-tracing-middleware tskv timestamp=2024-05-04T17:33:53.164258 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x0000000170143000 text=Task with task_id=14E826600 stopped tskv timestamp=2024-05-04T17:33:53.164267 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x0000000170143000 text=Task with task_id=14E827A00 stopped tskv timestamp=2024-05-04T17:33:53.164275 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E606EB0 thread_id=0x0000000170143000 text=Append waiter=(0x14e606eb0, 2) use_count=3 tskv timestamp=2024-05-04T17:33:53.164298 level=INFO module=DoStart ( userver/core/src/utils/periodic_task.cpp:54 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=Starting PeriodicTask with name=file-resolver-updater tskv timestamp=2024-05-04T17:33:53.164309 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=task with task_id=14E828E00 created task with task_id=14E706E20 tskv timestamp=2024-05-04T17:33:53.164323 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:47 ) task_id=14E706E20 thread_id=0x0000000170143000 text=WaitForEventUntil() tskv timestamp=2024-05-04T17:33:53.164325 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=task with task_id=14E828E00 created task with task_id=14F018600 tskv timestamp=2024-05-04T17:33:53.164330 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:56 ) task_id=14E706E20 thread_id=0x0000000170143000 text=iteration() tskv timestamp=2024-05-04T17:33:53.164335 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=Append waiter=(0x14e828e00, 11) use_count=3 tskv timestamp=2024-05-04T17:33:53.164338 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E706E20 thread_id=0x0000000170143000 text=Append waiter=(0x14e706e20, 0) use_count=3 tskv timestamp=2024-05-04T17:33:53.164338 level=INFO module=operator() ( userver/core/src/clients/dns/file_resolver.cpp:76 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Reloading static hosts mapping from /etc/hosts tskv timestamp=2024-05-04T17:33:53.164371 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Resolving dependency dynamic-config -> testsuite-support tskv timestamp=2024-05-04T17:33:53.164369 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FE87000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.164391 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x0000000170143000 text=Resolving dependency grpc-server -> statistics-storage tskv timestamp=2024-05-04T17:33:53.164408 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x0000000170143000 text=Resolving dependency grpc-server -> dynamic-config tskv timestamp=2024-05-04T17:33:53.164406 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FE87000 text=time_left=299.99991625 tskv timestamp=2024-05-04T17:33:53.164423 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x0000000170143000 text=component dynamic-config is not loaded yet, component grpc-server is waiting for it to load tskv timestamp=2024-05-04T17:33:53.164470 level=INFO module=operator() ( userver/core/src/clients/dns/file_resolver.cpp:99 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Parsed address 127.0.0.1:0 tskv timestamp=2024-05-04T17:33:53.164588 level=INFO module=operator() ( userver/core/src/clients/dns/file_resolver.cpp:106 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Parsed name 'localhost' tskv timestamp=2024-05-04T17:33:53.164611 level=INFO module=operator() ( userver/core/src/clients/dns/file_resolver.cpp:99 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Parsed address 255.255.255.255:0 tskv timestamp=2024-05-04T17:33:53.164621 level=INFO module=operator() ( userver/core/src/clients/dns/file_resolver.cpp:106 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Parsed name 'broadcasthost' tskv timestamp=2024-05-04T17:33:53.164631 level=INFO module=operator() ( userver/core/src/clients/dns/file_resolver.cpp:99 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Parsed address [::1]:0 tskv timestamp=2024-05-04T17:33:53.164641 level=INFO module=operator() ( userver/core/src/clients/dns/file_resolver.cpp:106 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Parsed name 'localhost' tskv timestamp=2024-05-04T17:33:53.164651 level=INFO module=operator() ( userver/core/src/clients/dns/file_resolver.cpp:113 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Loaded static hosts mapping with 2 names tskv timestamp=2024-05-04T17:33:53.164660 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:225 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Start writing ptr=0x0000600001C9C990 with custom initial value tskv timestamp=2024-05-04T17:33:53.164669 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Committing ptr=0x0000600001C9C990 tskv timestamp=2024-05-04T17:33:53.164678 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Retiring ptr=0x0000600001C975A0 tskv timestamp=2024-05-04T17:33:53.164687 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=Retire, not used ptr=0x0000600001C975A0 tskv timestamp=2024-05-04T17:33:53.164697 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 task_id=14F018600 thread_id=0x000000017025B000 text=task with task_id=14F018600 created task with task_id=14F805F10 tskv timestamp=2024-05-04T17:33:53.164715 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=0 thread_id=0x00000001701CF000 text=Task with task_id=14F805F10 stopped tskv timestamp=2024-05-04T17:33:53.164714 level=INFO module=Impl ( userver/core/src/utils/async.cpp:21 ) task_id=14F018600 thread_id=0x000000017025B000 text= trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8343f1c84aa870cc parent_id=8b38aab4df849de7 stopwatch_name=file-resolver-reload total_time=0.396958 span_ref_type=child stopwatch_units=ms start_timestamp=1714818833.164322 tskv timestamp=2024-05-04T17:33:53.164771 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000017025B000 text=WakeupOne waiter=(0x14e828e00, 11) use_count=2 tskv timestamp=2024-05-04T17:33:53.164800 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=Task with task_id=14F018600 stopped tskv timestamp=2024-05-04T17:33:53.164912 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Resolving dependency dynamic-config -> statistics-storage tskv timestamp=2024-05-04T17:33:53.164949 level=INFO module=SetConfig ( userver/core/src/dynamic_config/storage/component.cpp:219 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Setting new dynamic config value from 'dynamic-config' tskv timestamp=2024-05-04T17:33:53.165011 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=task with task_id=14E828E00 created task with task_id=13E826C00 tskv timestamp=2024-05-04T17:33:53.165024 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x00000001700B7000 text=Append waiter=(0x14e828e00, 12) use_count=3 tskv timestamp=2024-05-04T17:33:53.165366 level=INFO module=Impl ( userver/core/src/utils/async.cpp:21 ) task_id=13E826C00 thread_id=0x00000001701CF000 text= trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=3a5474b662484c85 parent_id=8b38aab4df849de7 stopwatch_name=net-resolver-init total_time=0.362584 span_ref_type=child stopwatch_units=ms start_timestamp=1714818833.165008 tskv timestamp=2024-05-04T17:33:53.165384 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001701CF000 text=WakeupOne waiter=(0x14e828e00, 12) use_count=2 tskv timestamp=2024-05-04T17:33:53.165400 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x000000016FF9F000 text=Task with task_id=13E826C00 stopped tskv timestamp=2024-05-04T17:33:53.165412 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x000000016FF9F000 text=task with task_id=14E828E00 created task with task_id=13E604BB0 tskv timestamp=2024-05-04T17:33:53.165441 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:47 ) task_id=13E604BB0 thread_id=0x0000000170143000 text=WaitForEventUntil() tskv timestamp=2024-05-04T17:33:53.165448 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:56 ) task_id=13E604BB0 thread_id=0x0000000170143000 text=iteration() tskv timestamp=2024-05-04T17:33:53.165454 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=13E604BB0 thread_id=0x0000000170143000 text=Append waiter=(0x13e604bb0, 0) use_count=3 tskv timestamp=2024-05-04T17:33:53.165485 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.165501 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FF13000 text=time_left=0.099939208 tskv timestamp=2024-05-04T17:33:53.165549 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:225 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Start writing ptr=0x00006000012A0C00 with custom initial value tskv timestamp=2024-05-04T17:33:53.165555 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x000000016FF9F000 text=Resolving dependency dns-client -> statistics-storage tskv timestamp=2024-05-04T17:33:53.165559 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Committing ptr=0x00006000012A0C00 tskv timestamp=2024-05-04T17:33:53.165571 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x000000016FF9F000 text=component deps: "dns-client" [label="dns-client\nuserver::v2_0_rc::clients::dns::Component"]; "dns-client" -> "logging"; "dns-client" -> "statistics-storage"; "dns-client" -> "tracer" tskv timestamp=2024-05-04T17:33:53.165579 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Retiring ptr=0x00006000012A05E0 tskv timestamp=2024-05-04T17:33:53.165584 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=26dfee00eab04752968fa48ceb0b7434 span_id=8b38aab4df849de7 parent_id= task_id=14E828E00 thread_id=0x000000016FF9F000 text=Started component dns-client tskv timestamp=2024-05-04T17:33:53.165589 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Retire, not used ptr=0x00006000012A05E0 tskv timestamp=2024-05-04T17:33:53.165595 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF9F000 text=WakeupOne waiter=(0x14e606eb0, 2) use_count=2 tskv timestamp=2024-05-04T17:33:53.165599 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=task with task_id=14E82A200 created task with task_id=14E60BC50 tskv timestamp=2024-05-04T17:33:53.165624 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=component deps: "dynamic-config" [label="dynamic-config\nuserver::v2_0_rc::components::DynamicConfig"]; "dynamic-config" -> "alerts-storage"; "dynamic-config" -> "logging"; "dynamic-config" -> "statistics-storage"; "dynamic-config" -> "testsuite-support"; "dynamic-config" -> "tracer" tskv timestamp=2024-05-04T17:33:53.165634 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=0 thread_id=0x00000001700B7000 text=Task with task_id=14E60BC50 stopped tskv timestamp=2024-05-04T17:33:53.165641 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=a6a7ddc2578342cf92f0c66d6777ff90 span_id=0f2c82139384d5f9 parent_id= task_id=14E82A200 thread_id=0x000000017002B000 text=Started component dynamic-config tskv timestamp=2024-05-04T17:33:53.165647 level=INFO module=HttpRequestHandler ( userver/core/src/server/http/http_request_handler.cpp:66 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=Access log is disabled tskv timestamp=2024-05-04T17:33:53.165623 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x0000000170143000 text=Task with task_id=14E828E00 stopped tskv timestamp=2024-05-04T17:33:53.165656 level=INFO module=HttpRequestHandler ( userver/core/src/server/http/http_request_handler.cpp:73 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=Access_tskv log is disabled tskv timestamp=2024-05-04T17:33:53.165668 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=Resolving dependency server -> default-server-middleware-pipeline-builder tskv timestamp=2024-05-04T17:33:53.165684 level=INFO module=ServerImpl ( userver/core/src/server/server.cpp:176 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=Server is created, listening for incoming connections. tskv timestamp=2024-05-04T17:33:53.165686 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=7e7c3ae4489746d9bbd93a6fb465a3cb span_id=6bd337e03914e7bf parent_id= task_id=15000DE00 thread_id=0x000000016FF9F000 text=Resolving dependency manager-controller -> logging tskv timestamp=2024-05-04T17:33:53.165671 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x0000000170143000 text=Task with task_id=14E82A200 stopped tskv timestamp=2024-05-04T17:33:53.165700 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=7e7c3ae4489746d9bbd93a6fb465a3cb span_id=6bd337e03914e7bf parent_id= task_id=15000DE00 thread_id=0x000000016FF9F000 text=component deps: "manager-controller" [label="manager-controller\nuserver::v2_0_rc::components::ManagerControllerComponent"]; "manager-controller" -> "dynamic-config"; "manager-controller" -> "logging"; "manager-controller" -> "statistics-storage" tskv timestamp=2024-05-04T17:33:53.165712 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x000000017025B000 text=queue wait time = 0us tskv timestamp=2024-05-04T17:33:53.165707 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=component deps: "server" [label="server\nuserver::v2_0_rc::components::Server"]; "server" -> "default-server-middleware-pipeline-builder"; "server" -> "dynamic-config"; "server" -> "logging"; "server" -> "statistics-storage"; "server" -> "tracer" tskv timestamp=2024-05-04T17:33:53.165700 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E606EB0 thread_id=0x0000000170143000 text=Append waiter=(0x14e606eb0, 3) use_count=3 tskv timestamp=2024-05-04T17:33:53.165713 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=7e7c3ae4489746d9bbd93a6fb465a3cb span_id=6bd337e03914e7bf parent_id= task_id=15000DE00 thread_id=0x000000016FF9F000 text=Started component manager-controller tskv timestamp=2024-05-04T17:33:53.165721 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=9b7b3496af5b41d9907664abf0198517 span_id=92b20ae405e36680 parent_id= task_id=13E80F600 thread_id=0x00000001700B7000 text=Started component server tskv timestamp=2024-05-04T17:33:53.165732 level=INFO module=Impl ( userver/grpc/src/ugrpc/server/server.cpp:130 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x000000016FF9F000 text=Configuring the gRPC server tskv timestamp=2024-05-04T17:33:53.165799 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=bc840b34786949909e180db182d70eb5 span_id=d173054c2aa7bcf0 parent_id= task_id=14E82F200 thread_id=0x0000000170143000 text=Resolving dependency handler-ping -> dynamic-config tskv timestamp=2024-05-04T17:33:53.165957 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Resolving dependency postgres-db-1 -> statistics-storage tskv timestamp=2024-05-04T17:33:53.165973 level=INFO module=Postgres ( userver/postgresql/src/storages/postgres/component.cpp:128 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Start 1 shards for pg_grpc_service_template_db_1 tskv timestamp=2024-05-04T17:33:53.165985 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Resolving dependency postgres-db-1 -> testsuite-support tskv timestamp=2024-05-04T17:33:53.165991 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001701CF000 text=queue wait time = 9us tskv timestamp=2024-05-04T17:33:53.166001 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Resolving dependency postgres-db-1 -> dns-client tskv timestamp=2024-05-04T17:33:53.166031 level=INFO module=ClusterImpl ( userver/postgresql/src/storages/postgres/detail/cluster_impl.cpp:85 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Creating a cluster in standalone mode tskv timestamp=2024-05-04T17:33:53.166109 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=Resolving dependency grpc-client-factory -> statistics-storage tskv timestamp=2024-05-04T17:33:53.166105 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x000000016FF9F000 text=component deps: "grpc-server" [label="grpc-server\nuserver::v2_0_rc::ugrpc::server::ServerComponent"]; "grpc-server" -> "dynamic-config"; "grpc-server" -> "logging"; "grpc-server" -> "statistics-storage"; "grpc-server" -> "tracer" tskv timestamp=2024-05-04T17:33:53.166120 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001701CF000 text=queue wait time = 2us tskv timestamp=2024-05-04T17:33:53.166125 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=Resolving dependency grpc-client-factory -> dynamic-config tskv timestamp=2024-05-04T17:33:53.166174 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=9dd4cf4cd20f4ce1bf64cad5575a244b span_id=6b7d993dbf52b95d parent_id= task_id=14E82CA00 thread_id=0x00000001700B7000 text=Started component grpc-server tskv timestamp=2024-05-04T17:33:53.166188 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=Resolving dependency grpc-client-factory -> testsuite-support tskv timestamp=2024-05-04T17:33:53.166218 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x000000017025B000 text=queue wait time = 0us tskv timestamp=2024-05-04T17:33:53.166240 level=INFO module=ClusterImpl ( userver/postgresql/src/storages/postgres/detail/cluster_impl.cpp:102 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Starting pools initialization tskv timestamp=2024-05-04T17:33:53.166354 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=component deps: "grpc-client-factory" [label="grpc-client-factory\nuserver::v2_0_rc::ugrpc::client::ClientFactoryComponent"]; "grpc-client-factory" -> "dynamic-config"; "grpc-client-factory" -> "grpc-server"; "grpc-client-factory" -> "logging"; "grpc-client-factory" -> "statistics-storage"; "grpc-client-factory" -> "testsuite-support"; "grpc-client-factory" -> "tracer" tskv timestamp=2024-05-04T17:33:53.166371 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=4537e8ba056b4078a26ec83e4eecdaa1 span_id=a136ea60d20f8466 parent_id= task_id=14E82B600 thread_id=0x00000001700B7000 text=Started component grpc-client-factory tskv timestamp=2024-05-04T17:33:53.166381 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001700B7000 text=WakeupOne waiter=(0x14e606eb0, 3) use_count=2 tskv timestamp=2024-05-04T17:33:53.166630 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x00000001700B7000 text=task with task_id=14E830600 created task with task_id=14E707B30 tskv timestamp=2024-05-04T17:33:53.166646 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x00000001700B7000 text=Append waiter=(0x14e830600, 6) use_count=3 tskv timestamp=2024-05-04T17:33:53.166659 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x00000001700B7000 text=Task with task_id=14E82B600 stopped tskv timestamp=2024-05-04T17:33:53.166667 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x00000001700B7000 text=Task with task_id=14E82CA00 stopped tskv timestamp=2024-05-04T17:33:53.166675 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E606EB0 thread_id=0x00000001700B7000 text=Append waiter=(0x14e606eb0, 4) use_count=3 tskv timestamp=2024-05-04T17:33:53.166658 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001702E7000 text=queue wait time = 9us tskv timestamp=2024-05-04T17:33:53.166711 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000016FF9F000 text=Resolving dependency handler-hello -> postgres-db-1 tskv timestamp=2024-05-04T17:33:53.166724 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000016FF9F000 text=component postgres-db-1 is not loaded yet, component handler-hello is waiting for it to load tskv timestamp=2024-05-04T17:33:53.166746 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x000000017025B000 text=queue wait time = 11us tskv timestamp=2024-05-04T17:33:53.167766 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=task with task_id=13E80E200 created task with task_id=14E82CA00 tskv timestamp=2024-05-04T17:33:53.167783 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001700B7000 text=queue wait time = 4us tskv timestamp=2024-05-04T17:33:53.167786 level=INFO module=Init ( userver/postgresql/src/storages/postgres/detail/pool.cpp:153 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Asynchronously initializing PostgreSQL connection pool, creating up to 4 connections to user=pg_grpc_service_template_user dbname=pg_grpc_service_template_db_1 host=localhost port=5432 tskv timestamp=2024-05-04T17:33:53.167798 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:47 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=b4a7b3e8e54daa69 parent_id=aac0e1146bae222f task_id=14E82CA00 thread_id=0x00000001700B7000 text=WaitForEventUntil() tskv timestamp=2024-05-04T17:33:53.167806 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:56 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=b4a7b3e8e54daa69 parent_id=aac0e1146bae222f task_id=14E82CA00 thread_id=0x00000001700B7000 text=iteration() tskv timestamp=2024-05-04T17:33:53.167814 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=b4a7b3e8e54daa69 parent_id=aac0e1146bae222f task_id=14E82CA00 thread_id=0x00000001700B7000 text=Append waiter=(0x14e82ca00, 0) use_count=3 tskv timestamp=2024-05-04T17:33:53.167914 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=task with task_id=13E80E200 created task with task_id=14E60D800 tskv timestamp=2024-05-04T17:33:53.167929 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=task with task_id=13E80E200 created task with task_id=14E60DC60 tskv timestamp=2024-05-04T17:33:53.167943 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=task with task_id=13E80E200 created task with task_id=14E60E020 tskv timestamp=2024-05-04T17:33:53.167945 level=INFO module=DoConnect ( userver/postgresql/src/storages/postgres/detail/pool.cpp:404 ) task_id=14E60D800 thread_id=0x000000016FF9F000 text=Creating PostgreSQL connection, current pool size: 3 tskv timestamp=2024-05-04T17:33:53.167953 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=task with task_id=13E80E200 created task with task_id=14E60E3E0 tskv timestamp=2024-05-04T17:33:53.167965 level=INFO module=Init ( userver/postgresql/src/storages/postgres/detail/pool.cpp:174 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Pool initialization is ongoing tskv timestamp=2024-05-04T17:33:53.167969 level=INFO module=ConnectionImpl ( userver/postgresql/src/storages/postgres/detail/connection_impl.cpp:216 ) task_id=14E60D800 thread_id=0x000000016FF9F000 text=Userver experiment pg-omit-describe-in-execute is enabled tskv timestamp=2024-05-04T17:33:53.167975 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:215 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Start writing ptr=0x00006000012A0E60 tskv timestamp=2024-05-04T17:33:53.167984 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Committing ptr=0x00006000012A0E60 tskv timestamp=2024-05-04T17:33:53.167993 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Retiring ptr=0x00006000012A0D60 tskv timestamp=2024-05-04T17:33:53.167996 level=INFO module=DoConnect ( userver/postgresql/src/storages/postgres/detail/pool.cpp:404 ) task_id=14E60DC60 thread_id=0x00000001700B7000 text=Creating PostgreSQL connection, current pool size: 4 tskv timestamp=2024-05-04T17:33:53.168003 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Retire, not used ptr=0x00006000012A0D60 tskv timestamp=2024-05-04T17:33:53.168013 level=INFO module=ConnectionImpl ( userver/postgresql/src/storages/postgres/detail/connection_impl.cpp:216 ) task_id=14E60DC60 thread_id=0x00000001700B7000 text=Userver experiment pg-omit-describe-in-execute is enabled tskv timestamp=2024-05-04T17:33:53.168013 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:225 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Start writing ptr=0x0000600001C92BE0 with custom initial value tskv timestamp=2024-05-04T17:33:53.168022 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Committing ptr=0x0000600001C92BE0 tskv timestamp=2024-05-04T17:33:53.168030 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Retiring ptr=0x0000600001C92A00 tskv timestamp=2024-05-04T17:33:53.168039 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Retire, not used ptr=0x0000600001C92A00 tskv timestamp=2024-05-04T17:33:53.168049 level=INFO module=DoStart ( userver/core/src/utils/periodic_task.cpp:54 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Starting PeriodicTask with name=pg_maintain tskv timestamp=2024-05-04T17:33:53.168058 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=task with task_id=13E80E200 created task with task_id=14E60EC40 tskv timestamp=2024-05-04T17:33:53.168069 level=INFO module=ClusterImpl ( userver/postgresql/src/storages/postgres/detail/cluster_impl.cpp:113 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Pools initialized tskv timestamp=2024-05-04T17:33:53.168088 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:215 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Start writing ptr=0x0000600002394780 tskv timestamp=2024-05-04T17:33:53.168097 level=INFO module=Commit ( userver/core/include/userver/rcu/rcu.hpp:247 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Committing ptr=0x0000600002394780 tskv timestamp=2024-05-04T17:33:53.168106 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:473 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Retiring ptr=0x00006000023946E0 tskv timestamp=2024-05-04T17:33:53.168116 level=INFO module=Retire ( userver/core/include/userver/rcu/rcu.hpp:481 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Retire, not used ptr=0x00006000023946E0 tskv timestamp=2024-05-04T17:33:53.168126 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=task with task_id=13E80E200 created task with task_id=14E60E650 tskv timestamp=2024-05-04T17:33:53.168151 level=INFO module=WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:215 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Start writing ptr=0x0000600003184460 tskv timestamp=2024-05-04T17:33:53.168160 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x000000017025B000 text=queue wait time = 0us tskv timestamp=2024-05-04T17:33:53.168161 level=INFO module=~WritablePtr ( userver/core/include/userver/rcu/rcu.hpp:238 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Stop writing ptr=0x0000600003184460 tskv timestamp=2024-05-04T17:33:53.168173 level=INFO module=Postgres ( userver/postgresql/src/storages/postgres/component.cpp:153 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Component ready tskv timestamp=2024-05-04T17:33:53.168183 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=component deps: "postgres-db-1" [label="postgres-db-1\nuserver::v2_0_rc::components::Postgres"]; "postgres-db-1" -> "dns-client"; "postgres-db-1" -> "dynamic-config"; "postgres-db-1" -> "logging"; "postgres-db-1" -> "statistics-storage"; "postgres-db-1" -> "testsuite-support"; "postgres-db-1" -> "tracer" tskv timestamp=2024-05-04T17:33:53.168199 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=8dd4abc0c07543b5bd7b4df219285cd7 span_id=aac0e1146bae222f parent_id= task_id=13E80E200 thread_id=0x000000017002B000 text=Started component postgres-db-1 tskv timestamp=2024-05-04T17:33:53.168194 level=INFO module=AsyncConnect ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:281 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000016FF9F000 text=Connecting to user=pg_grpc_service_template_user dbname=pg_grpc_service_template_db_1 host=localhost,localhost hostaddr=::1,127.0.0.1 port=5432 link=9fd0eb10ffd942939f7f38ef6d33db60 db_type=postgres pg_conn_id=1 tskv timestamp=2024-05-04T17:33:53.168211 level=INFO module=DoConnect ( userver/postgresql/src/storages/postgres/detail/pool.cpp:404 ) task_id=14E60E020 thread_id=0x000000017002B000 text=Creating PostgreSQL connection, current pool size: 4 tskv timestamp=2024-05-04T17:33:53.168223 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000016FF9F000 text=task with task_id=14E60D800 created task with task_id=14E708460 link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.168228 level=INFO module=ConnectionImpl ( userver/postgresql/src/storages/postgres/detail/connection_impl.cpp:216 ) task_id=14E60E020 thread_id=0x000000017002B000 text=Userver experiment pg-omit-describe-in-execute is enabled tskv timestamp=2024-05-04T17:33:53.168236 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000016FF9F000 text=Append waiter=(0x14e60d800, 0) use_count=3 link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.168247 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x000000016FF9F000 text=queue wait time = 284us tskv timestamp=2024-05-04T17:33:53.168254 level=INFO module=DoConnect ( userver/postgresql/src/storages/postgres/detail/pool.cpp:404 ) task_id=14E60E3E0 thread_id=0x000000016FF9F000 text=Creating PostgreSQL connection, current pool size: 4 tskv timestamp=2024-05-04T17:33:53.168257 level=INFO module=AsyncConnect ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:281 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000017002B000 text=Connecting to user=pg_grpc_service_template_user dbname=pg_grpc_service_template_db_1 host=localhost,localhost hostaddr=::1,127.0.0.1 port=5432 link=0953e02df0c14268a124e8728bc871a4 db_type=postgres pg_conn_id=3 tskv timestamp=2024-05-04T17:33:53.168270 level=INFO module=ConnectionImpl ( userver/postgresql/src/storages/postgres/detail/connection_impl.cpp:216 ) task_id=14E60E3E0 thread_id=0x000000016FF9F000 text=Userver experiment pg-omit-describe-in-execute is enabled tskv timestamp=2024-05-04T17:33:53.168285 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000017002B000 text=task with task_id=14E60E020 created task with task_id=14E60E8C0 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.168298 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000017002B000 text=Append waiter=(0x14e60e020, 0) use_count=3 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.168296 level=INFO module=AsyncConnect ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:281 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x000000016FF9F000 text=Connecting to user=pg_grpc_service_template_user dbname=pg_grpc_service_template_db_1 host=localhost,localhost hostaddr=::1,127.0.0.1 port=5432 link=9eb999edb4a44c21a2132d463c4e2613 db_type=postgres pg_conn_id=4 tskv timestamp=2024-05-04T17:33:53.168312 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:47 ) task_id=14E60EC40 thread_id=0x000000017002B000 text=WaitForEventUntil() tskv timestamp=2024-05-04T17:33:53.168299 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001701CF000 text=queue wait time = 1us tskv timestamp=2024-05-04T17:33:53.168318 level=INFO module=WaitForEventUntil ( userver/core/src/engine/single_consumer_event.cpp:56 ) task_id=14E60EC40 thread_id=0x000000017002B000 text=iteration() tskv timestamp=2024-05-04T17:33:53.168321 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x000000016FF9F000 text=task with task_id=14E60E3E0 created task with task_id=14E7086D0 link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.168324 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E60EC40 thread_id=0x000000017002B000 text=Append waiter=(0x14e60ec40, 0) use_count=3 tskv timestamp=2024-05-04T17:33:53.168303 level=INFO module=AsyncConnect ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:281 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=Connecting to user=pg_grpc_service_template_user dbname=pg_grpc_service_template_db_1 host=localhost,localhost hostaddr=::1,127.0.0.1 port=5432 link=54299e0211a14a72851c3147f016bc61 db_type=postgres pg_conn_id=2 tskv timestamp=2024-05-04T17:33:53.168332 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x000000016FF9F000 text=Append waiter=(0x14e60e3e0, 0) use_count=3 link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.168339 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=0 thread_id=0x000000017002B000 text=Task with task_id=14E60E650 stopped tskv timestamp=2024-05-04T17:33:53.168347 level=INFO module=AddDependency ( userver/core/src/components/component_context_impl.cpp:423 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000016FF9F000 text=Resolving dependency handler-hello -> hello-client tskv timestamp=2024-05-04T17:33:53.168351 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=task with task_id=14E60DC60 created task with task_id=13E606100 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.168356 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FE87000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168358 level=INFO module=DoFindComponent ( userver/core/src/components/component_context_impl.cpp:409 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000016FF9F000 text=component hello-client is not loaded yet, component handler-hello is waiting for it to load tskv timestamp=2024-05-04T17:33:53.168364 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FE87000 text=time_left=29.999947583 tskv timestamp=2024-05-04T17:33:53.168363 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60dc60, 0) use_count=3 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.168464 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001702E7000 text=WakeupOne waiter=(0x14e830600, 6) use_count=2 tskv timestamp=2024-05-04T17:33:53.168479 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x000000017002B000 text=Task with task_id=14E707B30 stopped tskv timestamp=2024-05-04T17:33:53.168498 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x000000017002B000 text=component deps: "hello-client" [label="hello-client\npg_grpc_service_template::HelloClient"]; "hello-client" -> "grpc-client-factory"; "hello-client" -> "logging"; "hello-client" -> "tracer" tskv timestamp=2024-05-04T17:33:53.168511 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=2f2e946415ba4d528a078453aec75504 span_id=b6c82a9156b5315d parent_id= task_id=14E830600 thread_id=0x000000017002B000 text=Started component hello-client tskv timestamp=2024-05-04T17:33:53.168528 level=INFO module=AddComponent ( userver/core/src/components/component_context_impl.cpp:126 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000016FF9F000 text=component deps: "handler-hello" [label="handler-hello\npg_grpc_service_template::Hello"]; "handler-hello" -> "grpc-server"; "handler-hello" -> "hello-client"; "handler-hello" -> "logging"; "handler-hello" -> "postgres-db-1"; "handler-hello" -> "tracer" tskv timestamp=2024-05-04T17:33:53.168543 level=INFO module=AddComponentImpl ( userver/core/src/components/manager.cpp:408 ) trace_id=4a5c26d9b9034796a06e6bc2441490c3 span_id=3c453f3053b62062 parent_id= task_id=14E82DE00 thread_id=0x000000016FF9F000 text=Started component handler-hello tskv timestamp=2024-05-04T17:33:53.168553 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF9F000 text=WakeupOne waiter=(0x14e606eb0, 4) use_count=2 tskv timestamp=2024-05-04T17:33:53.168569 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001700B7000 text=queue wait time = 7us tskv timestamp=2024-05-04T17:33:53.168616 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001701CF000 text=WakeupOne waiter=(0x14e60e020, 0) use_count=2 tskv timestamp=2024-05-04T17:33:53.168616 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000017025B000 text=WakeupOne waiter=(0x14e60d800, 0) use_count=2 tskv timestamp=2024-05-04T17:33:53.168623 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) task_id=14E606EB0 thread_id=0x00000001700B7000 text=Task with task_id=14E82DE00 stopped tskv timestamp=2024-05-04T17:33:53.168631 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) task_id=14E606EB0 thread_id=0x00000001700B7000 text=Append waiter=(0x14e606eb0, 5) use_count=3 tskv timestamp=2024-05-04T17:33:53.168644 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000016FF9F000 text=Task with task_id=14E708460 stopped link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.168632 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x000000017002B000 text=queue wait time = 7us tskv timestamp=2024-05-04T17:33:53.168661 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000017025B000 text=WakeupOne waiter=(0x14e60e3e0, 0) use_count=2 tskv timestamp=2024-05-04T17:33:53.168656 level=INFO module=StartAsyncConnect ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:322 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000016FF9F000 text=PQstatus: Waiting for connection to be made link=9fd0eb10ffd942939f7f38ef6d33db60 db_type=postgres pg_conn_id=1 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.168670 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x00000001700B7000 text=Task with task_id=14E7086D0 stopped link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.168681 level=INFO module=StartAsyncConnect ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:322 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x00000001700B7000 text=PQstatus: Waiting for connection to be made link=9eb999edb4a44c21a2132d463c4e2613 db_type=postgres pg_conn_id=4 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.168700 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000017025B000 text=WakeupOne waiter=(0x14e60dc60, 0) use_count=2 tskv timestamp=2024-05-04T17:33:53.168668 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000017002B000 text=Task with task_id=14E60E8C0 stopped link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.168715 level=INFO module=StartAsyncConnect ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:322 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000017002B000 text=PQstatus: Waiting for connection to be made link=0953e02df0c14268a124e8728bc871a4 db_type=postgres pg_conn_id=3 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.168828 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60e3e0, 1) use_count=3 link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.168828 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000016FF9F000 text=Append waiter=(0x14e60d800, 1) use_count=3 link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.168843 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001700B7000 text=queue wait time = 134us tskv timestamp=2024-05-04T17:33:53.168839 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000017002B000 text=Append waiter=(0x14e60e020, 1) use_count=3 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.168850 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=Task with task_id=13E606100 stopped link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.168851 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168862 level=INFO module=StartAsyncConnect ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:322 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=PQstatus: Waiting for connection to be made link=54299e0211a14a72851c3147f016bc61 db_type=postgres pg_conn_id=2 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.168853 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FE87000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168875 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168884 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60dc60, 1) use_count=3 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.168889 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FE87000 text=time_left=1.998305 tskv timestamp=2024-05-04T17:33:53.168894 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168914 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FE87000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168916 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FF13000 text=time_left=1.998379417 tskv timestamp=2024-05-04T17:33:53.168922 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FE87000 text=time_left=1.998380125 tskv timestamp=2024-05-04T17:33:53.168923 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168931 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168937 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FF13000 text=time_left=1.998319708 tskv timestamp=2024-05-04T17:33:53.168943 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.168954 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF13000 text=WakeupOne waiter=(0x14e60d800, 1) use_count=3 tskv timestamp=2024-05-04T17:33:53.168964 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF13000 text=WakeupOne waiter=(0x14e60e020, 1) use_count=3 tskv timestamp=2024-05-04T17:33:53.168968 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000016FF9F000 text=task with task_id=14E60D800 created task with task_id=14E7085A0 link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.168973 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF13000 text=WakeupOne waiter=(0x14e60e3e0, 1) use_count=3 tskv timestamp=2024-05-04T17:33:53.168983 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF13000 text=WakeupOne waiter=(0x14e60dc60, 1) use_count=3 tskv timestamp=2024-05-04T17:33:53.168981 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000016FF9F000 text=Append waiter=(0x14e60d800, 2) use_count=4 link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.168994 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x000000016FF9F000 text=task with task_id=14E60DC60 created task with task_id=14E708800 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.168990 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001700B7000 text=queue wait time = 8us tskv timestamp=2024-05-04T17:33:53.169005 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x000000016FF9F000 text=Append waiter=(0x14e60dc60, 2) use_count=4 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.169010 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x00000001700B7000 text=task with task_id=14E60E3E0 created task with task_id=14F8097F0 link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.169022 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60e3e0, 2) use_count=4 link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.169026 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001701CF000 text=WakeupOne waiter=(0x14e60d800, 2) use_count=3 tskv timestamp=2024-05-04T17:33:53.169012 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000017002B000 text=task with task_id=14E60E020 created task with task_id=115104080 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.169036 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x00000001700B7000 text=Task with task_id=14E7085A0 stopped link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.169047 level=INFO module=WaitConnectionFinish ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:390 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x00000001700B7000 text=PQstatus: Waiting for connection to be made link=9fd0eb10ffd942939f7f38ef6d33db60 db_type=postgres pg_conn_id=1 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.169059 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001701CF000 text=WakeupOne waiter=(0x14e60dc60, 2) use_count=3 tskv timestamp=2024-05-04T17:33:53.169048 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000017002B000 text=Append waiter=(0x14e60e020, 2) use_count=4 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.169067 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60d800, 3) use_count=4 link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.169090 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001701CF000 text=WakeupOne waiter=(0x14e60e020, 2) use_count=3 tskv timestamp=2024-05-04T17:33:53.169091 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FE87000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.169098 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001701CF000 text=queue wait time = 76us tskv timestamp=2024-05-04T17:33:53.169099 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FE87000 text=time_left=1.998093708 tskv timestamp=2024-05-04T17:33:53.169099 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x00000001700B7000 text=Task with task_id=115104080 stopped link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.169110 level=INFO module=WaitConnectionFinish ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:390 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x00000001700B7000 text=PQstatus: Waiting for connection to be made link=0953e02df0c14268a124e8728bc871a4 db_type=postgres pg_conn_id=3 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.169102 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.169124 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001701CF000 text=WakeupOne waiter=(0x14e60e3e0, 2) use_count=3 tskv timestamp=2024-05-04T17:33:53.169129 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60e020, 3) use_count=4 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.169134 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x000000017002B000 text=Task with task_id=14F8097F0 stopped link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.169108 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x000000016FF9F000 text=Task with task_id=14E708800 stopped link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.169145 level=INFO module=WaitConnectionFinish ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:390 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x000000017002B000 text=PQstatus: Waiting for connection to be made link=9eb999edb4a44c21a2132d463c4e2613 db_type=postgres pg_conn_id=4 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.169165 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x000000017002B000 text=Append waiter=(0x14e60e3e0, 3) use_count=4 link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.169139 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF13000 text=WakeupOne waiter=(0x14e60d800, 3) use_count=3 tskv timestamp=2024-05-04T17:33:53.169153 level=INFO module=WaitConnectionFinish ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:390 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x000000016FF9F000 text=PQstatus: Waiting for connection to be made link=54299e0211a14a72851c3147f016bc61 db_type=postgres pg_conn_id=2 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.169202 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x000000016FF9F000 text=Append waiter=(0x14e60dc60, 3) use_count=4 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.169238 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x00000001700B7000 text=task with task_id=14E60D800 created task with task_id=13E7044E0 link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.169244 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FE87000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.169251 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FE87000 text=time_left=1.99805025 tskv timestamp=2024-05-04T17:33:53.169252 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60d800, 4) use_count=4 link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.169206 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.169276 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.169283 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FF13000 text=time_left=1.997974458 tskv timestamp=2024-05-04T17:33:53.169289 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.169290 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001701CF000 text=WakeupOne waiter=(0x14e60d800, 4) use_count=3 tskv timestamp=2024-05-04T17:33:53.169296 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.169303 level=INFO module=DoArmTimerInEvThread ( userver/core/src/engine/task/context_timer.cpp:144 ) task_id=0 thread_id=0x000000016FF13000 text=time_left=1.997992584 tskv timestamp=2024-05-04T17:33:53.169309 level=INFO module=UpdateLoopWatcherImpl ( userver/core/src/engine/ev/thread.cpp:239 ) task_id=0 thread_id=0x000000016FF13000 text=Thread::UpdateLoopWatcherImpl(), userver::v2_0_rc::engine::ev::AsyncPayloadBase tskv timestamp=2024-05-04T17:33:53.169319 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF13000 text=WakeupOne waiter=(0x14e60e020, 3) use_count=3 tskv timestamp=2024-05-04T17:33:53.169328 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF13000 text=WakeupOne waiter=(0x14e60e3e0, 3) use_count=3 tskv timestamp=2024-05-04T17:33:53.169304 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x000000017002B000 text=queue wait time = 6us tskv timestamp=2024-05-04T17:33:53.169336 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x000000016FF13000 text=WakeupOne waiter=(0x14e60dc60, 3) use_count=3 tskv timestamp=2024-05-04T17:33:53.169341 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000017002B000 text=Task with task_id=13E7044E0 stopped link=9fd0eb10ffd942939f7f38ef6d33db60 tskv timestamp=2024-05-04T17:33:53.169361 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x00000001700B7000 text=task with task_id=14E60E3E0 created task with task_id=13E605FD0 link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.169363 level=WARNING module=CheckError ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:154 ) trace_id=ba9ca2b0562949d9b9a4a898ceeb8edc span_id=d32ef9e6ca7e86f5 parent_id= task_id=14E60D800 thread_id=0x000000017002B000 text=libpq PQconnectPoll error: connection to server at "::1", port 5432 failed: Connection refused\n\tIs the server running on that host and accepting TCP/IP connections?\nconnection to server at "127.0.0.1", port 5432 failed: Connection refused\n\tIs the server running on that host and accepting TCP/IP connections?\n. It may be useful to check the user's connection quota in the cloud or the server configuration link=9fd0eb10ffd942939f7f38ef6d33db60 db_type=postgres pg_conn_id=1 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.169375 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=4e283eeb4b574d00a33c3b5a65e084e4 span_id=6880b40f93500078 parent_id= task_id=14E60E3E0 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60e3e0, 4) use_count=4 link=9eb999edb4a44c21a2132d463c4e2613 tskv timestamp=2024-05-04T17:33:53.169386 level=INFO module=CheckWaitTime ( userver/core/src/engine/task/task_processor.cpp:286 ) task_id=0 thread_id=0x00000001700B7000 text=queue wait time = 43us tskv timestamp=2024-05-04T17:33:53.169394 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=task with task_id=14E60DC60 created task with task_id=13E607430 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.169404 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) task_id=0 thread_id=0x00000001701CF000 text=WakeupOne waiter=(0x14e60e3e0, 4) use_count=3 tskv timestamp=2024-05-04T17:33:53.169406 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=Append waiter=(0x14e60dc60, 4) use_count=4 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.169416 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=WakeupOne waiter=(0x14e60dc60, 4) use_count=4 link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.169427 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=Task with task_id=13E607430 stopped link=54299e0211a14a72851c3147f016bc61 tskv timestamp=2024-05-04T17:33:53.169401 level=INFO module=TaskContext ( userver/core/src/engine/task/task_context.cpp:121 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000016FF9F000 text=task with task_id=14E60E020 created task with task_id=115104080 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.169445 level=INFO module=GetSignalOrAppend ( userver/core/src/engine/impl/wait_list_light.cpp:94 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000016FF9F000 text=Append waiter=(0x14e60e020, 4) use_count=4 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.169437 level=WARNING module=CheckError ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:154 ) trace_id=106e4928f27b4151b6bc89dcba6aabb4 span_id=614ffb68d17309e7 parent_id= task_id=14E60DC60 thread_id=0x00000001700B7000 text=libpq PQconnectPoll error: connection to server at "::1", port 5432 failed: Connection refused\n\tIs the server running on that host and accepting TCP/IP connections?\nconnection to server at "127.0.0.1", port 5432 failed: Connection refused\n\tIs the server running on that host and accepting TCP/IP connections?\n. It may be useful to check the user's connection quota in the cloud or the server configuration link=54299e0211a14a72851c3147f016bc61 db_type=postgres pg_conn_id=2 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 tskv timestamp=2024-05-04T17:33:53.169456 level=INFO module=DoWakeup ( userver/core/src/engine/impl/wait_list_light.cpp:63 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000016FF9F000 text=WakeupOne waiter=(0x14e60e020, 4) use_count=4 link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.169467 level=INFO module=~TaskContext ( userver/core/src/engine/task/task_context.cpp:130 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000016FF9F000 text=Task with task_id=115104080 stopped link=0953e02df0c14268a124e8728bc871a4 tskv timestamp=2024-05-04T17:33:53.169477 level=WARNING module=CheckError ( userver/postgresql/src/storages/postgres/detail/pg_connection_wrapper.cpp:154 ) trace_id=eca685a3481a4984ac60ddd990ceddfb span_id=cb0529d7b66973f4 parent_id= task_id=14E60E020 thread_id=0x000000016FF9F000 text=libpq PQconnectPoll error: connection to server at "::1", port 5432 failed: Connection refused\n\tIs the server running on that host and accepting TCP/IP connections?\nconnection to server at "127.0.0.1", port 5432 failed: Connection refused\n\tIs the server running on that host and accepting TCP/IP connections?\n. It may be useful to check the user's connection quota in the cloud or the server configuration link=0953e02df0c14268a124e8728bc871a4 db_type=postgres pg_conn_id=3 db_instance=pg_grpc_service_template_db_1 peer_address=localhost:5432 Process 45420 stopped * thread #4, name = 'main-worker_0', stop reason = EXC_BAD_ACCESS (code=261, address=0xdac11410) frame #0: 0x00000001938e394c libunwind.dylib`_Unwind_GetIP + 224 libunwind.dylib`_Unwind_GetIP: -> 0x1938e394c <+224>: autib x16, x0 0x1938e3950 <+228>: mov x17, x16 0x1938e3954 <+232>: xpaci x17 0x1938e3958 <+236>: cmp x16, x17 thread #5, name = 'main-worker_1', stop reason = EXC_BAD_ACCESS (code=261, address=0xdac11410) frame #0: 0x00000001938e394c libunwind.dylib`_Unwind_GetIP + 224 libunwind.dylib`_Unwind_GetIP: -> 0x1938e394c <+224>: autib x16, x0 0x1938e3950 <+228>: mov x17, x16 0x1938e3954 <+232>: xpaci x17 0x1938e3958 <+236>: cmp x16, x17 thread #6, name = 'main-worker_2', stop reason = EXC_BAD_ACCESS (code=261, address=0xdac11410) frame #0: 0x00000001938e394c libunwind.dylib`_Unwind_GetIP + 224 libunwind.dylib`_Unwind_GetIP: -> 0x1938e394c <+224>: autib x16, x0 0x1938e3950 <+228>: mov x17, x16 0x1938e3954 <+232>: xpaci x17 0x1938e3958 <+236>: cmp x16, x17 * thread #4, name = 'main-worker_0', stop reason = EXC_BAD_ACCESS (code=261, address=0xdac11410) * frame #0: 0x00000001938e394c libunwind.dylib`_Unwind_GetIP + 224 frame #1: 0x0000000186c60aa4 libc++abi.dylib`__gxx_personality_v0 + 588 frame #2: 0x0000000102cc4930 libunwind.1.dylib`unwind_phase2 + 388 frame #3: 0x0000000102cc4b68 libunwind.1.dylib`_Unwind_Resume + 100 frame #4: 0x00000001000dc570 pg_grpc_service_template`void userver::v2_0_rc::storages::postgres::detail::PGConnectionWrapper::CheckError<userver::v2_0_rc::storages::postgres::ConnectionError>(this=0x000000014e82a458, cmd="PQconnectPoll", pg_dispatch_result=0) at pg_connection_wrapper.cpp:154:5 frame #5: 0x00000001000db09c pg_grpc_service_template`userver::v2_0_rc::storages::postgres::detail::PGConnectionWrapper::WaitConnectionFinish(this=0x000000014e82a458, deadline=userver::v2_0_rc::storages::postgres::detail::PGConnectionWrapper::Deadline @ 0x0000000104d32d20, dsn=0x0000000104d33478) at pg_connection_wrapper.cpp:387:7 frame #6: 0x00000001000d9d4c pg_grpc_service_template`userver::v2_0_rc::storages::postgres::detail::PGConnectionWrapper::AsyncConnect(this=0x000000014e82a458, dsn=0x0000000104d33478, deadline=userver::v2_0_rc::storages::postgres::detail::PGConnectionWrapper::Deadline @ 0x0000000104d330f0, scope=0x0000000104d33318) at pg_connection_wrapper.cpp:291:3 frame #7: 0x00000001000aff0c pg_grpc_service_template`userver::v2_0_rc::storages::postgres::detail::ConnectionImpl::AsyncConnect(this=0x000000014e82a400, dsn=0x0000000104d33478, deadline=Deadline @ 0x0000000104d333b0) at connection_impl.cpp:228:17 frame #8: 0x00000001000a8590 pg_grpc_service_template`userver::v2_0_rc::storages::postgres::detail::Connection::Connect(dsn=0x000000014e829688, resolver=0x000000014e810410, bg_task_processor=0x000000014e80fe00, bg_task_storage=0x000000014e8298e0, id=3, settings=ConnectionSettings @ 0x0000000104d337b0, default_cmd_ctls=0x000000014e829e08, testsuite_pg_ctl=0x000000014e829e18, ei_settings=0x000000014e829e30, size_lock=0x0000000104d33a30) at connection.cpp:36:21 frame #9: 0x00000001000f22c0 pg_grpc_service_template`userver::v2_0_rc::storages::postgres::detail::ConnectionPool::DoConnect(this=0x000000014e829420, size_lock=SemaphoreLock @ 0x0000000104d33a30) at pool.cpp:417:18 frame #10: 0x00000001000fb57c pg_grpc_service_template`userver::v2_0_rc::storages::postgres::detail::ConnectionPool::Connect(userver::v2_0_rc::engine::SemaphoreLock)::$_7::operator()(this=0x000000014e60e268) at pool.cpp:398:12 frame #11: 0x00000001000fb4dc pg_grpc_service_template`decltype(std::declval<userver::v2_0_rc::storages::postgres::detail::ConnectionPool::Connect(userver::v2_0_rc::engine::SemaphoreLock)::$_7>()()) std::__1::__invoke[abi:ue170006]<userver::v2_0_rc::storages::postgres::detail::ConnectionPool::Connect(userver::v2_0_rc::engine::SemaphoreLock)::$_7>(__f=0x000000014e60e268) at invoke.h:340:25 frame #12: 0x00000001000fb4b4 pg_grpc_service_template`decltype(auto) std::__1::__apply_tuple_impl[abi:ue170006]<userver::v2_0_rc::storages::postgres::detail::ConnectionPool::Connect(userver::v2_0_rc::engine::SemaphoreLock)::$_7, std::__1::tuple<>>(__f=0x000000014e60e268, __t=size=0, (null)=__tuple_indices<> @ 0x0000000104d33aaf) at tuple:1825:1 frame #13: 0x00000001000fb318 pg_grpc_service_template`decltype(auto) std::__1::apply[abi:ue170006]<userver::v2_0_rc::storages::postgres::detail::ConnectionPool::Connect(userver::v2_0_rc::engine::SemaphoreLock)::$_7, std::__1::tuple<>>(__f=0x000000014e60e268, __t=size=0) at tuple:1834:1 frame #14: 0x00000001000faa94 pg_grpc_service_template`userver::v2_0_rc::utils::impl::WrappedCallImpl<userver::v2_0_rc::storages::postgres::detail::ConnectionPool::Connect(userver::v2_0_rc::engine::SemaphoreLock)::$_7>::Perform(this=0x000000014e60e250) at wrapped_call.hpp:109:25 frame #15: 0x0000000100531794 pg_grpc_service_template`userver::v2_0_rc::engine::impl::TaskContext::CoroFunc(task_pipe=0x0000000104d33ce8) at task_context.cpp:512:30 frame #16: 0x0000000100544e7c pg_grpc_service_template`boost::coroutines2::detail::push_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>::control_block::control_block<boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (this=0x0000000104d33f18, c=0x0000000104d33df0)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&)>(boost::context::preallocated, boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&))::'lambda'(boost::context::fiber&&)::operator()(boost::context::fiber&&) at push_control_block_cc.ipp:90:19 frame #17: 0x0000000100544d98 pg_grpc_service_template`decltype(std::declval<boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&>()(std::declval<boost::context::fiber>())) std::__1::__invoke[abi:ue170006]<boost::coroutines2::detail::push_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>::control_block::control_block<boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&)>(boost::context::preallocated, boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&))::'lambda'(boost::context::fiber&&)&, boost::context::fiber>(__f=0x0000000104d33f18, __args=0x0000000104d33df0) at invoke.h:340:25 frame #18: 0x0000000100544d64 pg_grpc_service_template`std::__1::invoke_result<boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, boost::context::fiber>::type std::__1::invoke[abi:ue170006]<boost::coroutines2::detail::push_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>::control_block::control_block<boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&)>(boost::context::preallocated, boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&))::'lambda'(boost::context::fiber&&)&, boost::context::fiber>(__f=0x0000000104d33f18, __args=0x0000000104d33df0) at invoke.h:30:12 frame #19: 0x0000000100544c9c pg_grpc_service_template`boost::context::detail::fiber_record<boost::context::fiber, boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, boost::coroutines2::detail::push_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>::control_block::control_block<boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&)>(boost::context::preallocated, boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&))::'lambda'(boost::context::fiber&&)>::run(this=0x0000000104d33f00, fctx=0x000000016fdfd7e0) at fiber_fcontext.hpp:157:17 frame #20: 0x0000000100544af8 pg_grpc_service_template`void boost::context::detail::fiber_entry<boost::context::detail::fiber_record<boost::context::fiber, boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, boost::coroutines2::detail::push_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>::control_block::control_block<boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&)>(boost::context::preallocated, boost::context::basic_protected_fixedsize_stack<boost::context::stack_traits>&, void (* const&)(boost::coroutines2::detail::pull_coroutine<userver::v2_0_rc::engine::impl::TaskContext*>&))::'lambda'(boost::context::fiber&&)>>(t=(fctx = 0x000000016fdfd7e0, data = 0x0000000000000000)) at fiber_fcontext.hpp:97:23 frame #21: 0x0000000102aabc38 libboost_context-mt.dylib`make_fcontext + 24
The text was updated successfully, but these errors were encountered:
No branches or pull requests
OS:
MacOS arm64Compiler:
AppleClangInstall
userverRun
pg_grpc_service_templatemake test-debuglldb ./pg_grpc_service_template -- -c ../configs/static_config.yaml --config_vars ../configs/config_vars.yamlThe text was updated successfully, but these errors were encountered: