2025-12-05 16:17:52,827 - INFO - ya.test.22566  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:17:52,830 - INFO - ya.test.22566  - pytest_runtest_setup: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2]
2025-12-05 16:17:52,831 - INFO - ya.test.22566  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:17:52,832 - INFO - ya.test.22566  - pytest_runtest_setup: Test setup
2025-12-05 16:17:52,846 - DEBUG - ya.test.22566  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-05 16:17:52,849 - DEBUG - ya.test.22566  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-05 16:17:52,851 - DEBUG - ya.test.22566  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 16:17:52,869 - DEBUG - ya.test.22566  - execute: Command pid: 24015
2025-12-05 16:17:56,887 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) rc: 0
2025-12-05 16:17:56,889 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) elapsed time (sec): 4.007923603057861
2025-12-05 16:17:56,898 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) idrss: 0
2025-12-05 16:17:56,900 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) inblock: 0
2025-12-05 16:17:56,905 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) isrss: 0
2025-12-05 16:17:56,908 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) ixrss: 0
2025-12-05 16:17:56,912 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) majflt: 0
2025-12-05 16:17:56,914 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) maxrss: 486060
2025-12-05 16:17:56,917 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) minflt: 45649
2025-12-05 16:17:56,918 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) msgrcv: 0
2025-12-05 16:17:56,925 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) msgsnd: 0
2025-12-05 16:17:56,926 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) nivcsw: 359
2025-12-05 16:17:56,926 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) nsignals: 0
2025-12-05 16:17:56,926 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) nswap: 0
2025-12-05 16:17:56,926 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) nvcsw: 14
2025-12-05 16:17:56,926 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) oublock: 8
2025-12-05 16:17:56,938 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) stime: 2.08442
2025-12-05 16:17:56,940 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) utime: 0.465829
2025-12-05 16:17:56,944 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) wtime: 4.024
2025-12-05 16:17:56,946 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) output:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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-12-05 16:17:56,961 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24015) errors:

2025-12-05 16:17:56,966 - INFO - __tests__.base.22566  - _setup_ydb: Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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-12-05 16:17:58,291 - DEBUG - ya.test.22566  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd
2025-12-05 16:17:58,314 - DEBUG - library.python.filelock.22566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18645
2025-12-05 16:17:58,340 - DEBUG - ydb.tests.library.harness.kikimr_runner.22566  - start: Working directory: /home/runner/.ya/build/build_root/nihu/0016ed/r3tmp/kikimr_cluster_yj2wi3sq
2025-12-05 16:17:58,731 - DEBUG - ya.test.22566  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd
2025-12-05 16:17:58,734 - DEBUG - library.python.filelock.22566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8243
2025-12-05 16:17:58,741 - DEBUG - library.python.filelock.22566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4860
2025-12-05 16:17:58,745 - DEBUG - library.python.filelock.22566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17695
2025-12-05 16:17:58,747 - DEBUG - library.python.filelock.22566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26333
2025-12-05 16:17:58,753 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/nihu/0016ed/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-12-05 16:17:58,756 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - __make_run_command: Final command: /home/runner/.ya/build/build_root/nihu/0016ed/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/nihu/0016ed/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_f5c81p6s.log --grpc-port=8243 --mon-port=4860 --ic-port=18645
2025-12-05 16:17:58,761 - DEBUG - ydb.tests.library.harness.kikimr_runner.22566  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:8243/1, disk_size 68719476736
2025-12-05 16:17:58,774 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/nihu/0016ed/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-12-05 16:17:58,776 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - __make_run_command: Final command: /home/runner/.ya/build/build_root/nihu/0016ed/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/nihu/0016ed/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_f5c81p6s.log --grpc-port=8243 --mon-port=4860 --ic-port=18645
2025-12-05 16:17:58,789 - DEBUG - ya.test.22566  - execute: Executing '('/home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/nihu/0016ed/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/nihu/0016ed/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_f5c81p6s.log', '--grpc-port=8243', '--mon-port=4860', '--ic-port=18645')' in '/home/runner/.ya/build/build_root/nihu/0016ed/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1' (no wait)
2025-12-05 16:17:58,834 - DEBUG - ya.test.22566  - execute: Command pid: 24247
2025-12-05 16:17:58,839 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - start: Started node localhost:8243/1
2025-12-05 16:17:58,886 - DEBUG - ya.test.22566  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-05 16:17:58,911 - DEBUG - ya.test.22566  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-05 16:17:58,921 - DEBUG - ya.test.22566  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 16:17:58,966 - DEBUG - ya.test.22566  - execute: Command pid: 24248
2025-12-05 16:18:03,727 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) rc: 0
2025-12-05 16:18:03,731 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) elapsed time (sec): 4.757007360458374
2025-12-05 16:18:03,739 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) idrss: 0
2025-12-05 16:18:03,741 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) inblock: 0
2025-12-05 16:18:03,751 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) isrss: 0
2025-12-05 16:18:03,754 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) ixrss: 0
2025-12-05 16:18:03,756 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) majflt: 0
2025-12-05 16:18:03,760 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) maxrss: 489792
2025-12-05 16:18:03,762 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) minflt: 45772
2025-12-05 16:18:03,765 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) msgrcv: 0
2025-12-05 16:18:03,767 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) msgsnd: 0
2025-12-05 16:18:03,771 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) nivcsw: 308
2025-12-05 16:18:03,773 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) nsignals: 0
2025-12-05 16:18:03,780 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) nswap: 0
2025-12-05 16:18:03,790 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) nvcsw: 14
2025-12-05 16:18:03,794 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) oublock: 8
2025-12-05 16:18:03,796 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) stime: 3.2618489999999998
2025-12-05 16:18:03,800 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) utime: 0.463194
2025-12-05 16:18:03,807 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) wtime: 4.79
2025-12-05 16:18:03,815 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) output:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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-12-05 16:18:03,834 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 24248) errors:

2025-12-05 16:18:03,834 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - start: Node 1 version:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-05 16:18:27,161 - DEBUG - ya.test.22566  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd
2025-12-05 16:18:27,162 - DEBUG - ydb.tests.library.harness.kikimr_runner.22566  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8243', '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: 18645\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-05 16:18:27,163 - DEBUG - ya.test.22566  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-05 16:18:27,164 - DEBUG - ya.test.22566  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-05 16:18:27,164 - DEBUG - ya.test.22566  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8243', '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: 18645\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-05 16:18:27,169 - DEBUG - ya.test.22566  - execute: Command pid: 28955
2025-12-05 16:18:28,485 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) rc: 0
2025-12-05 16:18:28,486 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) elapsed time (sec): 1.2951610088348389
2025-12-05 16:18:28,486 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) idrss: 0
2025-12-05 16:18:28,487 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) inblock: 0
2025-12-05 16:18:28,487 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) isrss: 0
2025-12-05 16:18:28,487 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) ixrss: 0
2025-12-05 16:18:28,488 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) majflt: 0
2025-12-05 16:18:28,488 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) maxrss: 609712
2025-12-05 16:18:28,488 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) minflt: 52189
2025-12-05 16:18:28,488 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) msgrcv: 0
2025-12-05 16:18:28,489 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) msgsnd: 0
2025-12-05 16:18:28,489 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) nivcsw: 289
2025-12-05 16:18:28,489 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) nsignals: 0
2025-12-05 16:18:28,489 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) nswap: 0
2025-12-05 16:18:28,490 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) nvcsw: 650
2025-12-05 16:18:28,490 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) oublock: 8
2025-12-05 16:18:28,490 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) stime: 0.31348499999999996
2025-12-05 16:18:28,491 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) utime: 0.58126
2025-12-05 16:18:28,491 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) wtime: 1.301
2025-12-05 16:18:28,491 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-05 16:18:28,492 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 28955) errors:

2025-12-05 16:18:28,499 - DEBUG - ya.test.22566  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd
2025-12-05 16:18:28,541 - DEBUG - ydb.tests.library.harness.kikimr_runner.22566  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8243', '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-12-05 16:18:28,542 - DEBUG - ya.test.22566  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-05 16:18:28,543 - DEBUG - ya.test.22566  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-05 16:18:28,544 - DEBUG - ya.test.22566  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/0016ed/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8243', '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-12-05 16:18:28,557 - DEBUG - ya.test.22566  - execute: Command pid: 29662
2025-12-05 16:18:29,963 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) rc: 0
2025-12-05 16:18:29,964 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) elapsed time (sec): 1.4049248695373535
2025-12-05 16:18:29,964 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) idrss: 0
2025-12-05 16:18:29,964 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) inblock: 0
2025-12-05 16:18:29,965 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) isrss: 0
2025-12-05 16:18:29,965 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) ixrss: 0
2025-12-05 16:18:29,965 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) majflt: 0
2025-12-05 16:18:29,966 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) maxrss: 609936
2025-12-05 16:18:29,966 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) minflt: 52177
2025-12-05 16:18:29,966 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) msgrcv: 0
2025-12-05 16:18:29,967 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) msgsnd: 0
2025-12-05 16:18:29,967 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) nivcsw: 237
2025-12-05 16:18:29,967 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) nsignals: 0
2025-12-05 16:18:29,967 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) nswap: 0
2025-12-05 16:18:29,968 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) nvcsw: 443
2025-12-05 16:18:29,968 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) oublock: 8
2025-12-05 16:18:29,968 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) stime: 0.38698899999999997
2025-12-05 16:18:29,969 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) utime: 0.6158429999999999
2025-12-05 16:18:29,969 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) wtime: 1.419
2025-12-05 16:18:29,970 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-05 16:18:29,970 - DEBUG - ya.test.22566  - _save_outputs: Command (pid 29662) errors:

2025-12-05 16:18:29,971 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-05 16:18:30,159 - INFO - ydb.tests.library.harness.kikimr_runner.22566  - __run: Cluster started and initialized
2025-12-05 16:18:30,237 - DEBUG - ydb.connection.22566  - 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-12-05 16:18:30,253 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:18:30,254 - DEBUG - ydb.connection.22566  - 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-12-05 16:18:30,284 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:18:30,285 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ListEndpoints, 1a74c2ca-3082-4bc4-82ba-4fd3ef2ead0d, localhost:8243): creating call state
2025-12-05 16:18:30,286 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ListEndpoints, 1a74c2ca-3082-4bc4-82ba-4fd3ef2ead0d, localhost:8243): request = { database: "/Root" }
2025-12-05 16:18:30,304 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ListEndpoints, 1a74c2ca-3082-4bc4-82ba-4fd3ef2ead0d, localhost:8243): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 8243 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" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-05 16:18:30,305 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:8243, location 1, ssl: False>]>
2025-12-05 16:18:30,306 - DEBUG - ydb.connection.22566  - 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-12-05 16:18:30,337 - DEBUG - ydb.connection.22566  - close: Closing channel for endpoint localhost:8243
2025-12-05 16:18:30,341 - DEBUG - ydb.query.pool.22566  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-05 16:18:30,410 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(CreateSession, 703506b5-4fca-42e3-879e-5545f4c82289, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:30,412 - DEBUG - ydb.connection.22566  - _log_request: RpcState(CreateSession, 703506b5-4fca-42e3-879e-5545f4c82289, ghrun-a66hhqwkt4.auto.internal:8243): request = {  }
2025-12-05 16:18:30,422 - DEBUG - ydb.connection.22566  - _log_response: RpcState(CreateSession, 703506b5-4fca-42e3-879e-5545f4c82289, ghrun-a66hhqwkt4.auto.internal:8243): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" node_id: 1 }
2025-12-05 16:18:30,438 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(AttachSession, a092838f-22f9-49e2-874b-c3753b61cfb3, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:30,502 - DEBUG - ydb.connection.22566  - _log_request: RpcState(AttachSession, a092838f-22f9-49e2-874b-c3753b61cfb3, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" }
2025-12-05 16:18:30,510 - DEBUG - ydb.connection.22566  - _log_response: RpcState(AttachSession, a092838f-22f9-49e2-874b-c3753b61cfb3, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:30,532 - DEBUG - ydb.query.pool.22566  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:30,533 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, c4325350-cc2e-45cf-987b-5fb2de47ce9a, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:30,534 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, c4325350-cc2e-45cf-987b-5fb2de47ce9a, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:30,536 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, c4325350-cc2e-45cf-987b-5fb2de47ce9a, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:36,073 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:36,105 - INFO - __tests__.alter_compression.22566  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-05 16:18:36,105 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:36,106 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, a4adc589-cc97-4d83-95bc-ac702b8b9749, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:36,107 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, a4adc589-cc97-4d83-95bc-ac702b8b9749, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:36,108 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, a4adc589-cc97-4d83-95bc-ac702b8b9749, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:37,874 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:37,875 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:37,876 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 8d271409-de03-4910-b0e5-a3f9400920bc, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:37,878 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 8d271409-de03-4910-b0e5-a3f9400920bc, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:37,886 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 8d271409-de03-4910-b0e5-a3f9400920bc, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:39,883 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:39,917 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:39,918 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 8f03ef47-aadb-42d5-8e92-90e49758df9a, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:39,919 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 8f03ef47-aadb-42d5-8e92-90e49758df9a, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:39,921 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 8f03ef47-aadb-42d5-8e92-90e49758df9a, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:41,208 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:41,208 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:41,209 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 9034560c-4140-40a7-ad34-2ce0dff0b6b5, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:41,210 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 9034560c-4140-40a7-ad34-2ce0dff0b6b5, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:41,211 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 9034560c-4140-40a7-ad34-2ce0dff0b6b5, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:42,982 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:42,982 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-12-05 16:18:42,983 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:42,984 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 3d96c7ff-d62d-480c-b6b9-1934cc3becd7, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:42,984 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 3d96c7ff-d62d-480c-b6b9-1934cc3becd7, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:42,992 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 3d96c7ff-d62d-480c-b6b9-1934cc3becd7, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:44,268 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:44,278 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:44,311 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 9ca1caa5-3e72-4fe3-8d1d-842de0686ba6, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:44,312 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 9ca1caa5-3e72-4fe3-8d1d-842de0686ba6, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:44,325 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 9ca1caa5-3e72-4fe3-8d1d-842de0686ba6, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:44,649 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:44,650 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:44,691 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, ea5a4c61-ba66-4764-8877-eaff66406d0c, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:44,692 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, ea5a4c61-ba66-4764-8877-eaff66406d0c, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:44,697 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, ea5a4c61-ba66-4764-8877-eaff66406d0c, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:44,980 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:44,981 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-12-05 16:18:44,981 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:44,982 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 70d915cb-9011-497c-b30b-c450ec54bb53, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:44,983 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 70d915cb-9011-497c-b30b-c450ec54bb53, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:44,984 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 70d915cb-9011-497c-b30b-c450ec54bb53, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:46,085 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:46,117 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:46,118 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, cf6b2a5e-942a-4684-9f92-5b2abe7243b8, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:46,146 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, cf6b2a5e-942a-4684-9f92-5b2abe7243b8, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:46,170 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, cf6b2a5e-942a-4684-9f92-5b2abe7243b8, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:46,753 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:46,754 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:46,755 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 98964ec8-c3e1-4498-8bf9-372dbd3824d6, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:46,755 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 98964ec8-c3e1-4498-8bf9-372dbd3824d6, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:46,757 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 98964ec8-c3e1-4498-8bf9-372dbd3824d6, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:47,082 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:47,098 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-12-05 16:18:47,099 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:47,099 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 4bfde98e-c921-44b5-88bd-c5d306eb1498, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:47,100 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 4bfde98e-c921-44b5-88bd-c5d306eb1498, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:47,126 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 4bfde98e-c921-44b5-88bd-c5d306eb1498, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:48,295 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:48,295 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:48,296 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, bf3724d0-4b30-4dec-8595-8c330e188822, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:48,297 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, bf3724d0-4b30-4dec-8595-8c330e188822, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:48,299 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, bf3724d0-4b30-4dec-8595-8c330e188822, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:48,633 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:48,639 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:48,640 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, e4410cf5-d561-440f-9a11-8d655b69444a, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:48,707 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, e4410cf5-d561-440f-9a11-8d655b69444a, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:48,709 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, e4410cf5-d561-440f-9a11-8d655b69444a, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:49,471 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:49,471 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-12-05 16:18:49,471 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:49,472 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 8b05a278-214a-4647-99eb-a12c6fe3dcf1, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:49,472 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 8b05a278-214a-4647-99eb-a12c6fe3dcf1, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:49,474 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 8b05a278-214a-4647-99eb-a12c6fe3dcf1, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:50,495 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:50,570 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:50,571 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 897474f7-12e9-46ea-9dd4-f595be39a073, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:50,654 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 897474f7-12e9-46ea-9dd4-f595be39a073, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:50,655 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 897474f7-12e9-46ea-9dd4-f595be39a073, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:50,863 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:50,864 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:50,865 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, efd43f15-477b-4728-9601-1d37ce5712d1, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:50,920 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, efd43f15-477b-4728-9601-1d37ce5712d1, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:50,922 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, efd43f15-477b-4728-9601-1d37ce5712d1, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:51,519 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:51,533 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-12-05 16:18:51,572 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:51,573 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 12c8dddf-b8a8-464b-b5a0-4540af5c347b, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:51,575 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 12c8dddf-b8a8-464b-b5a0-4540af5c347b, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:51,576 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 12c8dddf-b8a8-464b-b5a0-4540af5c347b, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:52,742 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:52,748 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:52,749 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 4ad477b3-d0e1-459f-b874-75036817dd29, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:52,751 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 4ad477b3-d0e1-459f-b874-75036817dd29, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:52,786 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 4ad477b3-d0e1-459f-b874-75036817dd29, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:53,458 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:53,516 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:53,517 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 534e9c03-11a3-4173-a000-6d9d96b25bf5, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:53,518 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 534e9c03-11a3-4173-a000-6d9d96b25bf5, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:53,520 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 534e9c03-11a3-4173-a000-6d9d96b25bf5, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:54,190 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:54,190 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-12-05 16:18:54,191 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:54,191 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 7c0a9d6b-f8c1-4591-934b-16b21eec6d09, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:54,192 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 7c0a9d6b-f8c1-4591-934b-16b21eec6d09, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:54,194 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 7c0a9d6b-f8c1-4591-934b-16b21eec6d09, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:55,490 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:55,560 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:55,561 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 1323818d-905b-4a16-958d-1289d25eac06, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:55,562 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 1323818d-905b-4a16-958d-1289d25eac06, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:55,563 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 1323818d-905b-4a16-958d-1289d25eac06, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:55,951 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:55,952 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:55,952 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 25f77272-cdcc-40aa-bed5-c79a501c7ba4, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:55,953 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 25f77272-cdcc-40aa-bed5-c79a501c7ba4, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:55,963 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 25f77272-cdcc-40aa-bed5-c79a501c7ba4, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:56,546 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:56,602 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-12-05 16:18:56,621 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:56,622 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, f9e9f950-5738-41b7-84e5-c7c988c09ba5, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:56,623 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, f9e9f950-5738-41b7-84e5-c7c988c09ba5, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:56,624 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, f9e9f950-5738-41b7-84e5-c7c988c09ba5, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:57,615 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:57,616 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:57,616 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 7a93b95f-b8d9-4762-9f6b-4e1df4ee3944, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:57,617 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 7a93b95f-b8d9-4762-9f6b-4e1df4ee3944, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:57,618 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 7a93b95f-b8d9-4762-9f6b-4e1df4ee3944, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:57,994 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:58,122 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:58,123 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, c79ab3f4-7336-4870-be9e-09ae45f29c62, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:58,124 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, c79ab3f4-7336-4870-be9e-09ae45f29c62, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:58,125 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, c79ab3f4-7336-4870-be9e-09ae45f29c62, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:58,815 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:58,819 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-12-05 16:18:58,819 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:18:58,825 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 6d263094-4d1f-41fa-87e6-9fa53ffec40d, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:18:58,862 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 6d263094-4d1f-41fa-87e6-9fa53ffec40d, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:58,863 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 6d263094-4d1f-41fa-87e6-9fa53ffec40d, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:00,013 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:00,072 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:00,073 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, ddcdc75e-2027-46d4-8e5a-124c847ee38b, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:00,074 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, ddcdc75e-2027-46d4-8e5a-124c847ee38b, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:00,075 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, ddcdc75e-2027-46d4-8e5a-124c847ee38b, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:00,303 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:00,365 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:00,366 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 37a5572b-2175-484e-a8d9-bc2a2f47b366, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:00,367 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 37a5572b-2175-484e-a8d9-bc2a2f47b366, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:00,369 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 37a5572b-2175-484e-a8d9-bc2a2f47b366, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:01,002 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:01,038 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-12-05 16:19:01,050 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:01,076 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, eacb5c8f-4458-4070-92dc-0728f7a30d69, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:01,091 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, eacb5c8f-4458-4070-92dc-0728f7a30d69, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:01,093 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, eacb5c8f-4458-4070-92dc-0728f7a30d69, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:02,162 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:02,174 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:02,218 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, edf3d422-fc5a-4684-aeda-2802566b3803, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:02,219 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, edf3d422-fc5a-4684-aeda-2802566b3803, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:02,220 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, edf3d422-fc5a-4684-aeda-2802566b3803, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:02,719 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:02,720 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:02,720 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, fdb38e37-5181-4e81-b2b5-1d19923b8a09, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:02,721 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, fdb38e37-5181-4e81-b2b5-1d19923b8a09, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:02,729 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, fdb38e37-5181-4e81-b2b5-1d19923b8a09, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:03,574 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:03,590 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-12-05 16:19:03,590 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:03,591 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, a96d54f5-8a4b-4595-ad45-99f199eb3d8e, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:03,592 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, a96d54f5-8a4b-4595-ad45-99f199eb3d8e, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:03,595 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, a96d54f5-8a4b-4595-ad45-99f199eb3d8e, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:04,981 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:04,981 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:04,982 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, d3cd8af9-e33a-4393-9d77-22273141b789, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:04,983 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, d3cd8af9-e33a-4393-9d77-22273141b789, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:04,998 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, d3cd8af9-e33a-4393-9d77-22273141b789, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:05,445 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:05,513 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:05,514 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, ef83863b-bce7-4a69-a226-2b44a2f791c0, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:05,588 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, ef83863b-bce7-4a69-a226-2b44a2f791c0, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:05,614 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, ef83863b-bce7-4a69-a226-2b44a2f791c0, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:06,027 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:06,028 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:06,028 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 1141f9d1-0db9-4873-97db-d52209cfba17, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:06,029 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 1141f9d1-0db9-4873-97db-d52209cfba17, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:06,030 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 1141f9d1-0db9-4873-97db-d52209cfba17, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:10,715 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:20,756 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:20,763 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, d6ffa6b6-bc4e-40c9-af32-fc3381050ad9, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:20,764 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, d6ffa6b6-bc4e-40c9-af32-fc3381050ad9, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:20,765 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, d6ffa6b6-bc4e-40c9-af32-fc3381050ad9, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:24,828 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:24,876 - INFO - root.22566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-05 16:19:24,903 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:24,927 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 157353d3-2154-43ca-970e-445761344f16, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:24,927 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 157353d3-2154-43ca-970e-445761344f16, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:24,934 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 157353d3-2154-43ca-970e-445761344f16, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:28,291 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:38,342 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:38,343 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, ea8b565e-2095-4712-8591-3737a866aafb, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:38,344 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, ea8b565e-2095-4712-8591-3737a866aafb, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:38,345 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, ea8b565e-2095-4712-8591-3737a866aafb, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:40,146 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:50,193 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:19:50,194 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, b3dd189b-7eb8-4f3b-bfa8-6fdb0fae7f85, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:19:50,207 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, b3dd189b-7eb8-4f3b-bfa8-6fdb0fae7f85, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:50,221 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, b3dd189b-7eb8-4f3b-bfa8-6fdb0fae7f85, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:50,816 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:00,820 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:00,822 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 38c08245-6e75-4041-8525-7a0250e6c95a, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:00,823 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 38c08245-6e75-4041-8525-7a0250e6c95a, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:00,824 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 38c08245-6e75-4041-8525-7a0250e6c95a, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:01,410 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:01,416 - INFO - root.22566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-05 16:20:01,418 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:01,419 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, b427f8a2-2c60-4bb3-817f-0c13710de14d, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:01,432 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, b427f8a2-2c60-4bb3-817f-0c13710de14d, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:01,433 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, b427f8a2-2c60-4bb3-817f-0c13710de14d, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:01,638 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:01,641 - INFO - ya.test.22566  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2])
2025-12-05 16:20:01,642 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:01,642 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 2af3d474-4ca3-4673-8070-a64c350ec7b1, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:01,643 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 2af3d474-4ca3-4673-8070-a64c350ec7b1, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_2_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 2)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:01,645 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 2af3d474-4ca3-4673-8070-a64c350ec7b1, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:04,183 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:04,214 - INFO - __tests__.alter_compression.22566  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_2_compression created
2025-12-05 16:20:04,214 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:04,215 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 666b92b7-9784-49c6-97f6-e4a6ab3432d8, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:04,216 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 666b92b7-9784-49c6-97f6-e4a6ab3432d8, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:04,217 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 666b92b7-9784-49c6-97f6-e4a6ab3432d8, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:04,955 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:04,968 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:04,988 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, d4459b4d-b549-4324-8650-fecfead74438, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:04,994 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, d4459b4d-b549-4324-8650-fecfead74438, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:04,996 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, d4459b4d-b549-4324-8650-fecfead74438, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:06,129 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:06,133 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:06,134 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 5d720f0c-d5c4-47f7-b70e-58b34035700b, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:06,135 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 5d720f0c-d5c4-47f7-b70e-58b34035700b, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:06,136 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 5d720f0c-d5c4-47f7-b70e-58b34035700b, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:06,906 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:06,908 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:06,909 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 75b2c1b8-2b10-43e0-9593-27d34711ddaa, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:06,910 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 75b2c1b8-2b10-43e0-9593-27d34711ddaa, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:06,911 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 75b2c1b8-2b10-43e0-9593-27d34711ddaa, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:08,381 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:08,381 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-12-05 16:20:08,382 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:08,383 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 498f8ea4-77b1-4733-a4cc-93bd34923a32, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:08,383 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 498f8ea4-77b1-4733-a4cc-93bd34923a32, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:08,385 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 498f8ea4-77b1-4733-a4cc-93bd34923a32, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:09,277 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:09,278 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:09,279 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, c179a306-6b60-4af4-b226-a843eddde9c3, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:09,279 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, c179a306-6b60-4af4-b226-a843eddde9c3, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:09,280 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, c179a306-6b60-4af4-b226-a843eddde9c3, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:09,572 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:09,573 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:09,573 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 94224c1c-9eb9-4def-9ae3-c447d29fdb4e, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:09,574 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 94224c1c-9eb9-4def-9ae3-c447d29fdb4e, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:09,575 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 94224c1c-9eb9-4def-9ae3-c447d29fdb4e, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:09,866 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:09,866 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638656, 'BlobCount': 256}}
2025-12-05 16:20:09,867 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:09,877 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 05d5ddbb-4e4b-4b31-8b62-fb1c543a9c37, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:09,888 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 05d5ddbb-4e4b-4b31-8b62-fb1c543a9c37, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:09,898 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 05d5ddbb-4e4b-4b31-8b62-fb1c543a9c37, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:10,866 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:10,867 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:10,867 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 76ef99db-7a17-4743-823b-505e5be56487, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:10,868 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 76ef99db-7a17-4743-823b-505e5be56487, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:10,872 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 76ef99db-7a17-4743-823b-505e5be56487, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:11,120 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:11,121 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:11,121 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 2906d58d-a893-4cb0-9e17-8bf5de3b58af, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:11,122 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 2906d58d-a893-4cb0-9e17-8bf5de3b58af, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:11,124 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 2906d58d-a893-4cb0-9e17-8bf5de3b58af, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:11,509 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:11,509 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957984, 'BlobCount': 384}}
2025-12-05 16:20:11,510 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:11,513 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 4c9d5310-76f8-4649-a7bc-50acdb88c287, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:11,530 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 4c9d5310-76f8-4649-a7bc-50acdb88c287, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:11,545 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 4c9d5310-76f8-4649-a7bc-50acdb88c287, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:12,601 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:12,601 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:12,602 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, f7377171-ae4d-4bbe-85ab-cd06b79ddb89, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:12,603 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, f7377171-ae4d-4bbe-85ab-cd06b79ddb89, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:12,605 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, f7377171-ae4d-4bbe-85ab-cd06b79ddb89, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:12,826 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:12,862 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:12,898 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 9ffdd2e3-fa61-4191-89d9-a42472c6d8c3, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:12,899 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 9ffdd2e3-fa61-4191-89d9-a42472c6d8c3, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:12,901 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 9ffdd2e3-fa61-4191-89d9-a42472c6d8c3, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:13,322 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:13,330 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1277728, 'BlobCount': 512}}
2025-12-05 16:20:13,350 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:13,351 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, adbc06b4-1fb0-4eb9-9a37-1e3f2659ceed, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:13,396 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, adbc06b4-1fb0-4eb9-9a37-1e3f2659ceed, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:13,403 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, adbc06b4-1fb0-4eb9-9a37-1e3f2659ceed, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:14,416 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:14,416 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:14,417 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 3fe5888d-6617-4387-8c6d-1f5336556dd9, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:14,418 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 3fe5888d-6617-4387-8c6d-1f5336556dd9, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:14,419 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 3fe5888d-6617-4387-8c6d-1f5336556dd9, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:14,657 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:14,660 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:14,660 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 6b1a85b2-026e-49a5-b6f4-3f47719edea2, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:14,661 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 6b1a85b2-026e-49a5-b6f4-3f47719edea2, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:14,663 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 6b1a85b2-026e-49a5-b6f4-3f47719edea2, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:15,194 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:15,195 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1597184, 'BlobCount': 640}}
2025-12-05 16:20:15,197 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:15,198 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, aa1f0820-a926-4d52-b0c1-bcdbed5eb8b3, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:15,199 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, aa1f0820-a926-4d52-b0c1-bcdbed5eb8b3, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:15,201 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, aa1f0820-a926-4d52-b0c1-bcdbed5eb8b3, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:16,159 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:16,159 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:16,160 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 2c14629a-58ff-4064-98d3-0c56c8005687, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:16,173 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 2c14629a-58ff-4064-98d3-0c56c8005687, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:16,180 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 2c14629a-58ff-4064-98d3-0c56c8005687, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:16,420 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:16,425 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:16,426 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, c025bb9d-c6ea-4beb-a92a-73d39b81f7ef, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:16,427 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, c025bb9d-c6ea-4beb-a92a-73d39b81f7ef, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:16,429 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, c025bb9d-c6ea-4beb-a92a-73d39b81f7ef, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:16,913 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:16,948 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1916800, 'BlobCount': 768}}
2025-12-05 16:20:16,957 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:16,958 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 4a454f5d-e75c-4ca2-bb3c-13e6293dd5a9, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:16,991 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 4a454f5d-e75c-4ca2-bb3c-13e6293dd5a9, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:17,017 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 4a454f5d-e75c-4ca2-bb3c-13e6293dd5a9, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:18,206 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:18,207 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:18,208 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 9e0080e2-ac0a-464f-a9cc-2d98c21fb76d, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:18,305 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 9e0080e2-ac0a-464f-a9cc-2d98c21fb76d, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:18,306 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 9e0080e2-ac0a-464f-a9cc-2d98c21fb76d, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:18,562 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:18,563 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:18,619 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 1970f96e-0dc1-4343-bbbd-f2a0b65df6f9, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:18,620 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 1970f96e-0dc1-4343-bbbd-f2a0b65df6f9, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:18,674 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 1970f96e-0dc1-4343-bbbd-f2a0b65df6f9, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:18,749 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:20:18,750 - DEBUG - ydb.connection.22566  - 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-12-05 16:20:18,789 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:20:18,810 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ListEndpoints, ff316a27-05af-490b-9908-c4e0cb33f2ee, localhost:8243): creating call state
2025-12-05 16:20:18,811 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ListEndpoints, ff316a27-05af-490b-9908-c4e0cb33f2ee, localhost:8243): request = { database: "/Root" }
2025-12-05 16:20:18,839 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ListEndpoints, ff316a27-05af-490b-9908-c4e0cb33f2ee, localhost:8243): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 8243 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" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-05 16:20:18,861 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22566  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:8243, location 1, ssl: False>]>
2025-12-05 16:20:18,862 - DEBUG - ydb.connection.22566  - close: Closing channel for endpoint localhost:8243
2025-12-05 16:20:19,223 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:19,225 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2236496, 'BlobCount': 896}}
2025-12-05 16:20:19,225 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:19,226 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 8bb09283-010a-40c6-b71b-4cea3c15bce3, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:19,227 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 8bb09283-010a-40c6-b71b-4cea3c15bce3, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:19,228 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 8bb09283-010a-40c6-b71b-4cea3c15bce3, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:20,316 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:20,323 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:20,324 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 1ec5aec5-452c-4c10-993f-5d1c55a378bf, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:20,325 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 1ec5aec5-452c-4c10-993f-5d1c55a378bf, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:20,342 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 1ec5aec5-452c-4c10-993f-5d1c55a378bf, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:20,568 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:20,577 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:20,578 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 2196acb8-72aa-428d-9524-ae1c7f02dec3, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:20,591 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 2196acb8-72aa-428d-9524-ae1c7f02dec3, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:20,594 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 2196acb8-72aa-428d-9524-ae1c7f02dec3, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:21,235 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:21,236 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2556272, 'BlobCount': 1024}}
2025-12-05 16:20:21,236 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:21,237 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, fdb18c67-c113-44f1-a8b4-9a18bfc41939, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:21,238 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, fdb18c67-c113-44f1-a8b4-9a18bfc41939, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:21,249 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, fdb18c67-c113-44f1-a8b4-9a18bfc41939, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:22,219 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:22,220 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:22,221 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, c0c49f94-0d16-4ac5-9f50-08fdbe0387b1, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:22,222 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, c0c49f94-0d16-4ac5-9f50-08fdbe0387b1, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:22,223 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, c0c49f94-0d16-4ac5-9f50-08fdbe0387b1, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:22,457 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:22,458 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:22,470 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 83fc9e76-b729-413f-a331-9128ddbd40d7, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:22,472 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 83fc9e76-b729-413f-a331-9128ddbd40d7, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:22,482 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 83fc9e76-b729-413f-a331-9128ddbd40d7, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:23,210 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:23,240 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2875584, 'BlobCount': 1152}}
2025-12-05 16:20:23,240 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:23,241 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 4365e1f0-1453-46b1-9e6e-9df550e3db33, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:23,242 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 4365e1f0-1453-46b1-9e6e-9df550e3db33, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:23,243 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 4365e1f0-1453-46b1-9e6e-9df550e3db33, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:24,201 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:24,202 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:24,203 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 453af835-86c3-4856-afe6-4ba7512bdba4, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:24,224 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 453af835-86c3-4856-afe6-4ba7512bdba4, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:24,226 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 453af835-86c3-4856-afe6-4ba7512bdba4, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:24,450 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:24,489 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:24,490 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, b74f6d6e-ba8d-4bec-8975-d3be9d7cf138, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:24,491 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, b74f6d6e-ba8d-4bec-8975-d3be9d7cf138, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:24,492 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, b74f6d6e-ba8d-4bec-8975-d3be9d7cf138, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:25,106 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:25,107 - INFO - __tests__.alter_compression.22566  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3195568, 'BlobCount': 1280}}
2025-12-05 16:20:25,107 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:25,108 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 8b4a144f-a1de-4c01-a9f7-6df1cbbcb506, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:25,109 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 8b4a144f-a1de-4c01-a9f7-6df1cbbcb506, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:25,110 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 8b4a144f-a1de-4c01-a9f7-6df1cbbcb506, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:26,117 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:26,118 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:26,119 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 1fc7f502-0db7-4aa6-93b8-eb753004c62b, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:26,120 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 1fc7f502-0db7-4aa6-93b8-eb753004c62b, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:26,122 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 1fc7f502-0db7-4aa6-93b8-eb753004c62b, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:26,418 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:26,418 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:26,419 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 1177bc39-6702-4f31-842d-d7bf2d9326a2, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:26,420 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 1177bc39-6702-4f31-842d-d7bf2d9326a2, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:26,432 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 1177bc39-6702-4f31-842d-d7bf2d9326a2, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:26,705 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:26,706 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:26,707 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, dc04d29e-2608-4cd7-b4ae-eca920548dd2, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:26,707 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, dc04d29e-2608-4cd7-b4ae-eca920548dd2, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:26,708 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, dc04d29e-2608-4cd7-b4ae-eca920548dd2, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:30,660 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:40,716 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:40,722 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, 649bde4f-42ed-4fe8-a934-d13d95d80a77, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:40,722 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, 649bde4f-42ed-4fe8-a934-d13d95d80a77, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:40,724 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, 649bde4f-42ed-4fe8-a934-d13d95d80a77, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:45,516 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:45,552 - INFO - root.22566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1597784)
2025-12-05 16:20:45,568 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:45,578 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, fed21f8c-9613-438b-a82c-e9463e112736, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:45,599 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, fed21f8c-9613-438b-a82c-e9463e112736, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:45,600 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, fed21f8c-9613-438b-a82c-e9463e112736, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:45,889 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:45,902 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:45,925 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, bd06942f-b0de-48b8-8751-802cb302cac3, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:45,926 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, bd06942f-b0de-48b8-8751-802cb302cac3, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:45,939 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, bd06942f-b0de-48b8-8751-802cb302cac3, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:49,483 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:59,591 - DEBUG - ydb.query.pool.22566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:20:59,626 - DEBUG - ydb.connection.22566  - _prepare_call: RpcState(ExecuteQuery, e3079008-0b8f-448a-b775-a43ad626d0ce, ghrun-a66hhqwkt4.auto.internal:8243): creating call state
2025-12-05 16:20:59,627 - DEBUG - ydb.connection.22566  - _log_request: RpcState(ExecuteQuery, e3079008-0b8f-448a-b775-a43ad626d0ce, ghrun-a66hhqwkt4.auto.internal:8243): request = { session_id: "ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:59,628 - DEBUG - ydb.connection.22566  - _log_response: RpcState(ExecuteQuery, e3079008-0b8f-448a-b775-a43ad626d0ce, ghrun-a66hhqwkt4.auto.internal:8243): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:03,650 - DEBUG - ydb.query.pool.22566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWJlZTkxMTAtODRlZGJmYjUtZjA2YzFmZTItZTkyYWZkMDU=
2025-12-05 16:21:03,736 - INFO - root.22566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1597784)
2025-12-05 16:21:03,736 - INFO - root.22566  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_2_compression` 8092160 / 1597784: 5.064614491070132
2025-12-05 16:21:03,740 - INFO - ya.test.22566  - pytest_runtest_teardown: Test teardown
