2025-11-26 21:01:27,821 - INFO - ya.test.122521  - pytest_runtest_setup: ####################################################################################################
2025-11-26 21:01:27,822 - INFO - ya.test.122521  - pytest_runtest_setup: test_create_update_delete_one_queue[tables_format_v1-fifo]
2025-11-26 21:01:27,822 - INFO - ya.test.122521  - pytest_runtest_setup: ####################################################################################################
2025-11-26 21:01:27,822 - INFO - ya.test.122521  - pytest_runtest_setup: Test setup
2025-11-26 21:01:27,966 - DEBUG - ya.test.122521  - get_binary: Binary was found by /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd
2025-11-26 21:01:27,968 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26116
2025-11-26 21:01:27,971 - DEBUG - ydb.tests.library.harness.kikimr_runner.122521  - start: Working directory: /home/runner/.ya/build/build_root/4rkp/005fdd/r3tmp/kikimr_cluster_gjnzhqnd
2025-11-26 21:01:28,005 - DEBUG - ya.test.122521  - get_binary: Binary was found by /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd
2025-11-26 21:01:28,005 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26777
2025-11-26 21:01:28,006 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22133
2025-11-26 21:01:28,006 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12590
2025-11-26 21:01:28,007 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4380
2025-11-26 21:01:28,007 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10900
2025-11-26 21:01:28,008 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/kikimr_configs"
2025-11-26 21:01:28,009 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: Final command: /home/runner/.ya/build/build_root/4rkp/005fdd/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/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/node_1/logfile_5eugiucr.log --grpc-port=26777 --mon-port=22133 --ic-port=26116 --sqs-port=10900
2025-11-26 21:01:28,009 - DEBUG - ydb.tests.library.harness.kikimr_runner.122521  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:26777/1, disk_size 68719476736
2025-11-26 21:01:28,010 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/kikimr_configs"
2025-11-26 21:01:28,010 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: Final command: /home/runner/.ya/build/build_root/4rkp/005fdd/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/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/node_1/logfile_5eugiucr.log --grpc-port=26777 --mon-port=22133 --ic-port=26116 --sqs-port=10900
2025-11-26 21:01:28,011 - DEBUG - ya.test.122521  - execute: Executing '('/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/node_1/logfile_5eugiucr.log', '--grpc-port=26777', '--mon-port=22133', '--ic-port=26116', '--sqs-port=10900')' in '/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/node_1' (no wait)
2025-11-26 21:01:28,034 - DEBUG - ya.test.122521  - execute: Command pid: 123795
2025-11-26 21:01:28,036 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - start: Started node localhost:26777/1
2025-11-26 21:01:28,037 - DEBUG - ya.test.122521  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-26 21:01:28,038 - DEBUG - ya.test.122521  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-26 21:01:28,039 - DEBUG - ya.test.122521  - execute: Executing '['/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-26 21:01:28,041 - DEBUG - ya.test.122521  - execute: Command pid: 123798
2025-11-26 21:01:29,102 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) rc: 0
2025-11-26 21:01:29,103 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) elapsed time (sec): 1.0565545558929443
2025-11-26 21:01:29,103 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) idrss: 0
2025-11-26 21:01:29,103 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) inblock: 0
2025-11-26 21:01:29,103 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) isrss: 0
2025-11-26 21:01:29,104 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) ixrss: 0
2025-11-26 21:01:29,104 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) majflt: 0
2025-11-26 21:01:29,104 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) maxrss: 519116
2025-11-26 21:01:29,104 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) minflt: 45618
2025-11-26 21:01:29,105 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) msgrcv: 0
2025-11-26 21:01:29,105 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) msgsnd: 0
2025-11-26 21:01:29,105 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) nivcsw: 137
2025-11-26 21:01:29,105 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) nsignals: 0
2025-11-26 21:01:29,106 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) nswap: 0
2025-11-26 21:01:29,106 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) nvcsw: 14
2025-11-26 21:01:29,106 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) oublock: 8
2025-11-26 21:01:29,108 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) stime: 0.197405
2025-11-26 21:01:29,108 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) utime: 0.5051479999999999
2025-11-26 21:01:29,108 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) wtime: 1.059
2025-11-26 21:01:29,113 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) output:
Git info:
    Commit: 3a6281fc005e388432251060760d06ea70ac2e39
    Branch: 
    Author: Dmitry Kardymon <kardymon.d@gmail.com>
    Summary: Merge 16b5589081a24ad71272f6e0a48c73699fb876ec into fe49740846bccb57d40855c09ec9f41405fc180e
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-bs3qzf6rey    Host information: 
        Linux ghrun-bs3qzf6rey 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-26 21:01:29,113 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 123798) errors:

2025-11-26 21:01:29,114 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - start: Node 1 version:
Git info:
    Commit: 3a6281fc005e388432251060760d06ea70ac2e39
    Branch: 
    Author: Dmitry Kardymon <kardymon.d@gmail.com>
    Summary: Merge 16b5589081a24ad71272f6e0a48c73699fb876ec into fe49740846bccb57d40855c09ec9f41405fc180e
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-bs3qzf6rey    Host information: 
        Linux ghrun-bs3qzf6rey 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-26 21:01:36,225 - DEBUG - ya.test.122521  - get_binary: Binary was found by /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd
2025-11-26 21:01:36,225 - DEBUG - ydb.tests.library.harness.kikimr_runner.122521  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26777', '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: 26116\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-26 21:01:36,227 - DEBUG - ya.test.122521  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-26 21:01:36,227 - DEBUG - ya.test.122521  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-26 21:01:36,228 - DEBUG - ya.test.122521  - execute: Executing '['/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26777', '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: 26116\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-26 21:01:36,254 - DEBUG - ya.test.122521  - execute: Command pid: 126013
2025-11-26 21:01:37,919 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) rc: 0
2025-11-26 21:01:37,920 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) elapsed time (sec): 1.6640446186065674
2025-11-26 21:01:37,920 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) idrss: 0
2025-11-26 21:01:37,920 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) inblock: 0
2025-11-26 21:01:37,921 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) isrss: 0
2025-11-26 21:01:37,921 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) ixrss: 0
2025-11-26 21:01:37,921 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) majflt: 0
2025-11-26 21:01:37,921 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) maxrss: 579036
2025-11-26 21:01:37,922 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) minflt: 52109
2025-11-26 21:01:37,922 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) msgrcv: 0
2025-11-26 21:01:37,922 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) msgsnd: 0
2025-11-26 21:01:37,923 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) nivcsw: 245
2025-11-26 21:01:37,923 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) nsignals: 0
2025-11-26 21:01:37,923 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) nswap: 0
2025-11-26 21:01:37,923 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) nvcsw: 649
2025-11-26 21:01:37,924 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) oublock: 8
2025-11-26 21:01:37,924 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) stime: 0.259872
2025-11-26 21:01:37,924 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) utime: 0.603641
2025-11-26 21:01:37,924 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) wtime: 1.691
2025-11-26 21:01:37,925 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-26 21:01:37,925 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126013) errors:

2025-11-26 21:01:37,927 - DEBUG - ya.test.122521  - get_binary: Binary was found by /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd
2025-11-26 21:01:37,927 - DEBUG - ydb.tests.library.harness.kikimr_runner.122521  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26777', '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-11-26 21:01:37,928 - DEBUG - ya.test.122521  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-26 21:01:37,929 - DEBUG - ya.test.122521  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-26 21:01:37,930 - DEBUG - ya.test.122521  - execute: Executing '['/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26777', '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-11-26 21:01:37,946 - DEBUG - ya.test.122521  - execute: Command pid: 126682
2025-11-26 21:01:39,597 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) rc: 0
2025-11-26 21:01:39,597 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) elapsed time (sec): 1.6471729278564453
2025-11-26 21:01:39,598 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) idrss: 0
2025-11-26 21:01:39,598 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) inblock: 0
2025-11-26 21:01:39,598 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) isrss: 0
2025-11-26 21:01:39,598 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) ixrss: 0
2025-11-26 21:01:39,599 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) majflt: 0
2025-11-26 21:01:39,599 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) maxrss: 579036
2025-11-26 21:01:39,599 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) minflt: 52136
2025-11-26 21:01:39,599 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) msgrcv: 0
2025-11-26 21:01:39,600 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) msgsnd: 0
2025-11-26 21:01:39,600 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) nivcsw: 232
2025-11-26 21:01:39,600 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) nsignals: 0
2025-11-26 21:01:39,601 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) nswap: 0
2025-11-26 21:01:39,601 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) nvcsw: 553
2025-11-26 21:01:39,601 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) oublock: 8
2025-11-26 21:01:39,601 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) stime: 0.248127
2025-11-26 21:01:39,602 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) utime: 0.60273
2025-11-26 21:01:39,602 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) wtime: 1.663
2025-11-26 21:01:39,602 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-26 21:01:39,602 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 126682) errors:

2025-11-26 21:01:39,603 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-26 21:01:39,756 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __run: Cluster started and initialized
2025-11-26 21:01:40,765 - DEBUG - ya.test.122521  - get_binary: Binary was found by /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd
2025-11-26 21:01:40,766 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11409
2025-11-26 21:01:40,767 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7440
2025-11-26 21:01:40,767 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14338
2025-11-26 21:01:40,768 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21713
2025-11-26 21:01:40,768 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23889
2025-11-26 21:01:40,769 - DEBUG - library.python.filelock.122521  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11306
2025-11-26 21:01:40,770 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/kikimr_configs"
2025-11-26 21:01:40,771 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: Final command: /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26777 --tenant=/Root/TenantSQS --log-file-name=/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/slot_1/logfile_q45gydu7.log --grpc-port=11409 --mon-port=7440 --ic-port=14338 --sqs-port=11306
2025-11-26 21:01:40,771 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/kikimr_configs"
2025-11-26 21:01:40,772 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: Final command: /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26777 --tenant=/Root/TenantSQS --log-file-name=/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/slot_1/logfile_q45gydu7.log --grpc-port=11409 --mon-port=7440 --ic-port=14338 --sqs-port=11306
2025-11-26 21:01:40,773 - DEBUG - ya.test.122521  - execute: Executing '('/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:26777', '--tenant=/Root/TenantSQS', '--log-file-name=/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/slot_1/logfile_q45gydu7.log', '--grpc-port=11409', '--mon-port=7440', '--ic-port=14338', '--sqs-port=11306')' in '/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/slot_1' (no wait)
2025-11-26 21:01:40,786 - DEBUG - ya.test.122521  - execute: Command pid: 127832
2025-11-26 21:01:40,787 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - start: Started node localhost:11409/1
2025-11-26 21:01:40,789 - DEBUG - ya.test.122521  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-26 21:01:40,789 - DEBUG - ya.test.122521  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-26 21:01:40,794 - DEBUG - ya.test.122521  - execute: Executing '['/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-26 21:01:40,806 - DEBUG - ya.test.122521  - execute: Command pid: 127834
2025-11-26 21:01:42,136 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) rc: 0
2025-11-26 21:01:42,137 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) elapsed time (sec): 1.3292598724365234
2025-11-26 21:01:42,137 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) idrss: 0
2025-11-26 21:01:42,138 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) inblock: 0
2025-11-26 21:01:42,138 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) isrss: 0
2025-11-26 21:01:42,138 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) ixrss: 0
2025-11-26 21:01:42,138 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) majflt: 0
2025-11-26 21:01:42,139 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) maxrss: 590696
2025-11-26 21:01:42,139 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) minflt: 45654
2025-11-26 21:01:42,139 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) msgrcv: 0
2025-11-26 21:01:42,140 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) msgsnd: 0
2025-11-26 21:01:42,140 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) nivcsw: 191
2025-11-26 21:01:42,140 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) nsignals: 0
2025-11-26 21:01:42,140 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) nswap: 0
2025-11-26 21:01:42,141 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) nvcsw: 15
2025-11-26 21:01:42,141 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) oublock: 8
2025-11-26 21:01:42,141 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) stime: 0.18492599999999998
2025-11-26 21:01:42,142 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) utime: 0.532361
2025-11-26 21:01:42,148 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) wtime: 1.341
2025-11-26 21:01:42,148 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) output:
Git info:
    Commit: 3a6281fc005e388432251060760d06ea70ac2e39
    Branch: 
    Author: Dmitry Kardymon <kardymon.d@gmail.com>
    Summary: Merge 16b5589081a24ad71272f6e0a48c73699fb876ec into fe49740846bccb57d40855c09ec9f41405fc180e
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-bs3qzf6rey    Host information: 
        Linux ghrun-bs3qzf6rey 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-26 21:01:42,149 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 127834) errors:

2025-11-26 21:01:42,150 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - start: Node 1 version:
Git info:
    Commit: 3a6281fc005e388432251060760d06ea70ac2e39
    Branch: 
    Author: Dmitry Kardymon <kardymon.d@gmail.com>
    Summary: Merge 16b5589081a24ad71272f6e0a48c73699fb876ec into fe49740846bccb57d40855c09ec9f41405fc180e
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-bs3qzf6rey    Host information: 
        Linux ghrun-bs3qzf6rey 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-26 21:01:48,184 - DEBUG - ydb.connection.122521  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-26 21:01:48,215 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.122521  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-26 21:01:48,216 - DEBUG - ydb.connection.122521  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-26 21:01:48,275 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.122521  - context_resolve: Resolving endpoints for database /Root/TenantSQS
2025-11-26 21:01:48,276 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(ListEndpoints, 8f39844e-8b02-425b-89d0-3ac5a8ef418e, localhost:26777): creating call state
2025-11-26 21:01:48,277 - DEBUG - ydb.connection.122521  - _log_request: RpcState(ListEndpoints, 8f39844e-8b02-425b-89d0-3ac5a8ef418e, localhost:26777): request = { database: "/Root/TenantSQS" }
2025-11-26 21:01:48,287 - DEBUG - ydb.connection.122521  - _log_response: RpcState(ListEndpoints, 8f39844e-8b02-425b-89d0-3ac5a8ef418e, localhost:26777): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-bs3qzf6rey.auto.internal" port: 11409 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-11-26 21:01:48,288 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.122521  - _add_debug_details: Resolved endpoints for database /Root/TenantSQS: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-bs3qzf6rey.auto.internal:11409, location , ssl: False>]>
2025-11-26 21:01:48,294 - DEBUG - ydb.connection.122521  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-26 21:01:48,334 - DEBUG - ydb.connection.122521  - close: Closing channel for endpoint localhost:26777
2025-11-26 21:01:48,346 - DEBUG - ydb.table.SessionPool.122521  - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-26 21:01:48,347 - DEBUG - ydb.table.SessionPool.122521  - subscribe: Subscribe waiter <Future at 0x7b98c0a57970 state=pending>
2025-11-26 21:01:48,348 - DEBUG - ydb.table.SessionPool.122521  - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-26 21:01:48,348 - DEBUG - ydb.table.SessionPool.122521  - _create: Created session <ydb.table.Session object at 0x7b98c0a586f0>
2025-11-26 21:01:48,348 - DEBUG - ydb.table.SessionPool.122521  - _prepare: Preparing session <ydb.table.Session object at 0x7b98c0a586f0>
2025-11-26 21:01:48,349 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateSession, fd4b31cf-9bda-48cc-9c93-1538a8ae489d, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:48,350 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateSession, fd4b31cf-9bda-48cc-9c93-1538a8ae489d, ghrun-bs3qzf6rey.auto.internal:11409): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 21:01:51,360 - DEBUG - ydb.connection.122521  - _on_response_callback: RpcState(CreateSession, fd4b31cf-9bda-48cc-9c93-1538a8ae489d, ghrun-bs3qzf6rey.auto.internal:11409): on response callback started
2025-11-26 21:01:51,360 - DEBUG - ydb.connection.122521  - _rpc_error_handler: RpcState(CreateSession, fd4b31cf-9bda-48cc-9c93-1538a8ae489d, ghrun-bs3qzf6rey.auto.internal:11409): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.DEADLINE_EXCEEDED
	details = "Deadline Exceeded"
	debug_error_string = "UNKNOWN:Deadline Exceeded {grpc_status:4, created_time:"2025-11-26T21:01:51.35831877+00:00"}"
>
2025-11-26 21:01:51,361 - ERROR - ydb.table.SessionPool.122521  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Deadline exceeded on request
2025-11-26 21:01:51,362 - DEBUG - ydb.table.SessionPool.122521  - _prepare: Preparing session <ydb.table.Session object at 0x7b98c0a586f0>
2025-11-26 21:01:51,363 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateSession, c84f1203-d424-467d-bdea-82d8ac41ea2d, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:51,364 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateSession, c84f1203-d424-467d-bdea-82d8ac41ea2d, ghrun-bs3qzf6rey.auto.internal:11409): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 21:01:54,369 - DEBUG - ydb.connection.122521  - _on_response_callback: RpcState(CreateSession, c84f1203-d424-467d-bdea-82d8ac41ea2d, ghrun-bs3qzf6rey.auto.internal:11409): on response callback started
2025-11-26 21:01:54,371 - DEBUG - ydb.connection.122521  - _rpc_error_handler: RpcState(CreateSession, c84f1203-d424-467d-bdea-82d8ac41ea2d, ghrun-bs3qzf6rey.auto.internal:11409): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.DEADLINE_EXCEEDED
	details = "Deadline Exceeded"
	debug_error_string = "UNKNOWN:Deadline Exceeded {grpc_status:4, created_time:"2025-11-26T21:01:54.366316044+00:00"}"
>
2025-11-26 21:01:54,372 - ERROR - ydb.table.SessionPool.122521  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Deadline exceeded on request
2025-11-26 21:01:54,375 - DEBUG - ydb.table.SessionPool.122521  - _prepare: Preparing session <ydb.table.Session object at 0x7b98c0a586f0>
2025-11-26 21:01:54,376 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateSession, cde10200-e25b-4574-a2ec-f9900e8b3fe5, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:54,377 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateSession, cde10200-e25b-4574-a2ec-f9900e8b3fe5, ghrun-bs3qzf6rey.auto.internal:11409): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 21:01:54,411 - DEBUG - ydb.connection.122521  - _on_response_callback: RpcState(CreateSession, cde10200-e25b-4574-a2ec-f9900e8b3fe5, ghrun-bs3qzf6rey.auto.internal:11409): on response callback started
2025-11-26 21:01:54,412 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateSession, cde10200-e25b-4574-a2ec-f9900e8b3fe5, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" } } } }
2025-11-26 21:01:54,413 - DEBUG - ydb.table.SessionPool.122521  - put: Put on session <ydb.table.Session object at 0x7b98c0a586f0>
2025-11-26 21:01:54,413 - DEBUG - ydb.table.SessionPool.122521  - put: Replying to waiter with a session <ydb.table.Session object at 0x7b98c0a586f0>
2025-11-26 21:01:54,413 - DEBUG - ydb.connection.122521  - _on_response_callback: RpcState(CreateSession, cde10200-e25b-4574-a2ec-f9900e8b3fe5, ghrun-bs3qzf6rey.auto.internal:11409): on response callback success
2025-11-26 21:01:54,417 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, d1b76ed5-37b9-4977-bee9-6e2013443e6f, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:54,418 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, d1b76ed5-37b9-4977-bee9-6e2013443e6f, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:54,983 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, d1b76ed5-37b9-4977-bee9-6e2013443e6f, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:54,984 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(ExecuteDataQuery, b1a68a7c-b92f-4e25-9ab6-b405c408f88e, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:54,986 - DEBUG - ydb.connection.122521  - _log_request: RpcState(ExecuteDataQuery, b1a68a7c-b92f-4e25-9ab6-b405c408f88e, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:55,232 - DEBUG - ydb.connection.122521  - _log_response: RpcState(ExecuteDataQuery, b1a68a7c-b92f-4e25-9ab6-b405c408f88e, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-26 21:01:55,287 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, ee92fcf8-7aca-4311-9fc4-65792e87368d, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:55,302 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, ee92fcf8-7aca-4311-9fc4-65792e87368d, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:55,503 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, ee92fcf8-7aca-4311-9fc4-65792e87368d, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:55,505 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, 46554e5c-3232-462a-b498-2457bc6140cb, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:55,511 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, 46554e5c-3232-462a-b498-2457bc6140cb, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:55,796 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, 46554e5c-3232-462a-b498-2457bc6140cb, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:55,797 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, cbeaa86f-948b-40b0-a5d0-06537318c515, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:55,799 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, cbeaa86f-948b-40b0-a5d0-06537318c515, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:56,099 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, cbeaa86f-948b-40b0-a5d0-06537318c515, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:56,101 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, ccf330f4-3978-49aa-8997-c8c014639ea9, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:56,107 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, ccf330f4-3978-49aa-8997-c8c014639ea9, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:56,312 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, ccf330f4-3978-49aa-8997-c8c014639ea9, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:56,314 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, f0e5d155-db74-4c4e-bc1c-6507ad7f1e4f, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:56,316 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, f0e5d155-db74-4c4e-bc1c-6507ad7f1e4f, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:56,952 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, f0e5d155-db74-4c4e-bc1c-6507ad7f1e4f, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:56,954 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, 38d75f0f-ebec-4a28-8b11-1214132affc9, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:56,955 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, 38d75f0f-ebec-4a28-8b11-1214132affc9, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:57,609 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, 38d75f0f-ebec-4a28-8b11-1214132affc9, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:57,612 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, a18da1e5-5f63-45fb-bec6-ac96531c0f6e, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:57,622 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, a18da1e5-5f63-45fb-bec6-ac96531c0f6e, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:58,214 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, a18da1e5-5f63-45fb-bec6-ac96531c0f6e, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:58,216 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, a342f1b7-7dd9-4d46-a41c-23357f051d97, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:58,223 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, a342f1b7-7dd9-4d46-a41c-23357f051d97, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:59,043 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, a342f1b7-7dd9-4d46-a41c-23357f051d97, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:59,045 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, 5093c03a-964d-45fa-88df-2711bb85a979, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:59,048 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, 5093c03a-964d-45fa-88df-2711bb85a979, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:59,535 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, 5093c03a-964d-45fa-88df-2711bb85a979, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:59,537 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, 48740a09-3914-485c-ba9c-183315e2cffe, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:59,540 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, 48740a09-3914-485c-ba9c-183315e2cffe, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:01:59,800 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, 48740a09-3914-485c-ba9c-183315e2cffe, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:01:59,802 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, 86950fc0-ecc8-442a-b062-c90fc1fc4518, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:01:59,805 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, 86950fc0-ecc8-442a-b062-c90fc1fc4518, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:02:00,215 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, 86950fc0-ecc8-442a-b062-c90fc1fc4518, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:00,217 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, ff6e0244-aa17-471c-aa28-fc1888b7a316, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:00,221 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, ff6e0244-aa17-471c-aa28-fc1888b7a316, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:02:00,713 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, ff6e0244-aa17-471c-aa28-fc1888b7a316, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:00,720 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, 7134f7f5-9b83-4d3a-ac78-7ae3a3256435, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:00,728 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, 7134f7f5-9b83-4d3a-ac78-7ae3a3256435, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:02:01,221 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, 7134f7f5-9b83-4d3a-ac78-7ae3a3256435, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:01,223 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, ed09b810-cd89-4be6-8d07-dc1cedc95152, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:01,225 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, ed09b810-cd89-4be6-8d07-dc1cedc95152, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:02:01,629 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, ed09b810-cd89-4be6-8d07-dc1cedc95152, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:01,631 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, 073d6d7e-93d9-4ac4-bcc0-357947b8314e, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:01,632 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, 073d6d7e-93d9-4ac4-bcc0-357947b8314e, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:02:02,045 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, 073d6d7e-93d9-4ac4-bcc0-357947b8314e, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:02,048 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, 36a2e86f-4fbb-48ab-bcf6-e48b0816b274, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:02,061 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, 36a2e86f-4fbb-48ab-bcf6-e48b0816b274, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:02:02,431 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, 36a2e86f-4fbb-48ab-bcf6-e48b0816b274, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:02,433 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, d4f299e4-b0a1-4c68-a745-aceb66383e3b, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:02,436 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, d4f299e4-b0a1-4c68-a745-aceb66383e3b, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:02:02,769 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, d4f299e4-b0a1-4c68-a745-aceb66383e3b, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:02,771 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, c172c0de-4c52-4388-927b-04da3718b798, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:02,774 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, c172c0de-4c52-4388-927b-04da3718b798, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" 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-11-26 21:02:03,011 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, c172c0de-4c52-4388-927b-04da3718b798, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:03,013 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateTable, cdd771ca-fbb6-4713-bab5-65cfb63b9d00, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:03,015 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateTable, cdd771ca-fbb6-4713-bab5-65cfb63b9d00, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" path: "/Root/TenantSQS//.CloudEventsYmq" columns { name: "CreatedAt" type { optional_type { item { type_id: UINT64 } } } } columns { name: "Id" type { optional_type { item { type_id: UINT64 } } } } columns { name: "QueueName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "Type" type { optional_type { item { type_id: UTF8 } } } } columns { name: "CloudId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "FolderId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "ResourceId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "UserSID" type { optional_type { item { type_id: UTF8 } } } } columns { name: "MaskedToken" type { optional_type { item { type_id: UTF8 } } } } columns { name: "AuthType" type { optional_type { item { type_id: UTF8 } } } } columns { name: "PeerName" type { optional_type { item { type_id: UTF8 } } } } columns { name: "RequestId" type { optional_type { item { type_id: UTF8 } } } } columns { name: "Labels" type { optional_type { item { type_id: UTF8 } } } } primary_key: "CreatedAt" primary_key: "Id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 21:02:03,144 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateTable, cdd771ca-fbb6-4713-bab5-65cfb63b9d00, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:03,145 - DEBUG - ydb.table.SessionPool.122521  - put: Put on session <ydb.table.Session object at 0x7b98c0a586f0>
2025-11-26 21:02:03,146 - DEBUG - ydb.table.SessionPool.122521  - stop: Requested session pool stop.
2025-11-26 21:02:03,146 - DEBUG - ydb.table.SessionPool.122521  - stop: Session pool is under stop, cancelling all in flight waiters.
2025-11-26 21:02:03,146 - DEBUG - ydb.table.SessionPool.122521  - stop: Destroying sessions in active queue
2025-11-26 21:02:03,147 - DEBUG - ydb.table.SessionPool.122521  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7b98c0a586f0>, reason: session-pool-terminated
2025-11-26 21:02:03,147 - DEBUG - ydb.table.SessionPool.122521  - _destroy: Session <ydb.table.Session object at 0x7b98c0a586f0> is no longer active. Current active count 0.
2025-11-26 21:02:03,149 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(DeleteSession, 1b0de21a-45ed-4e0f-83e2-85295c1aef49, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:03,150 - DEBUG - ydb.connection.122521  - _log_request: RpcState(DeleteSession, 1b0de21a-45ed-4e0f-83e2-85295c1aef49, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=Y2M5YmVhOTQtM2FlNTllMzUtYmFjMTRlNmEtMzYxMjhmYjA=" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-26 21:02:03,152 - DEBUG - ydb.table.SessionPool.122521  - _terminate_event: Terminated session pool.
2025-11-26 21:02:03,156 - DEBUG - ydb.connection.122521  - _on_response_callback: RpcState(DeleteSession, 1b0de21a-45ed-4e0f-83e2-85295c1aef49, ghrun-bs3qzf6rey.auto.internal:11409): on response callback started
2025-11-26 21:02:03,157 - DEBUG - ydb.connection.122521  - _log_response: RpcState(DeleteSession, 1b0de21a-45ed-4e0f-83e2-85295c1aef49, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS } }
2025-11-26 21:02:03,158 - DEBUG - ydb.connection.122521  - _on_response_callback: RpcState(DeleteSession, 1b0de21a-45ed-4e0f-83e2-85295c1aef49, ghrun-bs3qzf6rey.auto.internal:11409): on response callback success
2025-11-26 21:02:03,158 - DEBUG - ydb.table.SessionPool.122521  - _destroy: Sent delete on session <ydb.table.Session object at 0x7b98c0a586f0>
2025-11-26 21:02:03,159 - DEBUG - ydb.table.SessionPool.122521  - stop: Destroyed active sessions
2025-11-26 21:02:03,159 - DEBUG - ydb.topic.122521  - close: Close topic client
2025-11-26 21:02:03,160 - DEBUG - ydb.topic.122521  - close: Topic client was closed
2025-11-26 21:02:03,160 - DEBUG - ydb.connection.122521  - close: Closing channel for endpoint localhost:26777
2025-11-26 21:02:03,160 - DEBUG - ydb.connection.122521  - close: Closing channel for endpoint ghrun-bs3qzf6rey.auto.internal:11409
2025-11-26 21:02:03,162 - INFO - ydb.pool.Discovery.122521  - run: Successfully terminated discovery process
2025-11-26 21:02:03,166 - DEBUG - ya.test.122521  - _get_command_output_file: Command out will be placed to curl.out
2025-11-26 21:02:03,167 - DEBUG - ya.test.122521  - _get_command_output_file: Command err will be placed to curl.err
2025-11-26 21:02:03,168 - DEBUG - ya.test.122521  - execute: Executing '['curl', '-v', 'localhost:10900?Action=CreateUser&UserName=metauser', '-H', 'authorization: aaa credential=abacaba/20220830/ec2/aws4_request']' in 'None' (waiting)
2025-11-26 21:02:03,173 - DEBUG - ya.test.122521  - execute: Command pid: 133211
2025-11-26 21:02:03,229 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) rc: 0
2025-11-26 21:02:03,230 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) elapsed time (sec): 0.054064273834228516
2025-11-26 21:02:03,230 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) idrss: 0
2025-11-26 21:02:03,230 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) inblock: 0
2025-11-26 21:02:03,231 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) isrss: 0
2025-11-26 21:02:03,231 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) ixrss: 0
2025-11-26 21:02:03,231 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) majflt: 0
2025-11-26 21:02:03,231 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) maxrss: 594292
2025-11-26 21:02:03,232 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) minflt: 590
2025-11-26 21:02:03,232 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) msgrcv: 0
2025-11-26 21:02:03,232 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) msgsnd: 0
2025-11-26 21:02:03,232 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) nivcsw: 3
2025-11-26 21:02:03,232 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) nsignals: 0
2025-11-26 21:02:03,233 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) nswap: 0
2025-11-26 21:02:03,233 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) nvcsw: 2
2025-11-26 21:02:03,233 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) oublock: 16
2025-11-26 21:02:03,233 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) stime: 0.010402999999999999
2025-11-26 21:02:03,234 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) utime: 0.0
2025-11-26 21:02:03,234 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) wtime: 0.06
2025-11-26 21:02:03,234 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) output:
<?xml version="1.0" encoding="UTF-8"?>
<ErrorResponse><Error><Message>This action is disabled in YMQ. Request id to report: d9e0e4cb-3a790381-c9044e5e-60acd064.</Message><Code>InvalidAction</Code></Error><RequestId></RequestId></ErrorResponse>

2025-11-26 21:02:03,235 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133211) errors:
*   Trying 127.0.0.1:10900...
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0* Connected to localhost (127.0.0.1) port 10900 (#0)
> GET /?Action=CreateUser&UserName=metauser HTTP/1.1
> Host: localhost:10900
> User-Agent: curl/7.81.0
> Accept: */*
> authorization: aaa credential=abacaba/20220830/ec2/aws4_request
> 
* Mark bundle as not supporting multiuse
< HTTP/1.1 400 Bad request
< Content-Type: application/xml
< Content-Length: 244
< Connection: Keep-Alive
< 
{ [244 bytes data]

100   244  100   244    0     0   5322      0 --:--:-- --:--:-- --:--:--  5422
* Connection #0 to host localhost left intact

2025-11-26 21:02:03,235 - DEBUG - ya.test.122521  - verify_sanitize_errors: 'curl' doesn't belong to '/home/runner/.ya/build/build_root/4rkp/005fdd' - no check for sanitize errors
2025-11-26 21:02:03,236 - DEBUG - root.122521  - setup_method: Test started: test_create_update_delete_one_queue
2025-11-26 21:02:03,237 - DEBUG - root.122521  - setup_method: Kikimr working dir: /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo
2025-11-26 21:02:03,237 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/kikimr_configs"
2025-11-26 21:02:03,237 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: Final command: /home/runner/.ya/build/build_root/4rkp/005fdd/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/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/node_1/logfile_5eugiucr.log --grpc-port=26777 --mon-port=22133 --ic-port=26116 --sqs-port=10900
2025-11-26 21:02:03,238 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - start: Started node localhost:26777/1
2025-11-26 21:02:03,239 - DEBUG - ya.test.122521  - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-11-26 21:02:03,240 - DEBUG - ya.test.122521  - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-11-26 21:02:03,240 - DEBUG - ya.test.122521  - execute: Executing '['/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-26 21:02:03,242 - DEBUG - ya.test.122521  - execute: Command pid: 133224
2025-11-26 21:02:04,042 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) rc: 0
2025-11-26 21:02:04,042 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) elapsed time (sec): 0.7985718250274658
2025-11-26 21:02:04,043 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) idrss: 0
2025-11-26 21:02:04,043 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) inblock: 0
2025-11-26 21:02:04,043 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) isrss: 0
2025-11-26 21:02:04,043 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) ixrss: 0
2025-11-26 21:02:04,044 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) majflt: 0
2025-11-26 21:02:04,044 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) maxrss: 594292
2025-11-26 21:02:04,044 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) minflt: 45588
2025-11-26 21:02:04,045 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) msgrcv: 0
2025-11-26 21:02:04,045 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) msgsnd: 0
2025-11-26 21:02:04,045 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) nivcsw: 192
2025-11-26 21:02:04,046 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) nsignals: 0
2025-11-26 21:02:04,046 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) nswap: 0
2025-11-26 21:02:04,046 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) nvcsw: 14
2025-11-26 21:02:04,047 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) oublock: 8
2025-11-26 21:02:04,047 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) stime: 0.198401
2025-11-26 21:02:04,047 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) utime: 0.530548
2025-11-26 21:02:04,047 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) wtime: 0.8
2025-11-26 21:02:04,048 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) output:
Git info:
    Commit: 3a6281fc005e388432251060760d06ea70ac2e39
    Branch: 
    Author: Dmitry Kardymon <kardymon.d@gmail.com>
    Summary: Merge 16b5589081a24ad71272f6e0a48c73699fb876ec into fe49740846bccb57d40855c09ec9f41405fc180e
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-bs3qzf6rey    Host information: 
        Linux ghrun-bs3qzf6rey 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-26 21:02:04,048 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133224) errors:

2025-11-26 21:02:04,049 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - start: Node 1 version:
Git info:
    Commit: 3a6281fc005e388432251060760d06ea70ac2e39
    Branch: 
    Author: Dmitry Kardymon <kardymon.d@gmail.com>
    Summary: Merge 16b5589081a24ad71272f6e0a48c73699fb876ec into fe49740846bccb57d40855c09ec9f41405fc180e
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-bs3qzf6rey    Host information: 
        Linux ghrun-bs3qzf6rey 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-26 21:02:04,049 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/kikimr_configs"
2025-11-26 21:02:04,050 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - __make_run_command: Final command: /home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:26777 --tenant=/Root/TenantSQS --log-file-name=/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/tests/functional/sqs/cloud/test-results/py3test/testing_out_stuff/chunk13/testing_out_stuff/test_yandex_audit.py.TestCloudEvents.test_create_update_delete_one_queue.tables_format_v1-fifo/cluster/slot_1/logfile_q45gydu7.log --grpc-port=11409 --mon-port=7440 --ic-port=14338 --sqs-port=11306
2025-11-26 21:02:04,050 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - start: Started node localhost:11409/1
2025-11-26 21:02:04,052 - DEBUG - ya.test.122521  - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-11-26 21:02:04,053 - DEBUG - ya.test.122521  - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-11-26 21:02:04,053 - DEBUG - ya.test.122521  - execute: Executing '['/home/runner/.ya/build/build_root/4rkp/005fdd/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-26 21:02:04,058 - DEBUG - ya.test.122521  - execute: Command pid: 133312
2025-11-26 21:02:04,942 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) rc: 0
2025-11-26 21:02:04,943 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) elapsed time (sec): 0.8797252178192139
2025-11-26 21:02:04,943 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) idrss: 0
2025-11-26 21:02:04,944 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) inblock: 0
2025-11-26 21:02:04,944 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) isrss: 0
2025-11-26 21:02:04,944 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) ixrss: 0
2025-11-26 21:02:04,945 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) majflt: 0
2025-11-26 21:02:04,945 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) maxrss: 594292
2025-11-26 21:02:04,945 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) minflt: 45584
2025-11-26 21:02:04,946 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) msgrcv: 0
2025-11-26 21:02:04,950 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) msgsnd: 0
2025-11-26 21:02:04,951 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) nivcsw: 275
2025-11-26 21:02:04,951 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) nsignals: 0
2025-11-26 21:02:04,952 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) nswap: 0
2025-11-26 21:02:04,952 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) nvcsw: 14
2025-11-26 21:02:04,952 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) oublock: 8
2025-11-26 21:02:04,953 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) stime: 0.22844099999999998
2025-11-26 21:02:04,953 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) utime: 0.501787
2025-11-26 21:02:04,953 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) wtime: 0.884
2025-11-26 21:02:04,954 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) output:
Git info:
    Commit: 3a6281fc005e388432251060760d06ea70ac2e39
    Branch: 
    Author: Dmitry Kardymon <kardymon.d@gmail.com>
    Summary: Merge 16b5589081a24ad71272f6e0a48c73699fb876ec into fe49740846bccb57d40855c09ec9f41405fc180e
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-bs3qzf6rey    Host information: 
        Linux ghrun-bs3qzf6rey 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-26 21:02:04,959 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133312) errors:

2025-11-26 21:02:04,960 - INFO - ydb.tests.library.harness.kikimr_runner.122521  - start: Node 1 version:
Git info:
    Commit: 3a6281fc005e388432251060760d06ea70ac2e39
    Branch: 
    Author: Dmitry Kardymon <kardymon.d@gmail.com>
    Summary: Merge 16b5589081a24ad71272f6e0a48c73699fb876ec into fe49740846bccb57d40855c09ec9f41405fc180e
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-bs3qzf6rey    Host information: 
        Linux ghrun-bs3qzf6rey 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-26 21:02:04,976 - DEBUG - urllib3.connectionpool.122521  - _new_conn: Starting new HTTP connection (1): localhost:7440
2025-11-26 21:02:05,010 - DEBUG - urllib3.connectionpool.122521  - _make_request: http://localhost:7440 "GET /tablets/app?TabletID=72057594037968897&node=1&page=SetDown&down=0 HTTP/1.1" 200 33
2025-11-26 21:02:05,012 - DEBUG - root.122521  - _enable_tablets_on_node: Toggle up reply: {"NodeId":1,"Down":false}
2025-11-26 21:02:05,039 - DEBUG - root.122521  - _setup_user: Running curl -v localhost:10900?Action=CreateUser&UserName=U_create_update_delete_one_queue_2984e5dd-cb0b-11f0-8e82-d00d636e60d4 -H authorization: aaa credential=abacaba/20220830/ec2/aws4_request
2025-11-26 21:02:05,041 - DEBUG - ya.test.122521  - _get_command_output_file: Command out will be placed to curl.out.0
2025-11-26 21:02:05,042 - DEBUG - ya.test.122521  - _get_command_output_file: Command err will be placed to curl.err.0
2025-11-26 21:02:05,042 - DEBUG - ya.test.122521  - execute: Executing '['curl', '-v', 'localhost:10900?Action=CreateUser&UserName=U_create_update_delete_one_queue_2984e5dd-cb0b-11f0-8e82-d00d636e60d4', '-H', 'authorization: aaa credential=abacaba/20220830/ec2/aws4_request']' in 'None' (waiting)
2025-11-26 21:02:05,078 - DEBUG - ya.test.122521  - execute: Command pid: 133438
2025-11-26 21:02:05,134 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) rc: 0
2025-11-26 21:02:05,135 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) elapsed time (sec): 0.04652070999145508
2025-11-26 21:02:05,135 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) idrss: 0
2025-11-26 21:02:05,136 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) inblock: 0
2025-11-26 21:02:05,136 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) isrss: 0
2025-11-26 21:02:05,136 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) ixrss: 0
2025-11-26 21:02:05,137 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) majflt: 0
2025-11-26 21:02:05,137 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) maxrss: 594856
2025-11-26 21:02:05,137 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) minflt: 590
2025-11-26 21:02:05,138 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) msgrcv: 0
2025-11-26 21:02:05,140 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) msgsnd: 0
2025-11-26 21:02:05,141 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) nivcsw: 6
2025-11-26 21:02:05,141 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) nsignals: 0
2025-11-26 21:02:05,142 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) nswap: 0
2025-11-26 21:02:05,142 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) nvcsw: 2
2025-11-26 21:02:05,142 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) oublock: 16
2025-11-26 21:02:05,143 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) stime: 0.005879
2025-11-26 21:02:05,143 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) utime: 0.005879
2025-11-26 21:02:05,143 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) wtime: 0.083
2025-11-26 21:02:05,144 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) output:
<?xml version="1.0" encoding="UTF-8"?>
<ErrorResponse><Error><Message>This action is disabled in YMQ. Request id to report: f4e89362-7014c9b8-e10bd144-4f6516e6.</Message><Code>InvalidAction</Code></Error><RequestId></RequestId></ErrorResponse>

2025-11-26 21:02:05,144 - DEBUG - ya.test.122521  - _save_outputs: Command (pid 133438) errors:
*   Trying 127.0.0.1:10900...
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0* Connected to localhost (127.0.0.1) port 10900 (#0)
> GET /?Action=CreateUser&UserName=U_create_update_delete_one_queue_2984e5dd-cb0b-11f0-8e82-d00d636e60d4 HTTP/1.1
> Host: localhost:10900
> User-Agent: curl/7.81.0
> Accept: */*
> authorization: aaa credential=abacaba/20220830/ec2/aws4_request
> 
* Mark bundle as not supporting multiuse
< HTTP/1.1 400 Bad request
< Content-Type: application/xml
< Content-Length: 244
< Connection: Keep-Alive
< 
{ [244 bytes data]

100   244  100   244    0     0  22051      0 --:--:-- --:--:-- --:--:-- 24400
* Connection #0 to host localhost left intact

2025-11-26 21:02:05,145 - DEBUG - ya.test.122521  - verify_sanitize_errors: 'curl' doesn't belong to '/home/runner/.ya/build/build_root/4rkp/005fdd' - no check for sanitize errors
2025-11-26 21:02:05,165 - INFO - ydb.tests.library.sqs.test_base.122521  - wait_can_list_users: Listing SQS users
2025-11-26 21:02:05,166 - DEBUG - ydb.tests.library.sqs.requests_client.122521  - execute_request: Execute request POST http://localhost:11306 from user U_create_update_delete_one_queue_2984e5dd-cb0b-11f0-8e82-d00d636e60d4 with params: Action=ListQueues
2025-11-26 21:02:05,170 - DEBUG - urllib3.connectionpool.122521  - _new_conn: Starting new HTTP connection (1): localhost:11306
2025-11-26 21:02:05,411 - DEBUG - urllib3.connectionpool.122521  - _make_request: http://localhost:11306 "POST / HTTP/1.1" 400 279
2025-11-26 21:02:05,469 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:05,486 - WARNING - ydb.tests.library.sqs.requests_client.122521  - _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: cab95ea9-dead6744-17a0cd5f-ac6fcdfc.</Message><Code>IncompleteSignature</Code></Error><RequestId></RequestId></ErrorResponse>
'
2025-11-26 21:02:05,488 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:05,489 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:05,498 - INFO - ydb.tests.library.sqs.test_base.122521  - wait_can_list_users: Success. Api is up.
2025-11-26 21:02:05,502 - DEBUG - ydb.connection.122521  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-26 21:02:05,530 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.122521  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-26 21:02:05,531 - DEBUG - ydb.connection.122521  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-26 21:02:05,562 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.122521  - context_resolve: Resolving endpoints for database /Root/TenantSQS
2025-11-26 21:02:05,563 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(ListEndpoints, cd09c8e6-e481-489d-bd8e-f4659f775741, localhost:11409): creating call state
2025-11-26 21:02:05,563 - DEBUG - ydb.connection.122521  - _log_request: RpcState(ListEndpoints, cd09c8e6-e481-489d-bd8e-f4659f775741, localhost:11409): request = { database: "/Root/TenantSQS" }
2025-11-26 21:02:05,576 - DEBUG - ydb.connection.122521  - _log_response: RpcState(ListEndpoints, cd09c8e6-e481-489d-bd8e-f4659f775741, localhost:11409): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-bs3qzf6rey.auto.internal" port: 11409 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" node_id: 50000 } } } } }
2025-11-26 21:02:05,577 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.122521  - _add_debug_details: Resolved endpoints for database /Root/TenantSQS: DiscoveryResult <self_location: , endpoints [<Endpoint ghrun-bs3qzf6rey.auto.internal:11409, location , ssl: False>]>
2025-11-26 21:02:05,578 - DEBUG - ydb.connection.122521  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-26 21:02:05,606 - DEBUG - ydb.connection.122521  - close: Closing channel for endpoint localhost:11409
2025-11-26 21:02:05,607 - INFO - root.122521  - _before_test_start: run test with cloud_id=CLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4 folder_id=folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4
2025-11-26 21:02:05,613 - INFO - ya.test.122521  - pytest_runtest_call: Test call (class_name: test_yandex_audit.py::TestCloudEvents, test_name: test_create_update_delete_one_queue[tables_format_v1-fifo])
2025-11-26 21:02:05,614 - DEBUG - root.122521  - _execute_yql_query: Executing database query: "UPSERT INTO `/Root/TenantSQS/.Settings` (Account, Name, Value)                 VALUES ("CLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4", "CreateQueuesWithTabletFormat", "1")"
2025-11-26 21:02:05,617 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(CreateSession, ff8e4a3b-5353-4146-9516-a0444cec2629, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:05,623 - DEBUG - ydb.connection.122521  - _log_request: RpcState(CreateSession, ff8e4a3b-5353-4146-9516-a0444cec2629, ghrun-bs3qzf6rey.auto.internal:11409): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 21:02:05,632 - DEBUG - ydb.connection.122521  - _log_response: RpcState(CreateSession, ff8e4a3b-5353-4146-9516-a0444cec2629, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YTk5NDI5Yy1jNjlkNWM0My1kYTBjYWFjLWE5MWFiZjli" } } } }
2025-11-26 21:02:05,633 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(ExecuteDataQuery, f24667c2-ebfe-465b-9166-ee72e84f28ed, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:05,634 - DEBUG - ydb.connection.122521  - _log_request: RpcState(ExecuteDataQuery, f24667c2-ebfe-465b-9166-ee72e84f28ed, ghrun-bs3qzf6rey.auto.internal:11409): request = { session_id: "ydb://session/3?node_id=50000&id=YTk5NDI5Yy1jNjlkNWM0My1kYTBjYWFjLWE5MWFiZjli" 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_29df72cf-cb0b-11f0-a6e6-d00d636e60d4\", \"CreateQueuesWithTabletFormat\", \"1\")" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-26 21:02:05,930 - DEBUG - ydb.connection.122521  - _log_response: RpcState(ExecuteDataQuery, f24667c2-ebfe-465b-9166-ee72e84f28ed, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-26 21:02:05,932 - DEBUG - ydb.tests.library.sqs.requests_client.122521  - execute_request: Execute request POST http://localhost:11306 from user CLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4 with params: QueueName=Q_create_update_delete_one_queue_2988d392-cb0b-11f0-954b-d00d636e60d4.fifo&Attribute.1.Name=FifoQueue&Attribute.1.Value=true&Action=CreateQueue&folderId=folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4
2025-11-26 21:02:05,948 - DEBUG - urllib3.connectionpool.122521  - _new_conn: Starting new HTTP connection (1): localhost:11306
2025-11-26 21:02:06,466 - DEBUG - urllib3.connectionpool.122521  - _make_request: http://localhost:11306 "POST / HTTP/1.1" 200 434
2025-11-26 21:02:06,496 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:06,496 - DEBUG - root.122521  - _process_response: Parsing response: <?xml version="1.0" encoding="UTF-8"?>
<CreateQueueResponse><CreateQueueResult><QueueUrl>http://ghrun-bs3qzf6rey.auto.internal:11306/CLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4/0000000000000001004l/Q_create_update_delete_one_queue_2988d392-cb0b-11f0-954b-d00d636e60d4.fifo</QueueUrl></CreateQueueResult><ResponseMetadata><RequestId>83d0516d-e0e91ba4-21bb72c6-d77d057</RequestId></ResponseMetadata></CreateQueueResponse>

2025-11-26 21:02:06,497 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:07,503 - DEBUG - ydb.tests.library.sqs.requests_client.122521  - execute_request: Execute request POST http://localhost:11306 from user CLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4 with params: QueueUrl=http%3A%2F%2Fghrun-bs3qzf6rey.auto.internal%3A11306%2FCLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4%2F0000000000000001004l%2FQ_create_update_delete_one_queue_2988d392-cb0b-11f0-954b-d00d636e60d4.fifo&Tag.1.Key=tag_key_1&Tag.1.Value=tag_value_1&Tag.2.Key=tag_key_2&Tag.2.Value=tag_value_2&Action=TagQueue&folderId=folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4
2025-11-26 21:02:07,743 - DEBUG - urllib3.connectionpool.122521  - _make_request: http://localhost:11306 "POST / HTTP/1.1" 200 172
2025-11-26 21:02:07,803 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:07,804 - DEBUG - root.122521  - _process_response: Parsing response: <?xml version="1.0" encoding="UTF-8"?>
<TagQueueResponse><ResponseMetadata><RequestId>adefe0d1-303347d2-9e4ba38a-11363859</RequestId></ResponseMetadata></TagQueueResponse>

2025-11-26 21:02:07,805 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:08,806 - DEBUG - ydb.tests.library.sqs.requests_client.122521  - execute_request: Execute request POST http://localhost:11306 from user CLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4 with params: QueueUrl=http%3A%2F%2Fghrun-bs3qzf6rey.auto.internal%3A11306%2FCLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4%2F0000000000000001004l%2FQ_create_update_delete_one_queue_2988d392-cb0b-11f0-954b-d00d636e60d4.fifo&TagKey.1=tag_key_1&TagKey.2=tag_key_2&Action=UntagQueue&folderId=folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4
2025-11-26 21:02:08,879 - DEBUG - urllib3.connectionpool.122521  - _make_request: http://localhost:11306 "POST / HTTP/1.1" 200 175
2025-11-26 21:02:08,905 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:08,908 - DEBUG - root.122521  - _process_response: Parsing response: <?xml version="1.0" encoding="UTF-8"?>
<UntagQueueResponse><ResponseMetadata><RequestId>a88970b-7d4dacb2-ca5e68b2-4dcd02d4</RequestId></ResponseMetadata></UntagQueueResponse>

2025-11-26 21:02:08,909 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:09,910 - DEBUG - ydb.tests.library.sqs.requests_client.122521  - execute_request: Execute request POST http://localhost:11306 from user CLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4 with params: QueueUrl=http%3A%2F%2Fghrun-bs3qzf6rey.auto.internal%3A11306%2FCLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4%2F0000000000000001004l%2FQ_create_update_delete_one_queue_2988d392-cb0b-11f0-954b-d00d636e60d4.fifo&Action=DeleteQueue&folderId=folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4
2025-11-26 21:02:10,712 - DEBUG - urllib3.connectionpool.122521  - _make_request: http://localhost:11306 "POST / HTTP/1.1" 200 178
2025-11-26 21:02:10,740 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:10,741 - DEBUG - root.122521  - _process_response: Parsing response: <?xml version="1.0" encoding="UTF-8"?>
<DeleteQueueResponse><ResponseMetadata><RequestId>ba2d08f5-9cc9941e-8278d02b-3d719491</RequestId></ResponseMetadata></DeleteQueueResponse>

2025-11-26 21:02:10,742 - DEBUG - charset_normalizer.122521  - from_bytes: Encoding detection: utf_8 is most likely the one.
2025-11-26 21:02:20,854 - INFO - ya.test.122521  - pytest_runtest_teardown: Test teardown
2025-11-26 21:02:20,855 - DEBUG - ydb.connection.122521  - _prepare_call: RpcState(DescribePath, 8986081c-4195-4e49-bc37-7f7d69cce3ec, ghrun-bs3qzf6rey.auto.internal:11409): creating call state
2025-11-26 21:02:20,856 - DEBUG - ydb.connection.122521  - _log_request: RpcState(DescribePath, 8986081c-4195-4e49-bc37-7f7d69cce3ec, ghrun-bs3qzf6rey.auto.internal:11409): request = { operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } path: "/Root/TenantSQS/CLOUD_FOR_folder_acc_29df72cf-cb0b-11f0-a6e6-d00d636e60d4/Queues" }
2025-11-26 21:02:20,868 - DEBUG - ydb.connection.122521  - _log_response: RpcState(DescribePath, 8986081c-4195-4e49-bc37-7f7d69cce3ec, ghrun-bs3qzf6rey.auto.internal:11409): response = { operation { ready: true status: SCHEME_ERROR issues { message: "Path not found" severity: 1 } } }
2025-11-26 21:02:20,869 - DEBUG - ydb.tests.library.sqs.test_base.122521  - check_no_queues_table: No queue table: message: "Path not found" severity: 1 (server_code: 400070)
2025-11-26 21:02:20,870 - DEBUG - ydb.topic.122521  - close: Close topic client
2025-11-26 21:02:20,870 - DEBUG - ydb.topic.122521  - close: Topic client was closed
2025-11-26 21:02:20,870 - DEBUG - ydb.connection.122521  - close: Closing channel for endpoint localhost:11409
2025-11-26 21:02:20,871 - DEBUG - ydb.connection.122521  - close: Closing channel for endpoint ghrun-bs3qzf6rey.auto.internal:11409
2025-11-26 21:02:20,872 - INFO - ydb.pool.Discovery.122521  - run: Successfully terminated discovery process
2025-11-26 21:02:20,875 - DEBUG - root.122521  - teardown_method: Test finished: test_create_update_delete_one_queue
