Open root-kidik opened 4 months ago
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/python@3.12/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/python@3.12/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/python@3.12/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/python@3.12/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/python@3.12/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/python@3.12/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/python@3.12/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/python@3.12/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/python@3.12/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
OS:
MacOS arm64
Compiler:AppleClang
Install
userver
Run
pg_grpc_service_template
make test-debug
lldb ./pg_grpc_service_template -- -c ../configs/static_config.yaml --config_vars ../configs/config_vars.yaml