2025-11-01 12:54:04,514 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 12:54:04,515 - INFO - ya.test - pytest_runtest_setup: test_retryable_iam_error[tables_format_v1]
2025-11-01 12:54:04,515 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 12:54:04,515 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 12:54:04,517 - DEBUG - root - setup_method: Test started: test_retryable_iam_error
2025-11-01 12:54:04,524 - DEBUG - root - setup_method: Kikimr working dir: /home/runner/.ya/build/build_root/y7xs/003267/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk35/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_retryable_iam_error.tables_format_v0
2025-11-01 12:54:04,525 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y7xs/003267/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk35/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_retryable_iam_error.tables_format_v0/cluster/kikimr_configs"
2025-11-01 12:54:04,525 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y7xs/003267/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/y7xs/003267/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk35/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_retryable_iam_error.tables_format_v0/cluster/node_1/logfile__mqsqn84.log --grpc-port=18201 --mon-port=9161 --ic-port=3106 --sqs-port=13482
2025-11-01 12:54:04,526 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18201/1
2025-11-01 12:54:04,528 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.5
2025-11-01 12:54:04,529 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.5
2025-11-01 12:54:04,532 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y7xs/003267/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 12:54:04,550 - DEBUG - ya.test - execute: Command pid: 691054
2025-11-01 12:54:06,415 - DEBUG - ya.test - _save_outputs: Command (pid 691054) rc: 0
2025-11-01 12:54:06,415 - DEBUG - ya.test - _save_outputs: Command (pid 691054) elapsed time (sec): 1.8632173538208008
2025-11-01 12:54:06,416 - DEBUG - ya.test - _save_outputs: Command (pid 691054) idrss: 0
2025-11-01 12:54:06,416 - DEBUG - ya.test - _save_outputs: Command (pid 691054) inblock: 0
2025-11-01 12:54:06,416 - DEBUG - ya.test - _save_outputs: Command (pid 691054) isrss: 0
2025-11-01 12:54:06,417 - DEBUG - ya.test - _save_outputs: Command (pid 691054) ixrss: 0
2025-11-01 12:54:06,417 - DEBUG - ya.test - _save_outputs: Command (pid 691054) majflt: 0
2025-11-01 12:54:06,417 - DEBUG - ya.test - _save_outputs: Command (pid 691054) maxrss: 630804
2025-11-01 12:54:06,418 - DEBUG - ya.test - _save_outputs: Command (pid 691054) minflt: 45281
2025-11-01 12:54:06,418 - DEBUG - ya.test - _save_outputs: Command (pid 691054) msgrcv: 0
2025-11-01 12:54:06,424 - DEBUG - ya.test - _save_outputs: Command (pid 691054) msgsnd: 0
2025-11-01 12:54:06,424 - DEBUG - ya.test - _save_outputs: Command (pid 691054) nivcsw: 305
2025-11-01 12:54:06,425 - DEBUG - ya.test - _save_outputs: Command (pid 691054) nsignals: 0
2025-11-01 12:54:06,425 - DEBUG - ya.test - _save_outputs: Command (pid 691054) nswap: 0
2025-11-01 12:54:06,425 - DEBUG - ya.test - _save_outputs: Command (pid 691054) nvcsw: 15
2025-11-01 12:54:06,426 - DEBUG - ya.test - _save_outputs: Command (pid 691054) oublock: 8
2025-11-01 12:54:06,426 - DEBUG - ya.test - _save_outputs: Command (pid 691054) stime: 0.285498
2025-11-01 12:54:06,426 - DEBUG - ya.test - _save_outputs: Command (pid 691054) utime: 0.6415839999999999
2025-11-01 12:54:06,427 - DEBUG - ya.test - _save_outputs: Command (pid 691054) wtime: 1.882
2025-11-01 12:54:06,427 - DEBUG - ya.test - _save_outputs: Command (pid 691054) output:
Git info:
    Commit: 235dcd359a84997318c3b5f36e753769be4ef7a8
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 91aa06e47261e68d302c37f4fab09afd0c375532 into db869a148f5b0d22f563c440adb77c0a81fa51d7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ebytu4uhfq
    Host information: 
        Linux ghrun-ebytu4uhfq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-01 12:54:06,456 - DEBUG - ya.test - _save_outputs: Command (pid 691054) errors:

2025-11-01 12:54:06,456 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 235dcd359a84997318c3b5f36e753769be4ef7a8
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 91aa06e47261e68d302c37f4fab09afd0c375532 into db869a148f5b0d22f563c440adb77c0a81fa51d7
2025-11-01 12:54:06,457 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y7xs/003267/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk35/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_retryable_iam_error.tables_format_v0/cluster/kikimr_configs"
2025-11-01 12:54:06,457 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y7xs/003267/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:18201 --tenant=/Root/TenantSQS --log-file-name=/home/runner/.ya/build/build_root/y7xs/003267/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk35/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_retryable_iam_error.tables_format_v0/cluster/slot_1/logfile__ijjzmlt.log --grpc-port=19458 --mon-port=15444 --ic-port=32587 --sqs-port=3803
2025-11-01 12:54:06,458 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:19458/1
2025-11-01 12:54:06,472 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.6
2025-11-01 12:54:06,473 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.6
2025-11-01 12:54:06,474 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y7xs/003267/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 12:54:06,496 - DEBUG - ya.test - execute: Command pid: 691458
2025-11-01 12:54:08,788 - DEBUG - ya.test - _save_outputs: Command (pid 691458) rc: 0
2025-11-01 12:54:08,789 - DEBUG - ya.test - _save_outputs: Command (pid 691458) elapsed time (sec): 2.291278123855591
2025-11-01 12:54:08,789 - DEBUG - ya.test - _save_outputs: Command (pid 691458) idrss: 0
2025-11-01 12:54:08,789 - DEBUG - ya.test - _save_outputs: Command (pid 691458) inblock: 0
2025-11-01 12:54:08,790 - DEBUG - ya.test - _save_outputs: Command (pid 691458) isrss: 0
2025-11-01 12:54:08,790 - DEBUG - ya.test - _save_outputs: Command (pid 691458) ixrss: 0
2025-11-01 12:54:08,790 - DEBUG - ya.test - _save_outputs: Command (pid 691458) majflt: 0
2025-11-01 12:54:08,791 - DEBUG - ya.test - _save_outputs: Command (pid 691458) maxrss: 631212
2025-11-01 12:54:08,791 - DEBUG - ya.test - _save_outputs: Command (pid 691458) minflt: 45350
2025-11-01 12:54:08,839 - DEBUG - ya.test - _save_outputs: Command (pid 691458) msgrcv: 0
2025-11-01 12:54:08,840 - DEBUG - ya.test - _save_outputs: Command (pid 691458) msgsnd: 0
2025-11-01 12:54:08,840 - DEBUG - ya.test - _save_outputs: Command (pid 691458) nivcsw: 295
2025-11-01 12:54:08,841 - DEBUG - ya.test - _save_outputs: Command (pid 691458) nsignals: 0
2025-11-01 12:54:08,841 - DEBUG - ya.test - _save_outputs: Command (pid 691458) nswap: 0
2025-11-01 12:54:08,849 - DEBUG - ya.test - _save_outputs: Command (pid 691458) nvcsw: 14
2025-11-01 12:54:08,849 - DEBUG - ya.test - _save_outputs: Command (pid 691458) oublock: 8
2025-11-01 12:54:08,849 - DEBUG - ya.test - _save_outputs: Command (pid 691458) stime: 0.277219
2025-11-01 12:54:08,850 - DEBUG - ya.test - _save_outputs: Command (pid 691458) utime: 0.646354
2025-11-01 12:54:08,850 - DEBUG - ya.test - _save_outputs: Command (pid 691458) wtime: 2.313
2025-11-01 12:54:08,851 - DEBUG - ya.test - _save_outputs: Command (pid 691458) output:
Git info:
    Commit: 235dcd359a84997318c3b5f36e753769be4ef7a8
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 91aa06e47261e68d302c37f4fab09afd0c375532 into db869a148f5b0d22f563c440adb77c0a81fa51d7

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ebytu4uhfq
    Host information: 
        Linux ghrun-ebytu4uhfq 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-01 12:54:08,851 - DEBUG - ya.test - _save_outputs: Command (pid 691458) errors:

2025-11-01 12:54:08,861 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 235dcd359a84997318c3b5f36e753769be4ef7a8
    Branch: 
    Author: Innokentii Mokin <enjection@ya.ru>
    Summary: Merge 91aa06e47261e68d302c37f4fab09afd0c375532 into db869a148f5b0d22f563c440adb77c0a81fa51d7
2025-11-01 12:54:08,865 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:15444
2025-11-01 12:54:08,904 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:15444 "GET /tablets/app?TabletID=72057594037968897&node=1&page=SetDown&down=0 HTTP/1.1" 200 33
2025-11-01 12:54:08,905 - DEBUG - root - _enable_tablets_on_node: Toggle up reply: {"NodeId":1,"Down":false}
2025-11-01 12:54:08,912 - INFO - ydb.tests.library.sqs.test_base - wait_can_list_users: Listing SQS users
2025-11-01 12:54:08,913 - DEBUG - ydb.tests.library.sqs.requests_client - execute_request: Execute request POST http://localhost:3803 from user U_retryable_iam_error_db45b46b-b721-11f0-b32e-d00d894b3bc7 with params: Action=ListQueues
2025-11-01 12:54:08,927 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:3803
2025-11-01 12:54:08,932 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:3803 "POST / HTTP/1.1" 400 278
2025-11-01 12:54:08,953 - DEBUG - charset_normalizer - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-01 12:54:08,953 - WARNING - ydb.tests.library.sqs.requests_client - _process_response: Last request failed with code 400, reason 'Bad request' and text '<?xml version="1.0" encoding="UTF-8"?>
<ErrorResponse><Error><Message>Credential should be scoped to a valid region, not 'yandex'. Request id to report: d52a6062-4139010-5c57d8a4-ab0414df.</Message><Code>IncompleteSignature</Code></Error><RequestId></RequestId></ErrorResponse>
'
2025-11-01 12:54:08,954 - DEBUG - charset_normalizer - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-01 12:54:08,958 - DEBUG - charset_normalizer - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-01 12:54:08,958 - INFO - ydb.tests.library.sqs.test_base - wait_can_list_users: Success. Api is up.
2025-11-01 12:54:08,960 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 12:54:08,980 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 12:54:08,981 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 12:54:09,019 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/TenantSQS
2025-11-01 12:54:09,020 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 035051db-bd80-46bf-b716-6ff24cf498c5, localhost:19458): creating call state
2025-11-01 12:54:09,021 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 035051db-bd80-46bf-b716-6ff24cf498c5, localhost:19458): request = { database: "/Root/TenantSQS" }
2025-11-01 12:54:09,033 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 035051db-bd80-46bf-b716-6ff24cf498c5, localhost:19458): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ebytu4uhfq.auto.internal" port: 19458 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } } } } }
2025-11-01 12:54:09,034 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/TenantSQS: DiscoveryResult <self_location: , endpoints [<Endpoint ghrun-ebytu4uhfq.auto.internal:19458, location , ssl: False>]>
2025-11-01 12:54:09,035 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 12:54:09,084 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:19458
2025-11-01 12:54:09,088 - INFO - root - _before_test_start: run test with cloud_id=CLOUD_FOR_folder_acc_db615937-b721-11f0-ad22-d00d894b3bc7 folder_id=folder_acc_db615937-b721-11f0-ad22-d00d894b3bc7
2025-11-01 12:54:09,090 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_yandex_cloud_mode.py::TestSqsYandexCloudMode, test_name: test_retryable_iam_error[tables_format_v1])
2025-11-01 12:54:09,090 - DEBUG - root - _execute_yql_query: Executing database query: "UPSERT INTO `/Root/TenantSQS/.Settings` (Account, Name, Value)                 VALUES ("CLOUD_FOR_folder_acc_db615937-b721-11f0-ad22-d00d894b3bc7", "CreateQueuesWithTabletFormat", "1")"
2025-11-01 12:54:09,091 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8eefd297-032f-4a0e-9fe9-8482802e41f2, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:54:09,092 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8eefd297-032f-4a0e-9fe9-8482802e41f2, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 12:54:09,111 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8eefd297-032f-4a0e-9fe9-8482802e41f2, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=MjI0ZWQxMy1lYjVkN2IxLTYxZWFiOTM3LTc2MGM2OTI0" } } } }
2025-11-01 12:54:09,112 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 55bfec2a-bae8-48d4-a3ee-0bf7f03378bb, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:54:09,113 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 55bfec2a-bae8-48d4-a3ee-0bf7f03378bb, ghrun-ebytu4uhfq.auto.internal:19458): request = { session_id: "ydb://session/3?node_id=50000&id=MjI0ZWQxMy1lYjVkN2IxLTYxZWFiOTM3LTc2MGM2OTI0" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "UPSERT INTO `/Root/TenantSQS/.Settings` (Account, Name, Value)                 VALUES (\"CLOUD_FOR_folder_acc_db615937-b721-11f0-ad22-d00d894b3bc7\", \"CreateQueuesWithTabletFormat\", \"1\")" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-01 12:54:09,295 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 55bfec2a-bae8-48d4-a3ee-0bf7f03378bb, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-01 12:54:09,326 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from creating-client-class.iot-data to creating-client-class.iot-data-plane
2025-11-01 12:54:09,344 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-call.apigateway to before-call.api-gateway
2025-11-01 12:54:09,357 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from request-created.machinelearning.Predict to request-created.machine-learning.Predict
2025-11-01 12:54:09,373 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.autoscaling.CreateLaunchConfiguration to before-parameter-build.auto-scaling.CreateLaunchConfiguration
2025-11-01 12:54:09,374 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.route53 to before-parameter-build.route-53
2025-11-01 12:54:09,380 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from request-created.cloudsearchdomain.Search to request-created.cloudsearch-domain.Search
2025-11-01 12:54:09,387 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.autoscaling.CreateLaunchConfiguration.complete-section to docs.*.auto-scaling.CreateLaunchConfiguration.complete-section
2025-11-01 12:54:09,416 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.logs.CreateExportTask to before-parameter-build.cloudwatch-logs.CreateExportTask
2025-11-01 12:54:09,417 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.logs.CreateExportTask.complete-section to docs.*.cloudwatch-logs.CreateExportTask.complete-section
2025-11-01 12:54:09,418 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.cloudsearchdomain.Search to before-parameter-build.cloudsearch-domain.Search
2025-11-01 12:54:09,419 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.cloudsearchdomain.Search.complete-section to docs.*.cloudsearch-domain.Search.complete-section
2025-11-01 12:54:09,434 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/endpoints.json
2025-11-01 12:54:09,603 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sdk-default-configuration.json
2025-11-01 12:54:09,605 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7c5798d8fe30>
2025-11-01 12:54:09,609 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sqs/2012-11-05/service-2.json
2025-11-01 12:54:09,622 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sqs/2012-11-05/endpoint-rule-set-1.json
2025-11-01 12:54:09,625 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/partitions.json
2025-11-01 12:54:09,629 - DEBUG - botocore.hooks - _emit: Event creating-client-class.sqs: calling handler <function add_generate_presigned_url at 0x7c57992f2c70>
2025-11-01 12:54:09,631 - DEBUG - botocore.regions - _endpoint_for_partition: Creating a regex based endpoint for sqs, ru-central1
2025-11-01 12:54:09,643 - DEBUG - botocore.endpoint - create_endpoint: Setting sqs timeout as (60, 60)
2025-11-01 12:54:09,652 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/_retry.json
2025-11-01 12:54:09,660 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: sqs
2025-11-01 12:54:09,664 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.sqs.ListQueues: calling handler <function generate_idempotent_uuid at 0x7c5798d90990>
2025-11-01 12:54:09,666 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Region': 'ru-central1', 'UseDualStack': False, 'UseFIPS': False, 'Endpoint': 'http://localhost:3803'}
2025-11-01 12:54:09,667 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:3803
2025-11-01 12:54:09,669 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.ListQueues: calling handler <function add_recursion_detection_header at 0x7c5798d906f0>
2025-11-01 12:54:09,669 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.ListQueues: calling handler <function inject_api_version_header_if_needed at 0x7c5798d95a10>
2025-11-01 12:54:09,670 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=ListQueues) with params: {'url_path': '/', 'query_string': '', 'method': 'POST', 'headers': {'Content-Type': 'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': 'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89'}, 'body': {'Action': 'ListQueues', 'Version': '2012-11-05'}, 'url': 'http://localhost:3803/', 'context': {'client_region': 'ru-central1', 'client_config': <botocore.config.Config object at 0x7bb79b3687f0>, 'has_streaming_input': False, 'auth_type': None}}
2025-11-01 12:54:09,674 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bb79b3687b0>>
2025-11-01 12:54:09,676 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.ListQueues: calling handler <function set_operation_specific_signer at 0x7c5798d90c30>
2025-11-01 12:54:09,678 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-01 12:54:09,678 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:3803
x-amz-date:20251101T125409Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
48a38266faf90970d6c7fea9b15e6ba366e5f6397c2970fc893f8a7b5e207bd0
2025-11-01 12:54:09,679 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251101T125409Z
20251101/ru-central1/sqs/aws4_request
6aa4a54fd6627c672cda19333fea3f8855422a3b4c0b63fe2399080109055c15
2025-11-01 12:54:09,682 - DEBUG - botocore.auth - add_auth: Signature:
49a6541c3bce5375f24a43182078cec6796efee57ba0e58b274ffb554cfad169
2025-11-01 12:54:09,682 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <function add_retry_headers at 0x7c5798d96030>
2025-11-01 12:54:09,683 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:3803/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89', 'X-Amz-Date': b'20251101T125409Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=TEST_ID_FOR_RETRYIES/20251101/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=49a6541c3bce5375f24a43182078cec6796efee57ba0e58b274ffb554cfad169', 'amz-sdk-invocation-id': b'ab9ea7ec-4ed9-42f0-ba3a-7c4b1bd06b3a', 'amz-sdk-request': b'attempt=1', 'Content-Length': '36'}>
2025-11-01 12:54:09,687 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:3803
2025-11-01 12:54:39,840 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:3803 "POST / HTTP/1.1" 503 242
2025-11-01 12:54:39,842 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '242', 'Connection': 'Keep-Alive'}
2025-11-01 12:54:39,842 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<ErrorResponse><Error><Message>IAM authorization error. Request id to report: 2012fffe-86c4559a-3f84739e-5d36371f.</Message><Code>ServiceUnavailable</Code></Error><RequestId></RequestId></ErrorResponse>\n'
2025-11-01 12:54:39,854 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.ListQueues: calling handler <botocore.retryhandler.RetryHandler object at 0x7bb79b3777f0>
2025-11-01 12:54:39,855 - DEBUG - botocore.retryhandler - _check_response: retry needed: retryable HTTP status code received: 503
2025-11-01 12:54:39,884 - DEBUG - botocore.retryhandler - __call__: Retry needed, action of: 0.46160844859572014
2025-11-01 12:54:39,885 - DEBUG - botocore.endpoint - _needs_retry: Response received to retry, sleeping for 0.46160844859572014 seconds
2025-11-01 12:54:40,347 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bb79b3687b0>>
2025-11-01 12:54:40,348 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.ListQueues: calling handler <function set_operation_specific_signer at 0x7c5798d90c30>
2025-11-01 12:54:40,350 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-01 12:54:40,350 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:3803
x-amz-date:20251101T125440Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
48a38266faf90970d6c7fea9b15e6ba366e5f6397c2970fc893f8a7b5e207bd0
2025-11-01 12:54:40,350 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251101T125440Z
20251101/ru-central1/sqs/aws4_request
45160df7b09cf0d44892dbd5ec39ef5725b2cac7f25f3a9d3adbcead736ba139
2025-11-01 12:54:40,351 - DEBUG - botocore.auth - add_auth: Signature:
c61013546cc807ba1f7a7a9fb050012ea84137d03eee0eb2a90d7041cb4c7faf
2025-11-01 12:54:40,356 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <function add_retry_headers at 0x7c5798d96030>
2025-11-01 12:54:40,357 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:3803/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89', 'X-Amz-Date': b'20251101T125440Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=TEST_ID_FOR_RETRYIES/20251101/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=c61013546cc807ba1f7a7a9fb050012ea84137d03eee0eb2a90d7041cb4c7faf', 'amz-sdk-invocation-id': b'ab9ea7ec-4ed9-42f0-ba3a-7c4b1bd06b3a', 'amz-sdk-request': b'attempt=2; max=5', 'Content-Length': '36'}>
2025-11-01 12:55:10,684 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:3803 "POST / HTTP/1.1" 503 241
2025-11-01 12:55:10,685 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '241', 'Connection': 'Keep-Alive'}
2025-11-01 12:55:10,686 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<ErrorResponse><Error><Message>IAM authorization error. Request id to report: 7013716-ec65f5ff-6e815598-2b559e8b.</Message><Code>ServiceUnavailable</Code></Error><RequestId></RequestId></ErrorResponse>\n'
2025-11-01 12:55:10,687 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.ListQueues: calling handler <botocore.retryhandler.RetryHandler object at 0x7bb79b3777f0>
2025-11-01 12:55:10,687 - DEBUG - botocore.retryhandler - _check_response: retry needed: retryable HTTP status code received: 503
2025-11-01 12:55:10,688 - DEBUG - botocore.retryhandler - __call__: Retry needed, action of: 1.0697763525972295
2025-11-01 12:55:10,688 - DEBUG - botocore.endpoint - _needs_retry: Response received to retry, sleeping for 1.0697763525972295 seconds
2025-11-01 12:55:11,759 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bb79b3687b0>>
2025-11-01 12:55:11,759 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.ListQueues: calling handler <function set_operation_specific_signer at 0x7c5798d90c30>
2025-11-01 12:55:11,761 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-01 12:55:11,761 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:3803
x-amz-date:20251101T125511Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
48a38266faf90970d6c7fea9b15e6ba366e5f6397c2970fc893f8a7b5e207bd0
2025-11-01 12:55:11,762 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251101T125511Z
20251101/ru-central1/sqs/aws4_request
b5b2266a14e324e9f2445d7620669417ddfeb193d34413250136af42b959dcab
2025-11-01 12:55:11,762 - DEBUG - botocore.auth - add_auth: Signature:
17ac102800bcedf733f069bea2cfb2466a5a6d17fd3d05677b5f2d231a0e3c2a
2025-11-01 12:55:11,763 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <function add_retry_headers at 0x7c5798d96030>
2025-11-01 12:55:11,785 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:3803/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89', 'X-Amz-Date': b'20251101T125511Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=TEST_ID_FOR_RETRYIES/20251101/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=17ac102800bcedf733f069bea2cfb2466a5a6d17fd3d05677b5f2d231a0e3c2a', 'amz-sdk-invocation-id': b'ab9ea7ec-4ed9-42f0-ba3a-7c4b1bd06b3a', 'amz-sdk-request': b'attempt=3; max=5', 'Content-Length': '36'}>
2025-11-01 12:55:24,791 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 12:55:24,792 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 12:55:24,836 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/TenantSQS
2025-11-01 12:55:24,838 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 132587ba-dab9-48f4-b8bf-df456d77f270, localhost:19458): creating call state
2025-11-01 12:55:24,839 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 132587ba-dab9-48f4-b8bf-df456d77f270, localhost:19458): request = { database: "/Root/TenantSQS" }
2025-11-01 12:55:24,850 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 132587ba-dab9-48f4-b8bf-df456d77f270, localhost:19458): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ebytu4uhfq.auto.internal" port: 19458 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } } } } }
2025-11-01 12:55:24,851 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/TenantSQS: DiscoveryResult <self_location: , endpoints [<Endpoint ghrun-ebytu4uhfq.auto.internal:19458, location , ssl: False>]>
2025-11-01 12:55:24,852 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:19458
2025-11-01 12:55:41,941 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:3803 "POST / HTTP/1.1" 503 242
2025-11-01 12:55:41,942 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '242', 'Connection': 'Keep-Alive'}
2025-11-01 12:55:41,942 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<ErrorResponse><Error><Message>IAM authorization error. Request id to report: 7a9e5c07-4eae1feb-115b8299-cb5c3896.</Message><Code>ServiceUnavailable</Code></Error><RequestId></RequestId></ErrorResponse>\n'
2025-11-01 12:55:41,945 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.ListQueues: calling handler <botocore.retryhandler.RetryHandler object at 0x7bb79b3777f0>
2025-11-01 12:55:41,946 - DEBUG - botocore.retryhandler - _check_response: retry needed: retryable HTTP status code received: 503
2025-11-01 12:55:41,946 - DEBUG - botocore.retryhandler - __call__: Retry needed, action of: 1.2032582523223172
2025-11-01 12:55:41,947 - DEBUG - botocore.endpoint - _needs_retry: Response received to retry, sleeping for 1.2032582523223172 seconds
2025-11-01 12:55:43,156 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bb79b3687b0>>
2025-11-01 12:55:43,157 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.ListQueues: calling handler <function set_operation_specific_signer at 0x7c5798d90c30>
2025-11-01 12:55:43,158 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-01 12:55:43,158 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:3803
x-amz-date:20251101T125543Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
48a38266faf90970d6c7fea9b15e6ba366e5f6397c2970fc893f8a7b5e207bd0
2025-11-01 12:55:43,159 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251101T125543Z
20251101/ru-central1/sqs/aws4_request
33fd939fbca36a794f9254370cb7d60708726977ade56f30c02bab4aefc21112
2025-11-01 12:55:43,162 - DEBUG - botocore.auth - add_auth: Signature:
a7b57fb4e8fc8edae0114b9388eee1b33849a467fc8b198bef6d854c1ae5625d
2025-11-01 12:55:43,163 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <function add_retry_headers at 0x7c5798d96030>
2025-11-01 12:55:43,164 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:3803/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89', 'X-Amz-Date': b'20251101T125543Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=TEST_ID_FOR_RETRYIES/20251101/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=a7b57fb4e8fc8edae0114b9388eee1b33849a467fc8b198bef6d854c1ae5625d', 'amz-sdk-invocation-id': b'ab9ea7ec-4ed9-42f0-ba3a-7c4b1bd06b3a', 'amz-sdk-request': b'attempt=4; max=5', 'Content-Length': '36'}>
2025-11-01 12:56:13,337 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:3803 "POST / HTTP/1.1" 503 241
2025-11-01 12:56:13,339 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '241', 'Connection': 'Keep-Alive'}
2025-11-01 12:56:13,341 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<ErrorResponse><Error><Message>IAM authorization error. Request id to report: c4f5131c-7693cdc9-1324ddd0-506822e.</Message><Code>ServiceUnavailable</Code></Error><RequestId></RequestId></ErrorResponse>\n'
2025-11-01 12:56:13,343 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.ListQueues: calling handler <botocore.retryhandler.RetryHandler object at 0x7bb79b3777f0>
2025-11-01 12:56:13,343 - DEBUG - botocore.retryhandler - _check_response: retry needed: retryable HTTP status code received: 503
2025-11-01 12:56:13,344 - DEBUG - botocore.retryhandler - __call__: Retry needed, action of: 3.323283706223534
2025-11-01 12:56:13,344 - DEBUG - botocore.endpoint - _needs_retry: Response received to retry, sleeping for 3.323283706223534 seconds
2025-11-01 12:56:16,668 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bb79b3687b0>>
2025-11-01 12:56:16,669 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.ListQueues: calling handler <function set_operation_specific_signer at 0x7c5798d90c30>
2025-11-01 12:56:16,670 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-01 12:56:16,671 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:3803
x-amz-date:20251101T125616Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
48a38266faf90970d6c7fea9b15e6ba366e5f6397c2970fc893f8a7b5e207bd0
2025-11-01 12:56:16,673 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251101T125616Z
20251101/ru-central1/sqs/aws4_request
383d5a8f200a652c7fb95ff1085fa1589b16c92b40a78e5137026a7d5a8d248f
2025-11-01 12:56:16,674 - DEBUG - botocore.auth - add_auth: Signature:
aa52fc069a73c9f4c5539d9d0338b519145a08b9176d72ba3a17430f39ca9235
2025-11-01 12:56:16,674 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <function add_retry_headers at 0x7c5798d96030>
2025-11-01 12:56:16,675 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:3803/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89', 'X-Amz-Date': b'20251101T125616Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=TEST_ID_FOR_RETRYIES/20251101/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=aa52fc069a73c9f4c5539d9d0338b519145a08b9176d72ba3a17430f39ca9235', 'amz-sdk-invocation-id': b'ab9ea7ec-4ed9-42f0-ba3a-7c4b1bd06b3a', 'amz-sdk-request': b'attempt=5; max=5', 'Content-Length': '36'}>
2025-11-01 12:56:37,147 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 12:56:37,147 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 12:56:37,196 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/TenantSQS
2025-11-01 12:56:37,197 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c3de7692-3ad8-477d-92cd-f3657e0c23a2, localhost:19458): creating call state
2025-11-01 12:56:37,198 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c3de7692-3ad8-477d-92cd-f3657e0c23a2, localhost:19458): request = { database: "/Root/TenantSQS" }
2025-11-01 12:56:37,210 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c3de7692-3ad8-477d-92cd-f3657e0c23a2, localhost:19458): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ebytu4uhfq.auto.internal" port: 19458 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } } } } }
2025-11-01 12:56:37,212 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/TenantSQS: DiscoveryResult <self_location: , endpoints [<Endpoint ghrun-ebytu4uhfq.auto.internal:19458, location , ssl: False>]>
2025-11-01 12:56:37,212 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:19458
2025-11-01 12:56:46,837 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:3803 "POST / HTTP/1.1" 503 241
2025-11-01 12:56:46,838 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '241', 'Connection': 'Keep-Alive'}
2025-11-01 12:56:46,838 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<ErrorResponse><Error><Message>IAM authorization error. Request id to report: 27ea9314-1fd67230-98e475d4-ac18635.</Message><Code>ServiceUnavailable</Code></Error><RequestId></RequestId></ErrorResponse>\n'
2025-11-01 12:56:46,844 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.ListQueues: calling handler <botocore.retryhandler.RetryHandler object at 0x7bb79b3777f0>
2025-11-01 12:56:46,845 - DEBUG - botocore.retryhandler - _check_response: retry needed: retryable HTTP status code received: 503
2025-11-01 12:56:46,845 - DEBUG - botocore.retryhandler - __call__: Reached the maximum number of retry attempts: 5
2025-11-01 12:56:46,846 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-01 12:56:46,859 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-01 12:56:46,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, 73787504-f0c1-4ba5-b557-51acc83f61bc, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:56:46,862 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, 73787504-f0c1-4ba5-b557-51acc83f61bc, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS" }
2025-11-01 12:56:46,886 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, 73787504-f0c1-4ba5-b557-51acc83f61bc, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Scheme.ListDirectoryResult] { self { name: "Root/TenantSQS" owner: "root@builtin" type: DATABASE effective_permissions { subject: "USERS" permission_names: "ydb.database.connect" } effective_permissions { subject: "METADATA-READERS" permission_names: "ydb.generic.list" } effective_permissions { subject: "DATA-READERS" permission_names: "ydb.granular.select_row" } effective_permissions { subject: "DATA-WRITERS" permission_names: "ydb.tables.modify" } effective_permissions { subject: "DDL-ADMINS" permission_names: "ydb.granular.create_directory" permission_names: "ydb.granular.write_attributes" permission_names: "ydb.granular.create_table" permission_names: "ydb.granular.remove_schema" permission_names: "ydb.granular.create_queue" permission_names: "ydb.granular.alter_schema" } effective_permissions { subject: "ACCESS-ADMINS" permission_names: "ydb.access.grant" } effective_permissions { subject: "DATABASE-ADMINS" permission_names: "ydb.generic.manage" } size_bytes: 315 created_at { tx_id: 1 } } children { name: ".AtomicCounter" owner: "root@builtin" type: TABLE created_at { plan_step: 1762001474140 tx_id: 281474976720660 } } children { name: ".Events" owner: "root@builtin" type: TABLE created_at { plan_step: 1762001475250 tx_id: 281474976720666 } } children { name: ".FIFO" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1762001477780 tx_id: 281474976720675 } } children { name: ".Queues" owner: "root@builtin" type: TABLE created_at { plan_step: 1762001475060 tx_id: 281474976720665 } } children { name: ".RemovedQueues" owner: "root@builtin" type: TABLE created_at { plan_step: 1762001475580 tx_id: 281474976720669 } } children { name: ".STD" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1762001475890 tx_id: 281474976720670 } } children { name: ".Settings" owner: "root@builtin" type: TABLE created_at { plan_step: 1762001475410 tx_id: 281474976720667 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1762001473960 tx_id: 281474976720657 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-11-01 12:56:46,898 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 0cdcf1cc-0600-4986-be0c-0248a9adb66d, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:56:46,909 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 0cdcf1cc-0600-4986-be0c-0248a9adb66d, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.Events/Queues" }
2025-11-01 12:56:46,929 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 0cdcf1cc-0600-4986-be0c-0248a9adb66d, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-01 12:56:46,931 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-11-01 12:56:46,933 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, e57e3231-ecc3-4e22-b9f3-30f8cf3cff65, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:56:46,934 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, e57e3231-ecc3-4e22-b9f3-30f8cf3cff65, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.FIFO/Queues" }
2025-11-01 12:56:46,969 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, e57e3231-ecc3-4e22-b9f3-30f8cf3cff65, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-01 12:56:46,971 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-11-01 12:56:46,972 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 47be7150-50cb-48e4-ad22-61e06a22ec1b, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:56:46,973 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 47be7150-50cb-48e4-ad22-61e06a22ec1b, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.RemovedQueues/Queues" }
2025-11-01 12:56:46,987 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 47be7150-50cb-48e4-ad22-61e06a22ec1b, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-01 12:56:46,989 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-11-01 12:56:46,990 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, fb1acd01-0ece-42e8-bcbb-52e0b33670b1, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:56:46,990 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, fb1acd01-0ece-42e8-bcbb-52e0b33670b1, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.STD/Queues" }
2025-11-01 12:56:47,001 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, fb1acd01-0ece-42e8-bcbb-52e0b33670b1, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-01 12:56:47,003 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-11-01 12:56:47,004 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, f9beb3dd-4705-417c-bd43-3ffa5a4e1683, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:56:47,005 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, f9beb3dd-4705-417c-bd43-3ffa5a4e1683, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.metadata/Queues" }
2025-11-01 12:56:47,022 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, f9beb3dd-4705-417c-bd43-3ffa5a4e1683, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-01 12:56:47,023 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-11-01 12:56:47,025 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 4a09b9dd-3eb3-4cb4-8327-0d25da146ef9, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:56:47,026 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 4a09b9dd-3eb3-4cb4-8327-0d25da146ef9, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.sys/Queues" }
2025-11-01 12:56:47,035 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 4a09b9dd-3eb3-4cb4-8327-0d25da146ef9, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-01 12:56:47,036 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-11-01 12:56:47,037 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 2c24dc93-c4be-4b3a-9397-a31cad3ef467, ghrun-ebytu4uhfq.auto.internal:19458): creating call state
2025-11-01 12:56:47,038 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 2c24dc93-c4be-4b3a-9397-a31cad3ef467, ghrun-ebytu4uhfq.auto.internal:19458): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/CLOUD_FOR_folder_acc_db615937-b721-11f0-ad22-d00d894b3bc7/Queues" }
2025-11-01 12:56:47,059 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 2c24dc93-c4be-4b3a-9397-a31cad3ef467, ghrun-ebytu4uhfq.auto.internal:19458): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-01 12:56:47,060 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-11-01 12:56:47,061 - DEBUG - ydb.topic - close: Close topic client
2025-11-01 12:56:47,061 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-01 12:56:47,063 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:19458
2025-11-01 12:56:47,067 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-ebytu4uhfq.auto.internal:19458
2025-11-01 12:56:47,070 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-01 12:56:47,072 - DEBUG - root - teardown_method: Test finished: test_retryable_iam_error
2025-11-01 12:57:09,092 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:19458/1
2025-11-01 12:57:09,108 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:18201/1
2025-11-01 12:57:09,110 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3803
2025-11-01 12:57:09,111 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25563
2025-11-01 12:57:09,111 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22692
2025-11-01 12:57:09,112 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32587
2025-11-01 12:57:09,112 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15444
2025-11-01 12:57:09,112 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19458
2025-11-01 12:57:09,113 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/13482
2025-11-01 12:57:09,113 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14142
2025-11-01 12:57:09,114 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6868
2025-11-01 12:57:09,114 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9161
2025-11-01 12:57:09,115 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18201
2025-11-01 12:57:09,115 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3106
