2025-10-15 15:13:03,850 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:13:03,851 - INFO - ya.test - pytest_runtest_setup: test_empty_access_key_id[tables_format_v0]
2025-10-15 15:13:03,851 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:13:03,851 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:13:04,032 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd
2025-10-15 15:13:04,035 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4394
2025-10-15 15:13:04,038 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/w2kl/001940/r3tmp/kikimr_cluster_tkenkaxe
2025-10-15 15:13:04,090 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd
2025-10-15 15:13:04,091 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20824
2025-10-15 15:13:04,091 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11119
2025-10-15 15:13:04,092 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24891
2025-10-15 15:13:04,092 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7915
2025-10-15 15:13:04,093 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11153
2025-10-15 15:13:04,094 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/kikimr_configs"
2025-10-15 15:13:04,094 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001940/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/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/node_1/logfile_0492gm94.log --grpc-port=20824 --mon-port=11119 --ic-port=4394 --sqs-port=11153
2025-10-15 15:13:04,095 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:20824/1, disk_size 68719476736
2025-10-15 15:13:04,096 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/kikimr_configs"
2025-10-15 15:13:04,096 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001940/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/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/node_1/logfile_0492gm94.log --grpc-port=20824 --mon-port=11119 --ic-port=4394 --sqs-port=11153
2025-10-15 15:13:04,098 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/node_1/logfile_0492gm94.log', '--grpc-port=20824', '--mon-port=11119', '--ic-port=4394', '--sqs-port=11153')' in '/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/node_1' (no wait)
2025-10-15 15:13:04,101 - DEBUG - ya.test - execute: Command pid: 614385
2025-10-15 15:13:04,103 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:20824/1
2025-10-15 15:13:04,105 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 15:13:04,106 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 15:13:04,107 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:13:04,115 - DEBUG - ya.test - execute: Command pid: 614387
2025-10-15 15:13:05,254 - DEBUG - ya.test - _save_outputs: Command (pid 614387) rc: 0
2025-10-15 15:13:05,255 - DEBUG - ya.test - _save_outputs: Command (pid 614387) elapsed time (sec): 1.1385002136230469
2025-10-15 15:13:05,255 - DEBUG - ya.test - _save_outputs: Command (pid 614387) idrss: 0
2025-10-15 15:13:05,256 - DEBUG - ya.test - _save_outputs: Command (pid 614387) inblock: 0
2025-10-15 15:13:05,256 - DEBUG - ya.test - _save_outputs: Command (pid 614387) isrss: 0
2025-10-15 15:13:05,256 - DEBUG - ya.test - _save_outputs: Command (pid 614387) ixrss: 0
2025-10-15 15:13:05,257 - DEBUG - ya.test - _save_outputs: Command (pid 614387) majflt: 0
2025-10-15 15:13:05,257 - DEBUG - ya.test - _save_outputs: Command (pid 614387) maxrss: 512596
2025-10-15 15:13:05,257 - DEBUG - ya.test - _save_outputs: Command (pid 614387) minflt: 45175
2025-10-15 15:13:05,258 - DEBUG - ya.test - _save_outputs: Command (pid 614387) msgrcv: 0
2025-10-15 15:13:05,258 - DEBUG - ya.test - _save_outputs: Command (pid 614387) msgsnd: 0
2025-10-15 15:13:05,258 - DEBUG - ya.test - _save_outputs: Command (pid 614387) nivcsw: 189
2025-10-15 15:13:05,258 - DEBUG - ya.test - _save_outputs: Command (pid 614387) nsignals: 0
2025-10-15 15:13:05,259 - DEBUG - ya.test - _save_outputs: Command (pid 614387) nswap: 0
2025-10-15 15:13:05,259 - DEBUG - ya.test - _save_outputs: Command (pid 614387) nvcsw: 14
2025-10-15 15:13:05,259 - DEBUG - ya.test - _save_outputs: Command (pid 614387) oublock: 8
2025-10-15 15:13:05,259 - DEBUG - ya.test - _save_outputs: Command (pid 614387) stime: 0.21903299999999998
2025-10-15 15:13:05,260 - DEBUG - ya.test - _save_outputs: Command (pid 614387) utime: 0.52572
2025-10-15 15:13:05,260 - DEBUG - ya.test - _save_outputs: Command (pid 614387) wtime: 1.147
2025-10-15 15:13:05,261 - DEBUG - ya.test - _save_outputs: Command (pid 614387) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 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-10-15 15:13:05,261 - DEBUG - ya.test - _save_outputs: Command (pid 614387) errors:

2025-10-15 15:13:05,262 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 15:13:11,642 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd
2025-10-15 15:13:11,643 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20824', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 4394\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 15:13:11,644 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 15:13:11,646 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 15:13:11,646 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20824', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 4394\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:13:11,648 - DEBUG - ya.test - execute: Command pid: 617054
2025-10-15 15:13:12,836 - DEBUG - ya.test - _save_outputs: Command (pid 617054) rc: 0
2025-10-15 15:13:12,837 - DEBUG - ya.test - _save_outputs: Command (pid 617054) elapsed time (sec): 1.1873815059661865
2025-10-15 15:13:12,837 - DEBUG - ya.test - _save_outputs: Command (pid 617054) idrss: 0
2025-10-15 15:13:12,837 - DEBUG - ya.test - _save_outputs: Command (pid 617054) inblock: 0
2025-10-15 15:13:12,838 - DEBUG - ya.test - _save_outputs: Command (pid 617054) isrss: 0
2025-10-15 15:13:12,838 - DEBUG - ya.test - _save_outputs: Command (pid 617054) ixrss: 0
2025-10-15 15:13:12,838 - DEBUG - ya.test - _save_outputs: Command (pid 617054) majflt: 0
2025-10-15 15:13:12,839 - DEBUG - ya.test - _save_outputs: Command (pid 617054) maxrss: 579792
2025-10-15 15:13:12,839 - DEBUG - ya.test - _save_outputs: Command (pid 617054) minflt: 51304
2025-10-15 15:13:12,839 - DEBUG - ya.test - _save_outputs: Command (pid 617054) msgrcv: 0
2025-10-15 15:13:12,839 - DEBUG - ya.test - _save_outputs: Command (pid 617054) msgsnd: 0
2025-10-15 15:13:12,840 - DEBUG - ya.test - _save_outputs: Command (pid 617054) nivcsw: 233
2025-10-15 15:13:12,840 - DEBUG - ya.test - _save_outputs: Command (pid 617054) nsignals: 0
2025-10-15 15:13:12,840 - DEBUG - ya.test - _save_outputs: Command (pid 617054) nswap: 0
2025-10-15 15:13:12,841 - DEBUG - ya.test - _save_outputs: Command (pid 617054) nvcsw: 679
2025-10-15 15:13:12,841 - DEBUG - ya.test - _save_outputs: Command (pid 617054) oublock: 8
2025-10-15 15:13:12,841 - DEBUG - ya.test - _save_outputs: Command (pid 617054) stime: 0.274314
2025-10-15 15:13:12,842 - DEBUG - ya.test - _save_outputs: Command (pid 617054) utime: 0.576765
2025-10-15 15:13:12,842 - DEBUG - ya.test - _save_outputs: Command (pid 617054) wtime: 1.189
2025-10-15 15:13:12,842 - DEBUG - ya.test - _save_outputs: Command (pid 617054) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 15:13:12,843 - DEBUG - ya.test - _save_outputs: Command (pid 617054) errors:

2025-10-15 15:13:12,844 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd
2025-10-15 15:13:12,845 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20824', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-15 15:13:12,846 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 15:13:12,847 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 15:13:12,847 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20824', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:13:12,856 - DEBUG - ya.test - execute: Command pid: 617639
2025-10-15 15:13:13,993 - DEBUG - ya.test - _save_outputs: Command (pid 617639) rc: 0
2025-10-15 15:13:13,994 - DEBUG - ya.test - _save_outputs: Command (pid 617639) elapsed time (sec): 1.1358861923217773
2025-10-15 15:13:13,994 - DEBUG - ya.test - _save_outputs: Command (pid 617639) idrss: 0
2025-10-15 15:13:13,994 - DEBUG - ya.test - _save_outputs: Command (pid 617639) inblock: 0
2025-10-15 15:13:13,995 - DEBUG - ya.test - _save_outputs: Command (pid 617639) isrss: 0
2025-10-15 15:13:13,995 - DEBUG - ya.test - _save_outputs: Command (pid 617639) ixrss: 0
2025-10-15 15:13:13,995 - DEBUG - ya.test - _save_outputs: Command (pid 617639) majflt: 0
2025-10-15 15:13:13,995 - DEBUG - ya.test - _save_outputs: Command (pid 617639) maxrss: 579848
2025-10-15 15:13:13,996 - DEBUG - ya.test - _save_outputs: Command (pid 617639) minflt: 51473
2025-10-15 15:13:13,996 - DEBUG - ya.test - _save_outputs: Command (pid 617639) msgrcv: 0
2025-10-15 15:13:13,996 - DEBUG - ya.test - _save_outputs: Command (pid 617639) msgsnd: 0
2025-10-15 15:13:13,998 - DEBUG - ya.test - _save_outputs: Command (pid 617639) nivcsw: 213
2025-10-15 15:13:13,998 - DEBUG - ya.test - _save_outputs: Command (pid 617639) nsignals: 0
2025-10-15 15:13:13,998 - DEBUG - ya.test - _save_outputs: Command (pid 617639) nswap: 0
2025-10-15 15:13:14,000 - DEBUG - ya.test - _save_outputs: Command (pid 617639) nvcsw: 850
2025-10-15 15:13:14,000 - DEBUG - ya.test - _save_outputs: Command (pid 617639) oublock: 8
2025-10-15 15:13:14,000 - DEBUG - ya.test - _save_outputs: Command (pid 617639) stime: 0.246277
2025-10-15 15:13:14,000 - DEBUG - ya.test - _save_outputs: Command (pid 617639) utime: 0.580224
2025-10-15 15:13:14,001 - DEBUG - ya.test - _save_outputs: Command (pid 617639) wtime: 1.145
2025-10-15 15:13:14,001 - DEBUG - ya.test - _save_outputs: Command (pid 617639) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-15 15:13:14,001 - DEBUG - ya.test - _save_outputs: Command (pid 617639) errors:

2025-10-15 15:13:14,002 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 15:13:14,117 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 15:13:14,695 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd
2025-10-15 15:13:14,696 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31205
2025-10-15 15:13:14,696 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22953
2025-10-15 15:13:14,705 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62469
2025-10-15 15:13:14,706 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4617
2025-10-15 15:13:14,707 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4617
2025-10-15 15:13:14,707 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4618
2025-10-15 15:13:14,708 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21700
2025-10-15 15:13:14,708 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11721
2025-10-15 15:13:14,710 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/kikimr_configs"
2025-10-15 15:13:14,710 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:20824 --tenant=/Root/TenantSQS --log-file-name=/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/slot_1/logfile_lh5rrdcw.log --grpc-port=31205 --mon-port=22953 --ic-port=62469 --sqs-port=11721
2025-10-15 15:13:14,710 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/kikimr_configs"
2025-10-15 15:13:14,712 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:20824 --tenant=/Root/TenantSQS --log-file-name=/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/slot_1/logfile_lh5rrdcw.log --grpc-port=31205 --mon-port=22953 --ic-port=62469 --sqs-port=11721
2025-10-15 15:13:14,713 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:20824', '--tenant=/Root/TenantSQS', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/slot_1/logfile_lh5rrdcw.log', '--grpc-port=31205', '--mon-port=22953', '--ic-port=62469', '--sqs-port=11721')' in '/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/slot_1' (no wait)
2025-10-15 15:13:14,728 - DEBUG - ya.test - execute: Command pid: 618091
2025-10-15 15:13:14,729 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:31205/1
2025-10-15 15:13:14,730 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 15:13:14,733 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 15:13:14,734 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:13:14,744 - DEBUG - ya.test - execute: Command pid: 618092
2025-10-15 15:13:15,861 - DEBUG - ya.test - _save_outputs: Command (pid 618092) rc: 0
2025-10-15 15:13:15,862 - DEBUG - ya.test - _save_outputs: Command (pid 618092) elapsed time (sec): 1.11564040184021
2025-10-15 15:13:15,862 - DEBUG - ya.test - _save_outputs: Command (pid 618092) idrss: 0
2025-10-15 15:13:15,862 - DEBUG - ya.test - _save_outputs: Command (pid 618092) inblock: 0
2025-10-15 15:13:15,863 - DEBUG - ya.test - _save_outputs: Command (pid 618092) isrss: 0
2025-10-15 15:13:15,863 - DEBUG - ya.test - _save_outputs: Command (pid 618092) ixrss: 0
2025-10-15 15:13:15,863 - DEBUG - ya.test - _save_outputs: Command (pid 618092) majflt: 0
2025-10-15 15:13:15,864 - DEBUG - ya.test - _save_outputs: Command (pid 618092) maxrss: 592264
2025-10-15 15:13:15,864 - DEBUG - ya.test - _save_outputs: Command (pid 618092) minflt: 45094
2025-10-15 15:13:15,864 - DEBUG - ya.test - _save_outputs: Command (pid 618092) msgrcv: 0
2025-10-15 15:13:15,865 - DEBUG - ya.test - _save_outputs: Command (pid 618092) msgsnd: 0
2025-10-15 15:13:15,865 - DEBUG - ya.test - _save_outputs: Command (pid 618092) nivcsw: 227
2025-10-15 15:13:15,865 - DEBUG - ya.test - _save_outputs: Command (pid 618092) nsignals: 0
2025-10-15 15:13:15,866 - DEBUG - ya.test - _save_outputs: Command (pid 618092) nswap: 0
2025-10-15 15:13:15,866 - DEBUG - ya.test - _save_outputs: Command (pid 618092) nvcsw: 14
2025-10-15 15:13:15,866 - DEBUG - ya.test - _save_outputs: Command (pid 618092) oublock: 8
2025-10-15 15:13:15,867 - DEBUG - ya.test - _save_outputs: Command (pid 618092) stime: 0.196742
2025-10-15 15:13:15,867 - DEBUG - ya.test - _save_outputs: Command (pid 618092) utime: 0.5557139999999999
2025-10-15 15:13:15,867 - DEBUG - ya.test - _save_outputs: Command (pid 618092) wtime: 1.127
2025-10-15 15:13:15,868 - DEBUG - ya.test - _save_outputs: Command (pid 618092) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 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-10-15 15:13:15,868 - DEBUG - ya.test - _save_outputs: Command (pid 618092) errors:

2025-10-15 15:13:15,877 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 15:13:21,918 - 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-10-15 15:13:21,924 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:13:21,925 - 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-10-15 15:13:21,970 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/TenantSQS
2025-10-15 15:13:21,971 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 97360e6f-7978-4b18-85a2-64ebee8255c8, localhost:20824): creating call state
2025-10-15 15:13:21,973 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 97360e6f-7978-4b18-85a2-64ebee8255c8, localhost:20824): request = { database: "/Root/TenantSQS" }
2025-10-15 15:13:21,983 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 97360e6f-7978-4b18-85a2-64ebee8255c8, localhost:20824): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 31205 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 } self_location: "1" } } } }
2025-10-15 15:13:21,987 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/TenantSQS: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:31205, location , ssl: False>]>
2025-10-15 15:13:21,988 - 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-10-15 15:13:22,021 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:20824
2025-10-15 15:13:22,027 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-10-15 15:13:22,027 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7bbd2bdc6b30 state=pending>
2025-10-15 15:13:22,028 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-10-15 15:13:22,029 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7bbd2bdc44b0>
2025-10-15 15:13:22,029 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7bbd2bdc44b0>
2025-10-15 15:13:22,031 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, e1617a19-dd7b-474e-a0c6-ca7a49c45a79, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:22,032 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, e1617a19-dd7b-474e-a0c6-ca7a49c45a79, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-15 15:13:22,649 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, e1617a19-dd7b-474e-a0c6-ca7a49c45a79, ghrun-lvxbqnxafi.auto.internal:31205): on response callback started
2025-10-15 15:13:22,651 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, e1617a19-dd7b-474e-a0c6-ca7a49c45a79, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" } } } }
2025-10-15 15:13:22,651 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7bbd2bdc44b0>
2025-10-15 15:13:22,652 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7bbd2bdc44b0>
2025-10-15 15:13:22,652 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, e1617a19-dd7b-474e-a0c6-ca7a49c45a79, ghrun-lvxbqnxafi.auto.internal:31205): on response callback success
2025-10-15 15:13:22,661 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 0f7c84af-19d6-4041-8d5e-decdd3ed426a, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:22,662 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 0f7c84af-19d6-4041-8d5e-decdd3ed426a, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS//.AtomicCounter" columns { name: "counter_key" type { optional_type { item { type_id: UINT64 } } } } columns { name: "value" type { optional_type { item { type_id: UINT64 } } } } primary_key: "counter_key" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 15:13:23,296 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 0f7c84af-19d6-4041-8d5e-decdd3ed426a, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:23,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, fdde188a-4712-40ae-91bf-3548c0932ce5, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:23,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, fdde188a-4712-40ae-91bf-3548c0932ce5, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "--!syntax_v1\nINSERT INTO `/Root/TenantSQS//.AtomicCounter` (counter_key, value) VALUES (0, 0);" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 15:13:23,495 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, fdde188a-4712-40ae-91bf-3548c0932ce5, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-15 15:13:23,498 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 58f5c3a5-b33a-4104-b3a4-a32d44745c3f, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:23,502 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 58f5c3a5-b33a-4104-b3a4-a32d44745c3f, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS//.Queues" columns { name: "Account" type { optional_type { item { type_id: UTF8 } } } } columns { name: "QueueName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "QueueId" type { optional_type { item { type_id: STRING } } } } columns { name: "QueueState" type { optional_type { item { type_id: UINT64 } } } } columns { name: "FifoQueue" type { optional_type { item { type_id: BOOL } } } } columns { name: "DeadLetterQueue" type { optional_type { item { type_id: BOOL } } } } columns { name: "CreatedTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Shards" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Partitions" type { optional_type { item { type_id: UINT64 } } } } columns { name: "MasterTabletId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "CustomQueueName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "FolderId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "Version" type { optional_type { item { type_id: UINT64 } } } } columns { name: "DlqName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "TablesFormat" type { optional_type { item { type_id: UINT32 } } } } columns { name: "Tags" type { optional_type { item { type_id: UTF8 } } } } primary_key: "Account" primary_key: "QueueName" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 15:13:23,658 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 58f5c3a5-b33a-4104-b3a4-a32d44745c3f, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:23,660 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 3b02870e-6d61-4618-ba06-97fb34b6a0ca, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:23,662 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 3b02870e-6d61-4618-ba06-97fb34b6a0ca, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS//.Events" columns { name: "Account" type { optional_type { item { type_id: UTF8 } } } } columns { name: "QueueName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "EventType" type { optional_type { item { type_id: UINT64 } } } } columns { name: "CustomQueueName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "EventTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "FolderId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "Labels" type { optional_type { item { type_id: UTF8 } } } } primary_key: "Account" primary_key: "QueueName" primary_key: "EventType" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 15:13:23,880 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 3b02870e-6d61-4618-ba06-97fb34b6a0ca, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:23,882 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, a04f5dc6-7c17-4ed0-8d05-f146bb4bd2ba, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:23,883 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, a04f5dc6-7c17-4ed0-8d05-f146bb4bd2ba, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS//.Settings" columns { name: "Account" type { optional_type { item { type_id: UTF8 } } } } columns { name: "Name" type { optional_type { item { type_id: UTF8 } } } } columns { name: "Value" type { optional_type { item { type_id: UTF8 } } } } primary_key: "Account" primary_key: "Name" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 15:13:24,050 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, a04f5dc6-7c17-4ed0-8d05-f146bb4bd2ba, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:24,052 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, ca367b0e-e370-4809-a647-29fe7f4b3d23, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:24,055 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, ca367b0e-e370-4809-a647-29fe7f4b3d23, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS//.RemovedQueues" columns { name: "RemoveTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Account" type { optional_type { item { type_id: UTF8 } } } } columns { name: "QueueName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "FifoQueue" type { optional_type { item { type_id: BOOL } } } } columns { name: "Shards" type { optional_type { item { type_id: UINT32 } } } } columns { name: "CustomQueueName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "FolderId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "TablesFormat" type { optional_type { item { type_id: UINT32 } } } } columns { name: "StartProcessTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "NodeProcess" type { optional_type { item { type_id: UINT32 } } } } primary_key: "RemoveTimestamp" primary_key: "QueueIdNumber" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 15:13:24,198 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, ca367b0e-e370-4809-a647-29fe7f4b3d23, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:24,200 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 13eac62c-5aea-4ecf-b535-419eb90a9290, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:24,202 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 13eac62c-5aea-4ecf-b535-419eb90a9290, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.STD//SentTimestampIdx" columns { name: "QueueIdNumberAndShardHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Shard" type { optional_type { item { type_id: UINT32 } } } } columns { name: "SentTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Offset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "DelayDeadline" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberAndShardHash" primary_key: "QueueIdNumber" primary_key: "Shard" primary_key: "SentTimestamp" primary_key: "Offset" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:24,520 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 13eac62c-5aea-4ecf-b535-419eb90a9290, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:24,522 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 7f0d8380-6b8a-432f-b7c9-ca59d0488321, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:24,525 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 7f0d8380-6b8a-432f-b7c9-ca59d0488321, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.STD//Messages" columns { name: "QueueIdNumberAndShardHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Shard" type { optional_type { item { type_id: UINT32 } } } } columns { name: "Offset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "SentTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "DelayDeadline" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberAndShardHash" primary_key: "QueueIdNumber" primary_key: "Shard" primary_key: "Offset" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:24,926 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 7f0d8380-6b8a-432f-b7c9-ca59d0488321, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:24,928 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, a31bb8ca-e022-4439-9b55-da4264945bcc, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:24,940 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, a31bb8ca-e022-4439-9b55-da4264945bcc, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.STD//State" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Shard" type { optional_type { item { type_id: UINT32 } } } } columns { name: "CleanupTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "CreatedTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "LastModifiedTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RetentionBoundary" type { optional_type { item { type_id: UINT64 } } } } columns { name: "InflyCount" type { optional_type { item { type_id: INT64 } } } } columns { name: "MessageCount" type { optional_type { item { type_id: INT64 } } } } columns { name: "ReadOffset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "WriteOffset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "CleanupVersion" type { optional_type { item { type_id: UINT64 } } } } columns { name: "InflyVersion" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" primary_key: "Shard" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:25,294 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, a31bb8ca-e022-4439-9b55-da4264945bcc, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:25,296 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 72c9ca88-5bc5-434a-aa3f-f6be9d22ab07, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:25,303 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 72c9ca88-5bc5-434a-aa3f-f6be9d22ab07, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.STD//Attributes" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ContentBasedDeduplication" type { optional_type { item { type_id: BOOL } } } } columns { name: "DelaySeconds" type { optional_type { item { type_id: UINT64 } } } } columns { name: "FifoQueue" type { optional_type { item { type_id: BOOL } } } } columns { name: "MaximumMessageSize" type { optional_type { item { type_id: UINT64 } } } } columns { name: "MessageRetentionPeriod" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ReceiveMessageWaitTime" type { optional_type { item { type_id: UINT64 } } } } columns { name: "VisibilityTimeout" type { optional_type { item { type_id: UINT64 } } } } columns { name: "DlqName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "DlqArn" type { optional_type { item { type_id: UTF8 } } } } columns { name: "MaxReceiveCount" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ShowDetailedCountersDeadline" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:25,632 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 72c9ca88-5bc5-434a-aa3f-f6be9d22ab07, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:25,634 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, cda594df-ce83-452e-93da-8e38cb71bb10, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:25,644 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, cda594df-ce83-452e-93da-8e38cb71bb10, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.FIFO//SentTimestampIdx" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "SentTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Offset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "DelayDeadline" type { optional_type { item { type_id: UINT64 } } } } columns { name: "GroupId" type { optional_type { item { type_id: STRING } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" primary_key: "SentTimestamp" primary_key: "Offset" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:25,923 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, cda594df-ce83-452e-93da-8e38cb71bb10, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:25,925 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, db5b205e-ded9-470a-9331-d8f5e3373dd8, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:25,928 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, db5b205e-ded9-470a-9331-d8f5e3373dd8, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.FIFO//Messages" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Offset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "GroupId" type { optional_type { item { type_id: STRING } } } } columns { name: "NextOffset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "NextRandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ReceiveCount" type { optional_type { item { type_id: UINT32 } } } } columns { name: "FirstReceiveTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "SentTimestamp" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" primary_key: "Offset" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:26,136 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, db5b205e-ded9-470a-9331-d8f5e3373dd8, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:26,138 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 599796b0-ebd0-476f-b0ac-df1737ca73ae, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:26,142 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 599796b0-ebd0-476f-b0ac-df1737ca73ae, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.FIFO//State" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "CleanupTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "CreatedTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "LastModifiedTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RetentionBoundary" type { optional_type { item { type_id: UINT64 } } } } columns { name: "InflyCount" type { optional_type { item { type_id: INT64 } } } } columns { name: "MessageCount" type { optional_type { item { type_id: INT64 } } } } columns { name: "ReadOffset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "WriteOffset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "CleanupVersion" type { optional_type { item { type_id: UINT64 } } } } columns { name: "InflyVersion" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:26,410 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 599796b0-ebd0-476f-b0ac-df1737ca73ae, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:26,412 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, fc32610a-a97e-4172-88ce-d7adf75f51d8, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:26,420 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, fc32610a-a97e-4172-88ce-d7adf75f51d8, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.FIFO//Attributes" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ContentBasedDeduplication" type { optional_type { item { type_id: BOOL } } } } columns { name: "DelaySeconds" type { optional_type { item { type_id: UINT64 } } } } columns { name: "FifoQueue" type { optional_type { item { type_id: BOOL } } } } columns { name: "MaximumMessageSize" type { optional_type { item { type_id: UINT64 } } } } columns { name: "MessageRetentionPeriod" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ReceiveMessageWaitTime" type { optional_type { item { type_id: UINT64 } } } } columns { name: "VisibilityTimeout" type { optional_type { item { type_id: UINT64 } } } } columns { name: "DlqName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "DlqArn" type { optional_type { item { type_id: UTF8 } } } } columns { name: "MaxReceiveCount" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ShowDetailedCountersDeadline" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:26,691 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, fc32610a-a97e-4172-88ce-d7adf75f51d8, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:26,693 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, a09edcd8-8e55-4fbb-80d4-c4e66e24b009, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:26,696 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, a09edcd8-8e55-4fbb-80d4-c4e66e24b009, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.STD//Infly" columns { name: "QueueIdNumberAndShardHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Shard" type { optional_type { item { type_id: UINT32 } } } } columns { name: "Offset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "LoadId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "FirstReceiveTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "LockTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ReceiveCount" type { optional_type { item { type_id: UINT32 } } } } columns { name: "SentTimestamp" type { optional_type { item { type_id: UINT64 } } } } columns { name: "VisibilityDeadline" type { optional_type { item { type_id: UINT64 } } } } columns { name: "DelayDeadline" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberAndShardHash" primary_key: "QueueIdNumber" primary_key: "Shard" primary_key: "Offset" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:26,990 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, a09edcd8-8e55-4fbb-80d4-c4e66e24b009, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:26,992 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 3c787a6c-cc67-4e88-a104-874e6217239d, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:26,996 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 3c787a6c-cc67-4e88-a104-874e6217239d, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.STD//MessageData" columns { name: "QueueIdNumberAndShardHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Shard" type { optional_type { item { type_id: UINT32 } } } } columns { name: "RandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Offset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Attributes" type { optional_type { item { type_id: STRING } } } } columns { name: "Data" type { optional_type { item { type_id: STRING } } } } columns { name: "MessageId" type { optional_type { item { type_id: STRING } } } } columns { name: "SenderId" type { optional_type { item { type_id: STRING } } } } primary_key: "QueueIdNumberAndShardHash" primary_key: "QueueIdNumber" primary_key: "Shard" primary_key: "RandomId" primary_key: "Offset" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:27,438 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 3c787a6c-cc67-4e88-a104-874e6217239d, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:27,440 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, d3c0a845-3b22-483a-b3e5-28c3a5d710dd, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:27,443 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, d3c0a845-3b22-483a-b3e5-28c3a5d710dd, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.FIFO//Reads" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ReceiveAttemptId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "Deadline" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" primary_key: "ReceiveAttemptId" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:27,769 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, d3c0a845-3b22-483a-b3e5-28c3a5d710dd, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:27,771 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 42665e77-fb1c-4e1f-8bcf-3ed7ec9b1451, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:27,777 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 42665e77-fb1c-4e1f-8bcf-3ed7ec9b1451, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.FIFO//Groups" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "GroupId" type { optional_type { item { type_id: STRING } } } } columns { name: "VisibilityDeadline" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Head" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Tail" type { optional_type { item { type_id: UINT64 } } } } columns { name: "ReceiveAttemptId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "LockTimestamp" type { optional_type { item { type_id: UINT64 } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" primary_key: "GroupId" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:28,078 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 42665e77-fb1c-4e1f-8bcf-3ed7ec9b1451, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:28,080 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 7ff5ce51-22c6-4e7d-a6da-8980f354bbc3, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:28,084 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 7ff5ce51-22c6-4e7d-a6da-8980f354bbc3, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.FIFO//Deduplication" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "DedupId" type { optional_type { item { type_id: STRING } } } } columns { name: "Deadline" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Offset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "MessageId" type { optional_type { item { type_id: STRING } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" primary_key: "DedupId" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:28,293 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 7ff5ce51-22c6-4e7d-a6da-8980f354bbc3, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:28,296 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, e53dbe7e-35ba-4a7b-b39b-826198362cae, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:28,298 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, e53dbe7e-35ba-4a7b-b39b-826198362cae, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" path: "/Root/TenantSQS/.FIFO//Data" columns { name: "QueueIdNumberHash" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueIdNumber" type { optional_type { item { type_id: UINT64 } } } } columns { name: "RandomId" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Offset" type { optional_type { item { type_id: UINT64 } } } } columns { name: "SenderId" type { optional_type { item { type_id: STRING } } } } columns { name: "DedupId" type { optional_type { item { type_id: STRING } } } } columns { name: "Attributes" type { optional_type { item { type_id: STRING } } } } columns { name: "Data" type { optional_type { item { type_id: STRING } } } } columns { name: "MessageId" type { optional_type { item { type_id: STRING } } } } primary_key: "QueueIdNumberHash" primary_key: "QueueIdNumber" primary_key: "RandomId" primary_key: "Offset" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } uniform_partitions: 10 partitioning_settings { partitioning_by_size: ENABLED partitioning_by_load: ENABLED min_partitions_count: 10 } }
2025-10-15 15:13:28,457 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, e53dbe7e-35ba-4a7b-b39b-826198362cae, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:28,458 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7bbd2bdc44b0>
2025-10-15 15:13:28,459 - DEBUG - ydb.table.SessionPool - stop: Requested session pool stop.
2025-10-15 15:13:28,459 - DEBUG - ydb.table.SessionPool - stop: Session pool is under stop, cancelling all in flight waiters.
2025-10-15 15:13:28,460 - DEBUG - ydb.table.SessionPool - stop: Destroying sessions in active queue
2025-10-15 15:13:28,460 - DEBUG - ydb.table.SessionPool - _destroy: Requested session destroy: <ydb.table.Session object at 0x7bbd2bdc44b0>, reason: session-pool-terminated
2025-10-15 15:13:28,460 - DEBUG - ydb.table.SessionPool - _destroy: Session <ydb.table.Session object at 0x7bbd2bdc44b0> is no longer active. Current active count 0.
2025-10-15 15:13:28,461 - DEBUG - ydb.table.SessionPool - _terminate_event: Terminated session pool.
2025-10-15 15:13:28,462 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 633c9b54-b06b-4808-a398-f2813db0cb93, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:28,464 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 633c9b54-b06b-4808-a398-f2813db0cb93, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=N2JlMjViZWEtN2VjYzI0ZGEtNWYzOGFlNGItOTBkYWM4MDU=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-10-15 15:13:28,466 - DEBUG - ydb.table.SessionPool - _destroy: Sent delete on session <ydb.table.Session object at 0x7bbd2bdc44b0>
2025-10-15 15:13:28,467 - DEBUG - ydb.table.SessionPool - stop: Destroyed active sessions
2025-10-15 15:13:28,467 - DEBUG - ydb.topic - close: Close topic client
2025-10-15 15:13:28,468 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-15 15:13:28,468 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:20824
2025-10-15 15:13:28,471 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-lvxbqnxafi.auto.internal:31205
2025-10-15 15:13:28,472 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-15 15:13:28,475 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 633c9b54-b06b-4808-a398-f2813db0cb93, ghrun-lvxbqnxafi.auto.internal:31205): on response callback started
2025-10-15 15:13:28,476 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 633c9b54-b06b-4808-a398-f2813db0cb93, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS } }
2025-10-15 15:13:28,477 - DEBUG - ydb.connection - _on_response_callback: RpcState(DeleteSession, 633c9b54-b06b-4808-a398-f2813db0cb93, ghrun-lvxbqnxafi.auto.internal:31205): on response callback success
2025-10-15 15:13:28,481 - DEBUG - root - setup_method: Test started: test_empty_access_key_id
2025-10-15 15:13:28,482 - DEBUG - root - setup_method: Kikimr working dir: /home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0
2025-10-15 15:13:28,482 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/kikimr_configs"
2025-10-15 15:13:28,483 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001940/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/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/node_1/logfile_0492gm94.log --grpc-port=20824 --mon-port=11119 --ic-port=4394 --sqs-port=11153
2025-10-15 15:13:28,483 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:20824/1
2025-10-15 15:13:28,485 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-10-15 15:13:28,486 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-10-15 15:13:28,487 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:13:28,488 - DEBUG - ya.test - execute: Command pid: 619783
2025-10-15 15:13:29,462 - DEBUG - ya.test - _save_outputs: Command (pid 619783) rc: 0
2025-10-15 15:13:29,463 - DEBUG - ya.test - _save_outputs: Command (pid 619783) elapsed time (sec): 0.9729058742523193
2025-10-15 15:13:29,463 - DEBUG - ya.test - _save_outputs: Command (pid 619783) idrss: 0
2025-10-15 15:13:29,464 - DEBUG - ya.test - _save_outputs: Command (pid 619783) inblock: 0
2025-10-15 15:13:29,464 - DEBUG - ya.test - _save_outputs: Command (pid 619783) isrss: 0
2025-10-15 15:13:29,465 - DEBUG - ya.test - _save_outputs: Command (pid 619783) ixrss: 0
2025-10-15 15:13:29,465 - DEBUG - ya.test - _save_outputs: Command (pid 619783) majflt: 0
2025-10-15 15:13:29,465 - DEBUG - ya.test - _save_outputs: Command (pid 619783) maxrss: 596080
2025-10-15 15:13:29,466 - DEBUG - ya.test - _save_outputs: Command (pid 619783) minflt: 45150
2025-10-15 15:13:29,466 - DEBUG - ya.test - _save_outputs: Command (pid 619783) msgrcv: 0
2025-10-15 15:13:29,466 - DEBUG - ya.test - _save_outputs: Command (pid 619783) msgsnd: 0
2025-10-15 15:13:29,467 - DEBUG - ya.test - _save_outputs: Command (pid 619783) nivcsw: 82
2025-10-15 15:13:29,467 - DEBUG - ya.test - _save_outputs: Command (pid 619783) nsignals: 0
2025-10-15 15:13:29,467 - DEBUG - ya.test - _save_outputs: Command (pid 619783) nswap: 0
2025-10-15 15:13:29,468 - DEBUG - ya.test - _save_outputs: Command (pid 619783) nvcsw: 14
2025-10-15 15:13:29,468 - DEBUG - ya.test - _save_outputs: Command (pid 619783) oublock: 8
2025-10-15 15:13:29,468 - DEBUG - ya.test - _save_outputs: Command (pid 619783) stime: 0.222043
2025-10-15 15:13:29,469 - DEBUG - ya.test - _save_outputs: Command (pid 619783) utime: 0.5316449999999999
2025-10-15 15:13:29,469 - DEBUG - ya.test - _save_outputs: Command (pid 619783) wtime: 0.975
2025-10-15 15:13:29,469 - DEBUG - ya.test - _save_outputs: Command (pid 619783) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 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-10-15 15:13:29,470 - DEBUG - ya.test - _save_outputs: Command (pid 619783) errors:

2025-10-15 15:13:29,470 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 15:13:29,471 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/kikimr_configs"
2025-10-15 15:13:29,471 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:20824 --tenant=/Root/TenantSQS --log-file-name=/home/runner/.ya/build/build_root/w2kl/001940/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk27/testing_out_stuff/test_yandex_cloud_mode.py.TestSqsYandexCloudMode.test_empty_access_key_id.tables_format_v0/cluster/slot_1/logfile_lh5rrdcw.log --grpc-port=31205 --mon-port=22953 --ic-port=62469 --sqs-port=11721
2025-10-15 15:13:29,472 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:31205/1
2025-10-15 15:13:29,474 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-10-15 15:13:29,475 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-10-15 15:13:29,475 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001940/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:13:29,485 - DEBUG - ya.test - execute: Command pid: 620223
2025-10-15 15:13:30,333 - DEBUG - ya.test - _save_outputs: Command (pid 620223) rc: 0
2025-10-15 15:13:30,334 - DEBUG - ya.test - _save_outputs: Command (pid 620223) elapsed time (sec): 0.8469345569610596
2025-10-15 15:13:30,334 - DEBUG - ya.test - _save_outputs: Command (pid 620223) idrss: 0
2025-10-15 15:13:30,334 - DEBUG - ya.test - _save_outputs: Command (pid 620223) inblock: 0
2025-10-15 15:13:30,334 - DEBUG - ya.test - _save_outputs: Command (pid 620223) isrss: 0
2025-10-15 15:13:30,334 - DEBUG - ya.test - _save_outputs: Command (pid 620223) ixrss: 0
2025-10-15 15:13:30,335 - DEBUG - ya.test - _save_outputs: Command (pid 620223) majflt: 0
2025-10-15 15:13:30,335 - DEBUG - ya.test - _save_outputs: Command (pid 620223) maxrss: 596080
2025-10-15 15:13:30,335 - DEBUG - ya.test - _save_outputs: Command (pid 620223) minflt: 45113
2025-10-15 15:13:30,335 - DEBUG - ya.test - _save_outputs: Command (pid 620223) msgrcv: 0
2025-10-15 15:13:30,335 - DEBUG - ya.test - _save_outputs: Command (pid 620223) msgsnd: 0
2025-10-15 15:13:30,336 - DEBUG - ya.test - _save_outputs: Command (pid 620223) nivcsw: 115
2025-10-15 15:13:30,336 - DEBUG - ya.test - _save_outputs: Command (pid 620223) nsignals: 0
2025-10-15 15:13:30,336 - DEBUG - ya.test - _save_outputs: Command (pid 620223) nswap: 0
2025-10-15 15:13:30,337 - DEBUG - ya.test - _save_outputs: Command (pid 620223) nvcsw: 15
2025-10-15 15:13:30,337 - DEBUG - ya.test - _save_outputs: Command (pid 620223) oublock: 8
2025-10-15 15:13:30,338 - DEBUG - ya.test - _save_outputs: Command (pid 620223) stime: 0.214833
2025-10-15 15:13:30,338 - DEBUG - ya.test - _save_outputs: Command (pid 620223) utime: 0.52018
2025-10-15 15:13:30,338 - DEBUG - ya.test - _save_outputs: Command (pid 620223) wtime: 0.857
2025-10-15 15:13:30,339 - DEBUG - ya.test - _save_outputs: Command (pid 620223) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 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-10-15 15:13:30,339 - DEBUG - ya.test - _save_outputs: Command (pid 620223) errors:

2025-10-15 15:13:30,340 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 15:13:30,347 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:22953
2025-10-15 15:13:30,360 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:22953 "GET /tablets/app?TabletID=72057594037968897&node=1&page=SetDown&down=0 HTTP/1.1" 200 33
2025-10-15 15:13:30,362 - DEBUG - root - _enable_tablets_on_node: Toggle up reply: {"NodeId":1,"Down":false}
2025-10-15 15:13:30,375 - INFO - ydb.tests.library.sqs.test_base - wait_can_list_users: Listing SQS users
2025-10-15 15:13:30,376 - DEBUG - ydb.tests.library.sqs.requests_client - execute_request: Execute request POST http://localhost:11721 from user U_empty_access_key_id_82110a76-a9d9-11f0-916b-d00daf17e6f1 with params: Action=ListQueues
2025-10-15 15:13:30,379 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:11721
2025-10-15 15:13:30,431 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:11721 "POST / HTTP/1.1" 400 279
2025-10-15 15:13:30,462 - DEBUG - charset_normalizer - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-10-15 15:13:30,463 - 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: 37aeda2c-66d2d1a3-9bd48c18-9abd9ccc.</Message><Code>IncompleteSignature</Code></Error><RequestId></RequestId></ErrorResponse>
'
2025-10-15 15:13:30,465 - DEBUG - charset_normalizer - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-10-15 15:13:30,467 - DEBUG - charset_normalizer - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-10-15 15:13:30,467 - INFO - ydb.tests.library.sqs.test_base - wait_can_list_users: Success. Api is up.
2025-10-15 15:13:30,468 - 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-10-15 15:13:30,472 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:13:30,473 - 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-10-15 15:13:30,488 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root/TenantSQS
2025-10-15 15:13:30,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, d94c8a57-94f6-4d2f-b5c5-35b4d06dcc37, localhost:31205): creating call state
2025-10-15 15:13:30,489 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, d94c8a57-94f6-4d2f-b5c5-35b4d06dcc37, localhost:31205): request = { database: "/Root/TenantSQS" }
2025-10-15 15:13:30,502 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, d94c8a57-94f6-4d2f-b5c5-35b4d06dcc37, localhost:31205): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 31205 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-10-15 15:13:30,503 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root/TenantSQS: DiscoveryResult <self_location: , endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:31205, location , ssl: False>]>
2025-10-15 15:13:30,504 - 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-10-15 15:13:30,519 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:31205
2025-10-15 15:13:30,521 - INFO - root - _before_test_start: run test with cloud_id=CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1 folder_id=folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1
2025-10-15 15:13:30,524 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_yandex_cloud_mode.py::TestSqsYandexCloudMode, test_name: test_empty_access_key_id[tables_format_v0])
2025-10-15 15:13:30,525 - DEBUG - root - _execute_yql_query: Executing database query: "UPSERT INTO `/Root/TenantSQS/.Settings` (Account, Name, Value)                 VALUES ("CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1", "CreateQueuesWithTabletFormat", "0")"
2025-10-15 15:13:30,526 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 826de6b6-9fe7-4687-ac05-b27eb5c6ebc5, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:30,527 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 826de6b6-9fe7-4687-ac05-b27eb5c6ebc5, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 15:13:30,538 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 826de6b6-9fe7-4687-ac05-b27eb5c6ebc5, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=Y2NhYThlZjItYzM1MDg2NjAtOTk0YTczZGYtNWU3Zjk0Mjg=" } } } }
2025-10-15 15:13:30,540 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, e5637180-9ff6-4f22-8cfe-10cc70e20d3d, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:30,546 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, e5637180-9ff6-4f22-8cfe-10cc70e20d3d, ghrun-lvxbqnxafi.auto.internal:31205): request = { session_id: "ydb://session/3?node_id=50000&id=Y2NhYThlZjItYzM1MDg2NjAtOTk0YTczZGYtNWU3Zjk0Mjg=" 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_822911f6-a9d9-11f0-8051-d00daf17e6f1\", \"CreateQueuesWithTabletFormat\", \"0\")" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-10-15 15:13:30,634 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, e5637180-9ff6-4f22-8cfe-10cc70e20d3d, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-10-15 15:13:30,648 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from creating-client-class.iot-data to creating-client-class.iot-data-plane
2025-10-15 15:13:30,666 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-call.apigateway to before-call.api-gateway
2025-10-15 15:13:30,675 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from request-created.machinelearning.Predict to request-created.machine-learning.Predict
2025-10-15 15:13:30,689 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.autoscaling.CreateLaunchConfiguration to before-parameter-build.auto-scaling.CreateLaunchConfiguration
2025-10-15 15:13:30,690 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.route53 to before-parameter-build.route-53
2025-10-15 15:13:30,713 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from request-created.cloudsearchdomain.Search to request-created.cloudsearch-domain.Search
2025-10-15 15:13:30,720 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.autoscaling.CreateLaunchConfiguration.complete-section to docs.*.auto-scaling.CreateLaunchConfiguration.complete-section
2025-10-15 15:13:30,752 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.logs.CreateExportTask to before-parameter-build.cloudwatch-logs.CreateExportTask
2025-10-15 15:13:30,753 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.logs.CreateExportTask.complete-section to docs.*.cloudwatch-logs.CreateExportTask.complete-section
2025-10-15 15:13:30,754 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.cloudsearchdomain.Search to before-parameter-build.cloudsearch-domain.Search
2025-10-15 15:13:30,755 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.cloudsearchdomain.Search.complete-section to docs.*.cloudsearch-domain.Search.complete-section
2025-10-15 15:13:30,771 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/endpoints.json
2025-10-15 15:13:30,925 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sdk-default-configuration.json
2025-10-15 15:13:30,927 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7c5d2bb34970>
2025-10-15 15:13:31,070 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sqs/2012-11-05/service-2.json
2025-10-15 15:13:31,082 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sqs/2012-11-05/endpoint-rule-set-1.json.gz
2025-10-15 15:13:31,085 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/partitions.json
2025-10-15 15:13:31,090 - DEBUG - botocore.hooks - _emit: Event creating-client-class.sqs: calling handler <function add_generate_presigned_url at 0x7c5d2bddbeb0>
2025-10-15 15:13:31,095 - DEBUG - botocore.regions - _endpoint_for_partition: Creating a regex based endpoint for sqs, ru-central1
2025-10-15 15:13:31,106 - DEBUG - botocore.endpoint - create_endpoint: Setting sqs timeout as (60, 60)
2025-10-15 15:13:31,111 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/_retry.json
2025-10-15 15:13:31,114 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: sqs
2025-10-15 15:13:31,116 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.sqs.CreateQueue: calling handler <function generate_idempotent_uuid at 0x7c5d2bb30fb0>
2025-10-15 15:13:31,118 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Region': 'ru-central1', 'UseDualStack': False, 'UseFIPS': False, 'Endpoint': 'http://localhost:11721'}
2025-10-15 15:13:31,119 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:11721
2025-10-15 15:13:31,121 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.CreateQueue: calling handler <function add_recursion_detection_header at 0x7c5d2bb345f0>
2025-10-15 15:13:31,121 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.CreateQueue: calling handler <function inject_api_version_header_if_needed at 0x7c5d2bb1c870>
2025-10-15 15:13:31,122 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=CreateQueue) with params: {'url_path': '/', 'query_string': '', 'method': 'POST', 'headers': {'Content-Type': 'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 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.31.80'}, 'body': {'Action': 'CreateQueue', 'Version': '2012-11-05', 'QueueName': 'Q_empty_access_key_id_82128c9c-a9d9-11f0-acfd-d00daf17e6f1'}, 'url': 'http://localhost:11721/', 'context': {'client_region': 'ru-central1', 'client_config': <botocore.config.Config object at 0x7bbd2db45b70>, 'has_streaming_input': False, 'auth_type': None}}
2025-10-15 15:13:31,124 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.CreateQueue: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bbd2db45b30>>
2025-10-15 15:13:31,127 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.CreateQueue: calling handler <function set_operation_specific_signer at 0x7c5d2bb315d0>
2025-10-15 15:13:31,130 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-10-15 15:13:31,130 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:11721
x-amz-date:20251015T151331Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
745647db80e131f5f5be2bff94a14a7ce722c5d431aeb15c4e48cf897297ff47
2025-10-15 15:13:31,130 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251015T151331Z
20251015/ru-central1/sqs/aws4_request
01ab401669f4f5629f5aac0d0d7afa0510e91a87d91323755fa68e59defb82e3
2025-10-15 15:13:31,131 - DEBUG - botocore.auth - add_auth: Signature:
b00db36b30bfac3e6428ecc6df0147a4db1cfe86e883d0680d39b02fec832f5d
2025-10-15 15:13:31,132 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.CreateQueue: calling handler <function add_retry_headers at 0x7c5d2bb1c6b0>
2025-10-15 15:13:31,133 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:11721/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 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.31.80', 'X-Amz-Date': b'20251015T151331Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=sa_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/20251015/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=b00db36b30bfac3e6428ecc6df0147a4db1cfe86e883d0680d39b02fec832f5d', 'amz-sdk-invocation-id': b'3fb15757-d7e5-4d63-bf8b-00cbf002b1b5', 'amz-sdk-request': b'attempt=1', 'Content-Length': '106'}>
2025-10-15 15:13:31,137 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:11721
2025-10-15 15:13:31,402 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:11721 "POST / HTTP/1.1" 200 436
2025-10-15 15:13:31,403 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '436', 'Connection': 'Keep-Alive'}
2025-10-15 15:13:31,403 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<CreateQueueResponse><CreateQueueResult><QueueUrl>http://ghrun-lvxbqnxafi.auto.internal:11721/CLOUD_FOR_FOLDER_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/000000000000000101s2/Q_empty_access_key_id_82128c9c-a9d9-11f0-acfd-d00daf17e6f1</QueueUrl></CreateQueueResult><ResponseMetadata><RequestId>e8897bc6-f2fccb68-a4d37845-f2ebe4fa</RequestId></ResponseMetadata></CreateQueueResponse>\n'
2025-10-15 15:13:31,411 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.CreateQueue: calling handler <botocore.retryhandler.RetryHandler object at 0x7bbd2db585f0>
2025-10-15 15:13:31,412 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-10-15 15:13:31,415 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.sqs.ListQueues: calling handler <function generate_idempotent_uuid at 0x7c5d2bb30fb0>
2025-10-15 15:13:31,416 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Region': 'ru-central1', 'UseDualStack': False, 'UseFIPS': False, 'Endpoint': 'http://localhost:11721'}
2025-10-15 15:13:31,416 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:11721
2025-10-15 15:13:31,418 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.ListQueues: calling handler <function add_recursion_detection_header at 0x7c5d2bb345f0>
2025-10-15 15:13:31,418 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.ListQueues: calling handler <function inject_api_version_header_if_needed at 0x7c5d2bb1c870>
2025-10-15 15:13:31,419 - 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.29.6 md/Botocore#1.31.80 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.31.80'}, 'body': {'Action': 'ListQueues', 'Version': '2012-11-05'}, 'url': 'http://localhost:11721/', 'context': {'client_region': 'ru-central1', 'client_config': <botocore.config.Config object at 0x7bbd2db45b70>, 'has_streaming_input': False, 'auth_type': None}}
2025-10-15 15:13:31,421 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bbd2db45b30>>
2025-10-15 15:13:31,422 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.ListQueues: calling handler <function set_operation_specific_signer at 0x7c5d2bb315d0>
2025-10-15 15:13:31,424 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-10-15 15:13:31,424 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:11721
x-amz-date:20251015T151331Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
48a38266faf90970d6c7fea9b15e6ba366e5f6397c2970fc893f8a7b5e207bd0
2025-10-15 15:13:31,425 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251015T151331Z
20251015/ru-central1/sqs/aws4_request
d6af7b5c5c987db48f7a8895b268a3cbaa383c372c962248f32b7a21431237c3
2025-10-15 15:13:31,425 - DEBUG - botocore.auth - add_auth: Signature:
0d26d27f04b63017fb5c70a370d808df1cb1b2f640f34a7c28480e1b7ada25f9
2025-10-15 15:13:31,426 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <function add_retry_headers at 0x7c5d2bb1c6b0>
2025-10-15 15:13:31,427 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:11721/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 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.31.80', 'X-Amz-Date': b'20251015T151331Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=sa_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/20251015/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=0d26d27f04b63017fb5c70a370d808df1cb1b2f640f34a7c28480e1b7ada25f9', 'amz-sdk-invocation-id': b'785e4f45-b83b-4af2-9434-d4c90fd3f35d', 'amz-sdk-request': b'attempt=1', 'Content-Length': '36'}>
2025-10-15 15:13:31,444 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:11721 "POST / HTTP/1.1" 200 432
2025-10-15 15:13:31,446 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '432', 'Connection': 'Keep-Alive'}
2025-10-15 15:13:31,446 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<ListQueuesResponse><ListQueuesResult><QueueUrl>http://ghrun-lvxbqnxafi.auto.internal:11721/CLOUD_FOR_FOLDER_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/000000000000000101s2/Q_empty_access_key_id_82128c9c-a9d9-11f0-acfd-d00daf17e6f1</QueueUrl></ListQueuesResult><ResponseMetadata><RequestId>6c3e02f8-a1f28155-ddd424aa-c935a753</RequestId></ResponseMetadata></ListQueuesResponse>\n'
2025-10-15 15:13:31,448 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.ListQueues: calling handler <botocore.retryhandler.RetryHandler object at 0x7bbd2db585f0>
2025-10-15 15:13:31,450 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-10-15 15:13:31,453 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.sqs.SendMessage: calling handler <function generate_idempotent_uuid at 0x7c5d2bb30fb0>
2025-10-15 15:13:31,453 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Region': 'ru-central1', 'UseDualStack': False, 'UseFIPS': False, 'Endpoint': 'http://localhost:11721'}
2025-10-15 15:13:31,454 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:11721
2025-10-15 15:13:31,455 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.SendMessage: calling handler <function add_recursion_detection_header at 0x7c5d2bb345f0>
2025-10-15 15:13:31,456 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.SendMessage: calling handler <function inject_api_version_header_if_needed at 0x7c5d2bb1c870>
2025-10-15 15:13:31,456 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=SendMessage) with params: {'url_path': '/', 'query_string': '', 'method': 'POST', 'headers': {'Content-Type': 'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 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.31.80'}, 'body': {'Action': 'SendMessage', 'Version': '2012-11-05', 'QueueUrl': 'http://ghrun-lvxbqnxafi.auto.internal:11721/CLOUD_FOR_FOLDER_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/000000000000000101s2/Q_empty_access_key_id_82128c9c-a9d9-11f0-acfd-d00daf17e6f1', 'MessageBody': 'awesome'}, 'url': 'http://localhost:11721/', 'context': {'client_region': 'ru-central1', 'client_config': <botocore.config.Config object at 0x7bbd2db45b70>, 'has_streaming_input': False, 'auth_type': None}}
2025-10-15 15:13:31,458 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.SendMessage: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bbd2db45b30>>
2025-10-15 15:13:31,459 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.SendMessage: calling handler <function set_operation_specific_signer at 0x7c5d2bb315d0>
2025-10-15 15:13:31,461 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-10-15 15:13:31,462 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:11721
x-amz-date:20251015T151331Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
939be8d87081f64e354c66b1e467f01dcc28ecf2f43b69a0acb33e3ca237d49f
2025-10-15 15:13:31,462 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251015T151331Z
20251015/ru-central1/sqs/aws4_request
2c5197d180fda056b516c0fab5eed68f66c327bcfc72db2872616e153742c43b
2025-10-15 15:13:31,463 - DEBUG - botocore.auth - add_auth: Signature:
efb5880a65b9cbecc4bbafee85e9bb8bdd81adeb00caef457ce686a079c43286
2025-10-15 15:13:31,464 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.SendMessage: calling handler <function add_retry_headers at 0x7c5d2bb1c6b0>
2025-10-15 15:13:31,465 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:11721/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 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.31.80', 'X-Amz-Date': b'20251015T151331Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=sa_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/20251015/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=efb5880a65b9cbecc4bbafee85e9bb8bdd81adeb00caef457ce686a079c43286', 'amz-sdk-invocation-id': b'a0a29e8f-c77e-4224-afb8-2a332c31bff2', 'amz-sdk-request': b'attempt=1', 'Content-Length': '279'}>
2025-10-15 15:13:31,661 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:11721 "POST / HTTP/1.1" 200 343
2025-10-15 15:13:31,663 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '343', 'Connection': 'Keep-Alive'}
2025-10-15 15:13:31,663 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<SendMessageResponse><SendMessageResult><MD5OfMessageBody>be121740bf988b2225a313fa1f107ca1</MD5OfMessageBody><MessageId>bdb9fcd5-bb43975-5c737e59-bd625b89</MessageId></SendMessageResult><ResponseMetadata><RequestId>b5dbc1ec-3164b97d-e2661c41-fb422d80</RequestId></ResponseMetadata></SendMessageResponse>\n'
2025-10-15 15:13:31,666 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.SendMessage: calling handler <botocore.retryhandler.RetryHandler object at 0x7bbd2db585f0>
2025-10-15 15:13:31,667 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-10-15 15:13:31,686 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from creating-client-class.iot-data to creating-client-class.iot-data-plane
2025-10-15 15:13:31,725 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-call.apigateway to before-call.api-gateway
2025-10-15 15:13:31,734 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from request-created.machinelearning.Predict to request-created.machine-learning.Predict
2025-10-15 15:13:31,747 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.autoscaling.CreateLaunchConfiguration to before-parameter-build.auto-scaling.CreateLaunchConfiguration
2025-10-15 15:13:31,748 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.route53 to before-parameter-build.route-53
2025-10-15 15:13:31,771 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from request-created.cloudsearchdomain.Search to request-created.cloudsearch-domain.Search
2025-10-15 15:13:31,778 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.autoscaling.CreateLaunchConfiguration.complete-section to docs.*.auto-scaling.CreateLaunchConfiguration.complete-section
2025-10-15 15:13:31,799 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.logs.CreateExportTask to before-parameter-build.cloudwatch-logs.CreateExportTask
2025-10-15 15:13:31,800 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.logs.CreateExportTask.complete-section to docs.*.cloudwatch-logs.CreateExportTask.complete-section
2025-10-15 15:13:31,801 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.cloudsearchdomain.Search to before-parameter-build.cloudsearch-domain.Search
2025-10-15 15:13:31,802 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.cloudsearchdomain.Search.complete-section to docs.*.cloudsearch-domain.Search.complete-section
2025-10-15 15:13:31,814 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/endpoints.json
2025-10-15 15:13:31,954 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sdk-default-configuration.json
2025-10-15 15:13:31,956 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7c5d2bb34970>
2025-10-15 15:13:31,958 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sqs/2012-11-05/service-2.json
2025-10-15 15:13:31,970 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sqs/2012-11-05/endpoint-rule-set-1.json.gz
2025-10-15 15:13:31,973 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/partitions.json
2025-10-15 15:13:31,976 - DEBUG - botocore.hooks - _emit: Event creating-client-class.sqs: calling handler <function add_generate_presigned_url at 0x7c5d2bddbeb0>
2025-10-15 15:13:31,979 - DEBUG - botocore.regions - _endpoint_for_partition: Creating a regex based endpoint for sqs, ru-central1
2025-10-15 15:13:31,984 - DEBUG - botocore.endpoint - create_endpoint: Setting sqs timeout as (60, 60)
2025-10-15 15:13:31,989 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/_retry.json
2025-10-15 15:13:31,992 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: sqs
2025-10-15 15:13:31,996 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.sqs.ListQueues: calling handler <function generate_idempotent_uuid at 0x7c5d2bb30fb0>
2025-10-15 15:13:31,998 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Region': 'ru-central1', 'UseDualStack': False, 'UseFIPS': False, 'Endpoint': 'http://localhost:11721'}
2025-10-15 15:13:31,999 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:11721
2025-10-15 15:13:32,000 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.ListQueues: calling handler <function add_recursion_detection_header at 0x7c5d2bb345f0>
2025-10-15 15:13:32,001 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.ListQueues: calling handler <function inject_api_version_header_if_needed at 0x7c5d2bb1c870>
2025-10-15 15:13:32,001 - 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.29.6 md/Botocore#1.31.80 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.31.80'}, 'body': {'Action': 'ListQueues', 'Version': '2012-11-05'}, 'url': 'http://localhost:11721/', 'context': {'client_region': 'ru-central1', 'client_config': <botocore.config.Config object at 0x7bbd2de566f0>, 'has_streaming_input': False, 'auth_type': None}}
2025-10-15 15:13:32,067 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bbd2de566b0>>
2025-10-15 15:13:32,069 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.ListQueues: calling handler <function set_operation_specific_signer at 0x7c5d2bb315d0>
2025-10-15 15:13:32,071 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-10-15 15:13:32,072 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:11721
x-amz-date:20251015T151332Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
48a38266faf90970d6c7fea9b15e6ba366e5f6397c2970fc893f8a7b5e207bd0
2025-10-15 15:13:32,072 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251015T151332Z
20251015/ru-central1/sqs/aws4_request
2ef1938c2eb5d94dbfd8820fa393281d78b695b4f2b7d46b8d3e6972e3ba4eba
2025-10-15 15:13:32,081 - DEBUG - botocore.auth - add_auth: Signature:
3a3a1ca5d00c763474268fcbc9b1f28c49675021201ef7dfbd386960486b84de
2025-10-15 15:13:32,082 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.ListQueues: calling handler <function add_retry_headers at 0x7c5d2bb1c6b0>
2025-10-15 15:13:32,083 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:11721/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 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.31.80', 'X-Amz-Date': b'20251015T151332Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=/20251015/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=3a3a1ca5d00c763474268fcbc9b1f28c49675021201ef7dfbd386960486b84de', 'amz-sdk-invocation-id': b'6ac9edac-c9d1-46ab-8002-6685e18c69fc', 'amz-sdk-request': b'attempt=1', 'Content-Length': '36'}>
2025-10-15 15:13:32,094 - DEBUG - urllib3.connectionpool - _new_conn: Starting new HTTP connection (1): localhost:11721
2025-10-15 15:13:32,110 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:11721 "POST / HTTP/1.1" 403 244
2025-10-15 15:13:32,111 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '244', 'Connection': 'Keep-Alive'}
2025-10-15 15:13:32,112 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?>\n<ErrorResponse><Error><Message>IAM authentication error. Request id to report: 1a41e3a0-d7e46eab-1d68c9a-4b1a13b9.</Message><Code>InvalidClientTokenId</Code></Error><RequestId></RequestId></ErrorResponse>\n'
2025-10-15 15:13:32,119 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.ListQueues: calling handler <botocore.retryhandler.RetryHandler object at 0x7bbd2de65630>
2025-10-15 15:13:32,120 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-10-15 15:13:32,131 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.sqs.SendMessage: calling handler <function generate_idempotent_uuid at 0x7c5d2bb30fb0>
2025-10-15 15:13:32,133 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Region': 'ru-central1', 'UseDualStack': False, 'UseFIPS': False, 'Endpoint': 'http://localhost:11721'}
2025-10-15 15:13:32,134 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:11721
2025-10-15 15:13:32,136 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.SendMessage: calling handler <function add_recursion_detection_header at 0x7c5d2bb345f0>
2025-10-15 15:13:32,136 - DEBUG - botocore.hooks - _emit: Event before-call.sqs.SendMessage: calling handler <function inject_api_version_header_if_needed at 0x7c5d2bb1c870>
2025-10-15 15:13:32,137 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=SendMessage) with params: {'url_path': '/', 'query_string': '', 'method': 'POST', 'headers': {'Content-Type': 'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 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.31.80'}, 'body': {'Action': 'SendMessage', 'Version': '2012-11-05', 'QueueUrl': 'http://ghrun-lvxbqnxafi.auto.internal:11721/CLOUD_FOR_FOLDER_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/000000000000000101s2/Q_empty_access_key_id_82128c9c-a9d9-11f0-acfd-d00daf17e6f1', 'MessageBody': 'psst, wanna some weed?'}, 'url': 'http://localhost:11721/', 'context': {'client_region': 'ru-central1', 'client_config': <botocore.config.Config object at 0x7bbd2de566f0>, 'has_streaming_input': False, 'auth_type': None}}
2025-10-15 15:13:32,139 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.SendMessage: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7bbd2de566b0>>
2025-10-15 15:13:32,141 - DEBUG - botocore.hooks - _emit: Event choose-signer.sqs.SendMessage: calling handler <function set_operation_specific_signer at 0x7c5d2bb315d0>
2025-10-15 15:13:32,143 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-10-15 15:13:32,144 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/

content-type:application/x-www-form-urlencoded; charset=utf-8
host:localhost:11721
x-amz-date:20251015T151332Z
x-amz-security-token:unused

content-type;host;x-amz-date;x-amz-security-token
09774f929b5710ee9ba0d6578afd94e485f96386206216d827586db999c9f2fe
2025-10-15 15:13:32,145 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251015T151332Z
20251015/ru-central1/sqs/aws4_request
420b650332ced1a168090e9fc32e174d5f8e43fddddf13e261a8da8a114dc5ae
2025-10-15 15:13:32,146 - DEBUG - botocore.auth - add_auth: Signature:
7e9ba932325bab0ed45926757691551bbe7628866a6bc9a455904efc2a75bfb0
2025-10-15 15:13:32,146 - DEBUG - botocore.hooks - _emit: Event request-created.sqs.SendMessage: calling handler <function add_retry_headers at 0x7c5d2bb1c6b0>
2025-10-15 15:13:32,147 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:11721/, headers={'Content-Type': b'application/x-www-form-urlencoded; charset=utf-8', 'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 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.31.80', 'X-Amz-Date': b'20251015T151332Z', 'X-Amz-Security-Token': b'unused', 'Authorization': b'AWS4-HMAC-SHA256 Credential=/20251015/ru-central1/sqs/aws4_request, SignedHeaders=content-type;host;x-amz-date;x-amz-security-token, Signature=7e9ba932325bab0ed45926757691551bbe7628866a6bc9a455904efc2a75bfb0', 'amz-sdk-invocation-id': b'b82eecf3-bdc8-4bdd-ae11-d067656a672a', 'amz-sdk-request': b'attempt=1', 'Content-Length': '298'}>
2025-10-15 15:13:32,155 - DEBUG - urllib3.connectionpool - _make_request: http://localhost:11721 "POST / HTTP/1.1" 400 245
2025-10-15 15:13:32,156 - DEBUG - botocore.parsers - parse: Response headers: {'Content-Type': 'application/xml', 'Content-Length': '245', 'Connection': 'Keep-Alive'}
2025-10-15 15:13:32,157 - 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: 22ef298b-c268e7cb-f451cc5f-f6d94cec.</Message><Code>AccessDeniedException</Code></Error><RequestId></RequestId></ErrorResponse>\n'
2025-10-15 15:13:32,160 - DEBUG - botocore.hooks - _emit: Event needs-retry.sqs.SendMessage: calling handler <botocore.retryhandler.RetryHandler object at 0x7bbd2de65630>
2025-10-15 15:13:32,161 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-10-15 15:13:32,165 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-15 15:13:32,166 - DEBUG - ydb.connection - _prepare_call: RpcState(ListDirectory, e310dbff-fe2f-4df1-8261-71dd41278970, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,167 - DEBUG - ydb.connection - _log_request: RpcState(ListDirectory, e310dbff-fe2f-4df1-8261-71dd41278970, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS" }
2025-10-15 15:13:32,183 - DEBUG - ydb.connection - _log_response: RpcState(ListDirectory, e310dbff-fe2f-4df1-8261-71dd41278970, ghrun-lvxbqnxafi.auto.internal:31205): 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" } created_at { tx_id: 1 } } children { name: ".AtomicCounter" owner: "root@builtin" type: TABLE created_at { plan_step: 1760541202840 tx_id: 281474976720660 } } children { name: ".Events" owner: "root@builtin" type: TABLE created_at { plan_step: 1760541203880 tx_id: 281474976720666 } } children { name: ".FIFO" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1760541205920 tx_id: 281474976720676 } } children { name: ".Queues" owner: "root@builtin" type: TABLE created_at { plan_step: 1760541203670 tx_id: 281474976720665 } } children { name: ".RemovedQueues" owner: "root@builtin" type: TABLE created_at { plan_step: 1760541204200 tx_id: 281474976720669 } } children { name: ".STD" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1760541204500 tx_id: 281474976720670 } } children { name: ".Settings" owner: "root@builtin" type: TABLE created_at { plan_step: 1760541204050 tx_id: 281474976720668 } } children { name: ".metadata" owner: "metadata@system" type: DIRECTORY created_at { plan_step: 1760541202730 tx_id: 281474976720659 } } children { name: "CLOUD_FOR_FOLDER_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1" owner: "root@builtin" type: DIRECTORY created_at { plan_step: 1760541211210 tx_id: 281474976720692 } } children { name: ".sys" type: DIRECTORY created_at { } } } } } }
2025-10-15 15:13:32,186 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, a28aa599-f880-491a-9ef2-282f4aaabd59, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,187 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, a28aa599-f880-491a-9ef2-282f4aaabd59, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.Events/Queues" }
2025-10-15 15:13:32,193 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, a28aa599-f880-491a-9ef2-282f4aaabd59, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-10-15 15:13:32,194 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-10-15 15:13:32,195 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 96f173da-11f1-4a30-a3e6-c1dde3b6001a, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,196 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 96f173da-11f1-4a30-a3e6-c1dde3b6001a, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.FIFO/Queues" }
2025-10-15 15:13:32,212 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 96f173da-11f1-4a30-a3e6-c1dde3b6001a, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-10-15 15:13:32,213 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-10-15 15:13:32,214 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, a00a56d4-41de-4dce-8f52-119d18c1a512, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,215 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, a00a56d4-41de-4dce-8f52-119d18c1a512, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.RemovedQueues/Queues" }
2025-10-15 15:13:32,220 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, a00a56d4-41de-4dce-8f52-119d18c1a512, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-10-15 15:13:32,221 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-10-15 15:13:32,222 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, ca52273f-194e-4367-ac7e-9f7f49201a77, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,223 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, ca52273f-194e-4367-ac7e-9f7f49201a77, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.STD/Queues" }
2025-10-15 15:13:32,235 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, ca52273f-194e-4367-ac7e-9f7f49201a77, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-10-15 15:13:32,236 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-10-15 15:13:32,237 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, a2b1f612-bf3f-4a68-a552-ff90a5089786, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,238 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, a2b1f612-bf3f-4a68-a552-ff90a5089786, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.metadata/Queues" }
2025-10-15 15:13:32,246 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, a2b1f612-bf3f-4a68-a552-ff90a5089786, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-10-15 15:13:32,247 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-10-15 15:13:32,248 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 34878077-770f-4d31-b838-432dad4f51c6, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,258 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 34878077-770f-4d31-b838-432dad4f51c6, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/CLOUD_FOR_FOLDER_CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/Queues" }
2025-10-15 15:13:32,271 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 34878077-770f-4d31-b838-432dad4f51c6, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-10-15 15:13:32,272 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-10-15 15:13:32,272 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 0997e19b-bdf6-4fab-9915-1504187da45a, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,274 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 0997e19b-bdf6-4fab-9915-1504187da45a, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/.sys/Queues" }
2025-10-15 15:13:32,283 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 0997e19b-bdf6-4fab-9915-1504187da45a, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-10-15 15:13:32,284 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-10-15 15:13:32,285 - DEBUG - ydb.connection - _prepare_call: RpcState(DescribePath, 964da49b-9d31-46a7-923e-c7f0355151d0, ghrun-lvxbqnxafi.auto.internal:31205): creating call state
2025-10-15 15:13:32,285 - DEBUG - ydb.connection - _log_request: RpcState(DescribePath, 964da49b-9d31-46a7-923e-c7f0355151d0, ghrun-lvxbqnxafi.auto.internal:31205): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/CLOUD_FOR_folder_acc_822911f6-a9d9-11f0-8051-d00daf17e6f1/Queues" }
2025-10-15 15:13:32,293 - DEBUG - ydb.connection - _log_response: RpcState(DescribePath, 964da49b-9d31-46a7-923e-c7f0355151d0, ghrun-lvxbqnxafi.auto.internal:31205): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-10-15 15:13:32,294 - DEBUG - ydb.tests.library.sqs.test_base - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-10-15 15:13:32,294 - DEBUG - ydb.topic - close: Close topic client
2025-10-15 15:13:32,295 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-15 15:13:32,306 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:31205
2025-10-15 15:13:32,307 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-lvxbqnxafi.auto.internal:31205
2025-10-15 15:13:32,309 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-15 15:13:32,310 - DEBUG - root - teardown_method: Test finished: test_empty_access_key_id
