2025-11-21 14:05:25,004 - INFO - ya.test.1133532  - pytest_runtest_setup: ####################################################################################################
2025-11-21 14:05:25,005 - INFO - ya.test.1133532  - pytest_runtest_setup: test_all_supported_compression[zstd_12_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 12]
2025-11-21 14:05:25,005 - INFO - ya.test.1133532  - pytest_runtest_setup: ####################################################################################################
2025-11-21 14:05:25,005 - INFO - ya.test.1133532  - pytest_runtest_setup: Test setup
2025-11-21 14:05:25,008 - DEBUG - ya.test.1133532  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-21 14:05:25,009 - DEBUG - ya.test.1133532  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-21 14:05:25,009 - DEBUG - ya.test.1133532  - execute: Executing '['/home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 14:05:25,032 - DEBUG - ya.test.1133532  - execute: Command pid: 1135742
2025-11-21 14:05:26,699 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) rc: 0
2025-11-21 14:05:26,700 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) elapsed time (sec): 1.665963888168335
2025-11-21 14:05:26,700 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) idrss: 0
2025-11-21 14:05:26,700 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) inblock: 1248
2025-11-21 14:05:26,701 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) isrss: 0
2025-11-21 14:05:26,701 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) ixrss: 0
2025-11-21 14:05:26,701 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) majflt: 8
2025-11-21 14:05:26,702 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) maxrss: 485388
2025-11-21 14:05:26,702 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) minflt: 45597
2025-11-21 14:05:26,702 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) msgrcv: 0
2025-11-21 14:05:26,702 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) msgsnd: 0
2025-11-21 14:05:26,707 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) nivcsw: 254
2025-11-21 14:05:26,707 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) nsignals: 0
2025-11-21 14:05:26,708 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) nswap: 0
2025-11-21 14:05:26,708 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) nvcsw: 22
2025-11-21 14:05:26,708 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) oublock: 8
2025-11-21 14:05:26,709 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) stime: 0.253907
2025-11-21 14:05:26,709 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) utime: 0.594383
2025-11-21 14:05:26,709 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) wtime: 1.689
2025-11-21 14:05:26,710 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) output:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-acwccf6kvy    Host information: 
        Linux ghrun-acwccf6kvy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-21 14:05:26,710 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1135742) errors:

2025-11-21 14:05:26,710 - INFO - __tests__.base.1133532  - _setup_ydb: Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-acwccf6kvy    Host information: 
        Linux ghrun-acwccf6kvy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-21 14:05:26,981 - DEBUG - ya.test.1133532  - get_binary: Binary was found by /home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd
2025-11-21 14:05:26,987 - DEBUG - library.python.filelock.1133532  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28374
2025-11-21 14:05:26,989 - DEBUG - ydb.tests.library.harness.kikimr_runner.1133532  - start: Working directory: /home/runner/.ya/build/build_root/evw0/005d99/r3tmp/kikimr_cluster_qcypquxm
2025-11-21 14:05:27,088 - DEBUG - ya.test.1133532  - get_binary: Binary was found by /home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd
2025-11-21 14:05:27,089 - DEBUG - library.python.filelock.1133532  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24841
2025-11-21 14:05:27,090 - DEBUG - library.python.filelock.1133532  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24455
2025-11-21 14:05:27,090 - DEBUG - library.python.filelock.1133532  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13414
2025-11-21 14:05:27,093 - DEBUG - library.python.filelock.1133532  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24456
2025-11-21 14:05:27,094 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/evw0/005d99/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/kikimr_configs"
2025-11-21 14:05:27,095 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - __make_run_command: Final command: /home/runner/.ya/build/build_root/evw0/005d99/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/evw0/005d99/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/node_1/logfile_jghz1b3e.log --grpc-port=24841 --mon-port=24455 --ic-port=28374
2025-11-21 14:05:27,095 - DEBUG - ydb.tests.library.harness.kikimr_runner.1133532  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:24841/1, disk_size 68719476736
2025-11-21 14:05:27,096 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/evw0/005d99/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/kikimr_configs"
2025-11-21 14:05:27,096 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - __make_run_command: Final command: /home/runner/.ya/build/build_root/evw0/005d99/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/evw0/005d99/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/node_1/logfile_jghz1b3e.log --grpc-port=24841 --mon-port=24455 --ic-port=28374
2025-11-21 14:05:27,098 - DEBUG - ya.test.1133532  - execute: Executing '('/home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/evw0/005d99/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/evw0/005d99/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/node_1/logfile_jghz1b3e.log', '--grpc-port=24841', '--mon-port=24455', '--ic-port=28374')' in '/home/runner/.ya/build/build_root/evw0/005d99/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/node_1' (no wait)
2025-11-21 14:05:27,131 - DEBUG - ya.test.1133532  - execute: Command pid: 1136247
2025-11-21 14:05:27,132 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - start: Started node localhost:24841/1
2025-11-21 14:05:27,134 - DEBUG - ya.test.1133532  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-21 14:05:27,139 - DEBUG - ya.test.1133532  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-21 14:05:27,140 - DEBUG - ya.test.1133532  - execute: Executing '['/home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 14:05:27,155 - DEBUG - ya.test.1133532  - execute: Command pid: 1136262
2025-11-21 14:05:28,374 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) rc: 0
2025-11-21 14:05:28,375 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) elapsed time (sec): 1.217984676361084
2025-11-21 14:05:28,375 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) idrss: 0
2025-11-21 14:05:28,375 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) inblock: 8
2025-11-21 14:05:28,376 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) isrss: 0
2025-11-21 14:05:28,376 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) ixrss: 0
2025-11-21 14:05:28,377 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) majflt: 1
2025-11-21 14:05:28,377 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) maxrss: 493404
2025-11-21 14:05:28,377 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) minflt: 45604
2025-11-21 14:05:28,378 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) msgrcv: 0
2025-11-21 14:05:28,378 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) msgsnd: 0
2025-11-21 14:05:28,378 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) nivcsw: 318
2025-11-21 14:05:28,378 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) nsignals: 0
2025-11-21 14:05:28,379 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) nswap: 0
2025-11-21 14:05:28,379 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) nvcsw: 15
2025-11-21 14:05:28,379 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) oublock: 8
2025-11-21 14:05:28,380 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) stime: 0.26095
2025-11-21 14:05:28,380 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) utime: 0.6359739999999999
2025-11-21 14:05:28,380 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) wtime: 1.234
2025-11-21 14:05:28,380 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) output:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-acwccf6kvy    Host information: 
        Linux ghrun-acwccf6kvy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-21 14:05:28,381 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1136262) errors:

2025-11-21 14:05:28,382 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - start: Node 1 version:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-acwccf6kvy    Host information: 
        Linux ghrun-acwccf6kvy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-21 14:05:34,290 - DEBUG - ya.test.1133532  - get_binary: Binary was found by /home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd
2025-11-21 14:05:34,291 - DEBUG - ydb.tests.library.harness.kikimr_runner.1133532  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24841', '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: 28374\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-21 14:05:34,292 - DEBUG - ya.test.1133532  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-21 14:05:34,293 - DEBUG - ya.test.1133532  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-21 14:05:34,293 - DEBUG - ya.test.1133532  - execute: Executing '['/home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24841', '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: 28374\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-21 14:05:34,303 - DEBUG - ya.test.1133532  - execute: Command pid: 1138571
2025-11-21 14:05:35,765 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) rc: 0
2025-11-21 14:05:35,766 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) elapsed time (sec): 1.4610514640808105
2025-11-21 14:05:35,766 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) idrss: 0
2025-11-21 14:05:35,767 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) inblock: 576
2025-11-21 14:05:35,767 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) isrss: 0
2025-11-21 14:05:35,767 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) ixrss: 0
2025-11-21 14:05:35,767 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) majflt: 5
2025-11-21 14:05:35,768 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) maxrss: 588792
2025-11-21 14:05:35,768 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) minflt: 51898
2025-11-21 14:05:35,768 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) msgrcv: 0
2025-11-21 14:05:35,769 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) msgsnd: 0
2025-11-21 14:05:35,769 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) nivcsw: 292
2025-11-21 14:05:35,769 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) nsignals: 0
2025-11-21 14:05:35,769 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) nswap: 0
2025-11-21 14:05:35,770 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) nvcsw: 675
2025-11-21 14:05:35,770 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) oublock: 8
2025-11-21 14:05:35,770 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) stime: 0.26364299999999996
2025-11-21 14:05:35,771 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) utime: 0.6286769999999999
2025-11-21 14:05:35,788 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) wtime: 1.471
2025-11-21 14:05:35,788 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-21 14:05:35,789 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138571) errors:

2025-11-21 14:05:35,790 - DEBUG - ya.test.1133532  - get_binary: Binary was found by /home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd
2025-11-21 14:05:35,791 - DEBUG - ydb.tests.library.harness.kikimr_runner.1133532  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24841', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-21 14:05:35,792 - DEBUG - ya.test.1133532  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-21 14:05:35,793 - DEBUG - ya.test.1133532  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-21 14:05:35,794 - DEBUG - ya.test.1133532  - execute: Executing '['/home/runner/.ya/build/build_root/evw0/005d99/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24841', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-21 14:05:35,803 - DEBUG - ya.test.1133532  - execute: Command pid: 1138971
2025-11-21 14:05:37,298 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) rc: 0
2025-11-21 14:05:37,298 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) elapsed time (sec): 1.4904813766479492
2025-11-21 14:05:37,299 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) idrss: 0
2025-11-21 14:05:37,299 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) inblock: 0
2025-11-21 14:05:37,299 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) isrss: 0
2025-11-21 14:05:37,300 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) ixrss: 0
2025-11-21 14:05:37,300 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) majflt: 0
2025-11-21 14:05:37,300 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) maxrss: 588964
2025-11-21 14:05:37,301 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) minflt: 52262
2025-11-21 14:05:37,301 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) msgrcv: 0
2025-11-21 14:05:37,301 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) msgsnd: 0
2025-11-21 14:05:37,302 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) nivcsw: 403
2025-11-21 14:05:37,302 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) nsignals: 0
2025-11-21 14:05:37,302 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) nswap: 0
2025-11-21 14:05:37,303 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) nvcsw: 570
2025-11-21 14:05:37,303 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) oublock: 8
2025-11-21 14:05:37,304 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) stime: 0.35697599999999996
2025-11-21 14:05:37,304 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) utime: 0.615834
2025-11-21 14:05:37,304 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) wtime: 1.501
2025-11-21 14:05:37,305 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-21 14:05:37,305 - DEBUG - ya.test.1133532  - _save_outputs: Command (pid 1138971) errors:

2025-11-21 14:05:37,331 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-21 14:05:37,737 - INFO - ydb.tests.library.harness.kikimr_runner.1133532  - __run: Cluster started and initialized
2025-11-21 14:05:37,797 - DEBUG - ydb.connection.1133532  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 14:05:37,806 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 14:05:37,808 - DEBUG - ydb.connection.1133532  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 14:05:37,875 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Resolving endpoints for database /Root
2025-11-21 14:05:37,876 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ListEndpoints, 89790576-46bc-4f49-9fe5-0540fb2dcb8f, localhost:24841): creating call state
2025-11-21 14:05:37,878 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ListEndpoints, 89790576-46bc-4f49-9fe5-0540fb2dcb8f, localhost:24841): request = { database: "/Root" }
2025-11-21 14:05:37,893 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ListEndpoints, 89790576-46bc-4f49-9fe5-0540fb2dcb8f, localhost:24841): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-acwccf6kvy.auto.internal" port: 24841 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-11-21 14:05:37,894 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-acwccf6kvy.auto.internal:24841, location 1, ssl: False>]>
2025-11-21 14:05:37,896 - DEBUG - ydb.connection.1133532  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 14:05:37,943 - DEBUG - ydb.connection.1133532  - close: Closing channel for endpoint localhost:24841
2025-11-21 14:05:37,944 - DEBUG - ydb.query.pool.1133532  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-21 14:05:37,945 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(CreateSession, 334d5db6-70d6-4249-882b-56320119cdb3, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:37,946 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(CreateSession, 334d5db6-70d6-4249-882b-56320119cdb3, ghrun-acwccf6kvy.auto.internal:24841): request = {  }
2025-11-21 14:05:38,001 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(CreateSession, 334d5db6-70d6-4249-882b-56320119cdb3, ghrun-acwccf6kvy.auto.internal:24841): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" node_id: 1 }
2025-11-21 14:05:38,002 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(AttachSession, 2fe33059-b6a3-45c6-a8c2-793efe151f3e, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:38,003 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(AttachSession, 2fe33059-b6a3-45c6-a8c2-793efe151f3e, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" }
2025-11-21 14:05:38,016 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(AttachSession, 2fe33059-b6a3-45c6-a8c2-793efe151f3e, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:38,021 - DEBUG - ydb.query.pool.1133532  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:38,023 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 1f80aa56-1d94-4a49-8373-5a164145812b, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:38,024 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 1f80aa56-1d94-4a49-8373-5a164145812b, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:38,025 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 1f80aa56-1d94-4a49-8373-5a164145812b, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:42,222 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:42,223 - INFO - __tests__.alter_compression.1133532  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-21 14:05:42,224 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:42,225 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, ab9759cd-4d2f-4487-a39a-ccebadd11261, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:42,226 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, ab9759cd-4d2f-4487-a39a-ccebadd11261, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:42,231 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, ab9759cd-4d2f-4487-a39a-ccebadd11261, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:43,609 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:43,623 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:43,624 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 3fa5bef0-3775-4e1f-ae9d-1082231213c8, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:43,625 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 3fa5bef0-3775-4e1f-ae9d-1082231213c8, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:43,636 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 3fa5bef0-3775-4e1f-ae9d-1082231213c8, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:45,075 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:45,076 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:45,077 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 5d2acd88-1e4a-46f7-a1d4-da9b188134ca, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:45,078 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 5d2acd88-1e4a-46f7-a1d4-da9b188134ca, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:45,081 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 5d2acd88-1e4a-46f7-a1d4-da9b188134ca, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:46,068 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:46,069 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:46,070 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 4a493fe8-c76b-41e6-a988-af3c5616f1f5, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:46,071 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 4a493fe8-c76b-41e6-a988-af3c5616f1f5, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:46,074 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 4a493fe8-c76b-41e6-a988-af3c5616f1f5, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:48,453 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:48,454 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:05:48,454 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:48,455 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 518f2b53-f9e8-4176-a352-24c8e445bdb5, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:48,456 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 518f2b53-f9e8-4176-a352-24c8e445bdb5, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:48,459 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 518f2b53-f9e8-4176-a352-24c8e445bdb5, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:50,025 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:50,025 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:50,026 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 82a399e1-1371-4968-9eab-429390731006, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:50,027 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 82a399e1-1371-4968-9eab-429390731006, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:50,029 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 82a399e1-1371-4968-9eab-429390731006, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:50,493 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:50,494 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:50,495 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 0bcd3de7-3e49-4e21-99da-6c1220a1a982, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:50,496 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 0bcd3de7-3e49-4e21-99da-6c1220a1a982, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:50,498 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 0bcd3de7-3e49-4e21-99da-6c1220a1a982, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:50,956 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:50,958 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:05:50,958 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:50,959 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6503e253-33e3-4ab1-91c1-e3eb50e308a1, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:50,960 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6503e253-33e3-4ab1-91c1-e3eb50e308a1, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:50,962 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6503e253-33e3-4ab1-91c1-e3eb50e308a1, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:51,855 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:51,856 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:51,857 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 996498ec-35b5-41d6-9de7-a365c695b7cc, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:51,858 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 996498ec-35b5-41d6-9de7-a365c695b7cc, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:51,860 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 996498ec-35b5-41d6-9de7-a365c695b7cc, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:52,240 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:52,241 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:52,241 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 324b7317-bc57-4180-a489-fdb2d5efa901, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:52,242 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 324b7317-bc57-4180-a489-fdb2d5efa901, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:52,244 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 324b7317-bc57-4180-a489-fdb2d5efa901, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:53,145 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:53,146 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:05:53,146 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:53,147 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, e4443027-144a-4ea7-b3c0-f4bda3016a3a, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:53,148 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, e4443027-144a-4ea7-b3c0-f4bda3016a3a, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:53,149 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, e4443027-144a-4ea7-b3c0-f4bda3016a3a, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:54,523 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:54,524 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:54,525 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 78f872be-cab5-4fbc-9ebb-2081853a3fe6, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:54,526 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 78f872be-cab5-4fbc-9ebb-2081853a3fe6, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:54,540 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 78f872be-cab5-4fbc-9ebb-2081853a3fe6, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:54,950 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:54,951 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:54,952 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 989ee038-e678-4add-a15e-0aa22e42d34c, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:54,953 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 989ee038-e678-4add-a15e-0aa22e42d34c, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:54,957 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 989ee038-e678-4add-a15e-0aa22e42d34c, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:55,450 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:55,451 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:05:55,452 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:55,452 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 91aeba7c-1b01-40e0-9dca-2476846855a6, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:55,453 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 91aeba7c-1b01-40e0-9dca-2476846855a6, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:55,456 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 91aeba7c-1b01-40e0-9dca-2476846855a6, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:56,586 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:56,587 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:56,588 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 234bc07c-f3ce-41b8-a804-48be71c3e293, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:56,589 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 234bc07c-f3ce-41b8-a804-48be71c3e293, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:56,591 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 234bc07c-f3ce-41b8-a804-48be71c3e293, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:56,847 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:56,848 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:56,849 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 3faef093-0080-4cc6-b27a-61f9ee2f0d3f, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:56,850 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 3faef093-0080-4cc6-b27a-61f9ee2f0d3f, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:56,851 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 3faef093-0080-4cc6-b27a-61f9ee2f0d3f, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:57,284 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:57,284 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:05:57,285 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:57,286 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, bed7abe3-878e-4b28-81f4-937e32ae7716, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:57,287 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, bed7abe3-878e-4b28-81f4-937e32ae7716, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:57,288 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, bed7abe3-878e-4b28-81f4-937e32ae7716, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:58,385 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:58,386 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:58,387 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6ceb4b9b-80ff-417b-800e-7f9e089fc3d8, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:58,388 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6ceb4b9b-80ff-417b-800e-7f9e089fc3d8, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:58,390 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6ceb4b9b-80ff-417b-800e-7f9e089fc3d8, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:58,699 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:58,700 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:58,700 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, cdf185cb-621d-4d5e-a798-299bd5e77c0a, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:58,701 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, cdf185cb-621d-4d5e-a798-299bd5e77c0a, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:58,704 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, cdf185cb-621d-4d5e-a798-299bd5e77c0a, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:05:59,203 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:59,204 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:05:59,205 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:05:59,205 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 0f38f3cd-b302-4668-8a12-78bc97f766e9, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:05:59,211 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 0f38f3cd-b302-4668-8a12-78bc97f766e9, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:05:59,213 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 0f38f3cd-b302-4668-8a12-78bc97f766e9, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:00,864 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:00,865 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:00,866 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 9e6e2516-916f-441c-8e97-b32fcecbc448, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:00,866 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 9e6e2516-916f-441c-8e97-b32fcecbc448, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:00,868 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 9e6e2516-916f-441c-8e97-b32fcecbc448, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:01,448 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:01,449 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:01,449 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 935da321-56d4-478a-945a-ff1604111fa8, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:01,450 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 935da321-56d4-478a-945a-ff1604111fa8, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:01,452 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 935da321-56d4-478a-945a-ff1604111fa8, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:02,632 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:02,633 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:06:02,634 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:02,635 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, fe441651-1f92-4098-baf6-4ad52d265a15, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:02,636 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, fe441651-1f92-4098-baf6-4ad52d265a15, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:02,637 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, fe441651-1f92-4098-baf6-4ad52d265a15, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:04,332 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:04,333 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:04,334 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 891320fe-e95e-4bb2-a978-1d0b17b430ab, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:04,335 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 891320fe-e95e-4bb2-a978-1d0b17b430ab, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:04,336 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 891320fe-e95e-4bb2-a978-1d0b17b430ab, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:04,740 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:04,741 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:04,742 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6d06623c-b476-481a-a835-61f2e217fc6d, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:04,743 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6d06623c-b476-481a-a835-61f2e217fc6d, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:04,745 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6d06623c-b476-481a-a835-61f2e217fc6d, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:05,496 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:05,496 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:06:05,497 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:05,498 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 662aed37-5cfb-4edb-8469-a44c91e8604f, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:05,503 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 662aed37-5cfb-4edb-8469-a44c91e8604f, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:05,505 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 662aed37-5cfb-4edb-8469-a44c91e8604f, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:06,875 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:06,876 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:06,877 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, a4f3e535-2c9e-4efb-987a-f3fdd16a0f5b, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:06,878 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, a4f3e535-2c9e-4efb-987a-f3fdd16a0f5b, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:06,879 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, a4f3e535-2c9e-4efb-987a-f3fdd16a0f5b, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:07,553 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:07,554 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:07,554 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 760c53a1-c19a-444d-8876-61e64ad70669, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:07,555 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 760c53a1-c19a-444d-8876-61e64ad70669, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:07,558 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 760c53a1-c19a-444d-8876-61e64ad70669, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:09,037 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:09,038 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:06:09,039 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:09,040 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 90be67e2-15af-4fb6-b3b5-c43b21e0c93c, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:09,041 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 90be67e2-15af-4fb6-b3b5-c43b21e0c93c, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:09,047 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 90be67e2-15af-4fb6-b3b5-c43b21e0c93c, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:10,803 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:10,804 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:10,805 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 9d9881a7-d283-4f98-83ab-09b6f9aa5537, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:10,806 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 9d9881a7-d283-4f98-83ab-09b6f9aa5537, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:10,808 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 9d9881a7-d283-4f98-83ab-09b6f9aa5537, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:11,244 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:11,245 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:11,246 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 43c3d873-fb84-410e-a2a7-c33145d72fce, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:11,254 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 43c3d873-fb84-410e-a2a7-c33145d72fce, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:11,255 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 43c3d873-fb84-410e-a2a7-c33145d72fce, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:13,615 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:13,616 - INFO - __tests__.alter_compression.1133532  - 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-11-21 14:06:13,616 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:13,617 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, be80e843-3d06-4538-b14f-de06aa8c67ca, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:13,618 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, be80e843-3d06-4538-b14f-de06aa8c67ca, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:13,620 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, be80e843-3d06-4538-b14f-de06aa8c67ca, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:15,696 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:15,697 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:15,698 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 04242318-f1bf-47ac-9407-20b16570f5e6, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:15,699 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 04242318-f1bf-47ac-9407-20b16570f5e6, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:15,700 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 04242318-f1bf-47ac-9407-20b16570f5e6, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:16,199 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:16,200 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:16,201 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 1035f398-daac-4b6b-8e70-5a3e17be1811, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:16,202 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 1035f398-daac-4b6b-8e70-5a3e17be1811, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:16,208 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 1035f398-daac-4b6b-8e70-5a3e17be1811, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:17,034 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:17,035 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:17,035 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 9c20a119-3eb8-45aa-9592-dc0539d82aa6, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:17,036 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 9c20a119-3eb8-45aa-9592-dc0539d82aa6, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:17,038 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 9c20a119-3eb8-45aa-9592-dc0539d82aa6, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:26,673 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:36,731 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:06:36,732 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6bbad4e7-080a-46d6-8eba-0c508106e5d5, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:06:36,733 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6bbad4e7-080a-46d6-8eba-0c508106e5d5, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:06:36,735 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6bbad4e7-080a-46d6-8eba-0c508106e5d5, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:06:52,492 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:02,941 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:03,000 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 3c77a674-1537-497c-8314-f49a8e99771d, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:03,001 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 3c77a674-1537-497c-8314-f49a8e99771d, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:07:03,002 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 3c77a674-1537-497c-8314-f49a8e99771d, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:07:09,464 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:11,218 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 14:07:11,219 - DEBUG - ydb.connection.1133532  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 14:07:11,243 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Resolving endpoints for database /Root
2025-11-21 14:07:11,244 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ListEndpoints, 57a1ffd2-0dc7-4575-a4ae-c819e176a95b, localhost:24841): creating call state
2025-11-21 14:07:11,245 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ListEndpoints, 57a1ffd2-0dc7-4575-a4ae-c819e176a95b, localhost:24841): request = { database: "/Root" }
2025-11-21 14:07:11,259 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ListEndpoints, 57a1ffd2-0dc7-4575-a4ae-c819e176a95b, localhost:24841): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-acwccf6kvy.auto.internal" port: 24841 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-11-21 14:07:11,276 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-acwccf6kvy.auto.internal:24841, location 1, ssl: False>]>
2025-11-21 14:07:11,277 - DEBUG - ydb.connection.1133532  - close: Closing channel for endpoint localhost:24841
2025-11-21 14:07:19,491 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:19,492 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 42cf23d7-9f5f-4fd1-b670-23fe66d52e0e, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:19,493 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 42cf23d7-9f5f-4fd1-b670-23fe66d52e0e, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:07:19,498 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 42cf23d7-9f5f-4fd1-b670-23fe66d52e0e, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:07:21,366 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:31,377 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:31,378 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, c8838f6e-7247-4126-8bac-da6ad3f00c38, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:31,379 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, c8838f6e-7247-4126-8bac-da6ad3f00c38, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:07:31,380 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, c8838f6e-7247-4126-8bac-da6ad3f00c38, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:07:33,965 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:33,972 - INFO - root.1133532  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-21 14:07:33,973 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:33,974 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, ee02cb6e-1712-4048-be8d-860ba8517573, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:33,978 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, ee02cb6e-1712-4048-be8d-860ba8517573, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:07:33,979 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, ee02cb6e-1712-4048-be8d-860ba8517573, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:07:36,680 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:46,687 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:46,688 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 8fe1e162-2854-478b-82f0-90f44f91d822, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:46,689 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 8fe1e162-2854-478b-82f0-90f44f91d822, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:07:46,690 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 8fe1e162-2854-478b-82f0-90f44f91d822, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:07:48,872 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:48,876 - INFO - root.1133532  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-21 14:07:48,877 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:48,877 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 785cb4b7-e9f2-44e3-9db3-ef828dd3e5c5, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:48,878 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 785cb4b7-e9f2-44e3-9db3-ef828dd3e5c5, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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 }
2025-11-21 14:07:48,884 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 785cb4b7-e9f2-44e3-9db3-ef828dd3e5c5, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:07:50,244 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:50,248 - INFO - ya.test.1133532  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_12_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 12])
2025-11-21 14:07:50,252 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:50,253 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 47f36481-6199-44ad-b3ae-32c4e46e0d3a, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:50,266 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 47f36481-6199-44ad-b3ae-32c4e46e0d3a, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_12_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 12)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:07:50,267 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 47f36481-6199-44ad-b3ae-32c4e46e0d3a, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:07:57,135 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:57,180 - INFO - __tests__.alter_compression.1133532  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_12_compression created
2025-11-21 14:07:57,180 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:57,181 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, ff0d2859-0887-4476-8593-1cfb5f910efe, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:57,182 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, ff0d2859-0887-4476-8593-1cfb5f910efe, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_12_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:07:57,183 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, ff0d2859-0887-4476-8593-1cfb5f910efe, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:07:58,446 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:58,447 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:07:58,447 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 68a80cab-0392-4740-93ee-8fc406ec6edf, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:07:58,448 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 68a80cab-0392-4740-93ee-8fc406ec6edf, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:07:58,450 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 68a80cab-0392-4740-93ee-8fc406ec6edf, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:06,094 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:06,095 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:06,095 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, c3e04c05-7fd2-4658-8a3f-57e8e97dc925, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:06,096 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, c3e04c05-7fd2-4658-8a3f-57e8e97dc925, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:06,115 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, c3e04c05-7fd2-4658-8a3f-57e8e97dc925, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:07,875 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:07,876 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:07,877 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, fa2d2fe5-bcad-4a34-a1de-b3a5068579ba, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:07,878 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, fa2d2fe5-bcad-4a34-a1de-b3a5068579ba, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:07,883 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, fa2d2fe5-bcad-4a34-a1de-b3a5068579ba, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:11,984 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:11,985 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 375776, 'BlobCount': 128}}
2025-11-21 14:08:11,985 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:11,986 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6e898141-789c-45c0-8b47-5b5db85b2c70, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:11,987 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6e898141-789c-45c0-8b47-5b5db85b2c70, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:11,989 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6e898141-789c-45c0-8b47-5b5db85b2c70, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:16,298 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:16,299 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:16,300 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, b88daa74-a8df-4192-8860-eeac54134c2d, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:16,300 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, b88daa74-a8df-4192-8860-eeac54134c2d, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:16,316 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, b88daa74-a8df-4192-8860-eeac54134c2d, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:17,331 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:17,332 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:17,333 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 13110a4f-c8d6-400b-b209-772813a5a047, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:17,334 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 13110a4f-c8d6-400b-b209-772813a5a047, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:17,336 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 13110a4f-c8d6-400b-b209-772813a5a047, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:18,212 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:18,213 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 753024, 'BlobCount': 256}}
2025-11-21 14:08:18,213 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:18,214 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, a25bda67-9ae0-4faf-94ff-e57640d9cdbb, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:18,217 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, a25bda67-9ae0-4faf-94ff-e57640d9cdbb, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:18,219 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, a25bda67-9ae0-4faf-94ff-e57640d9cdbb, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:23,049 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:23,050 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:23,051 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, af761dc5-079c-4055-8afe-c45907c0dab9, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:23,051 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, af761dc5-079c-4055-8afe-c45907c0dab9, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:23,080 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, af761dc5-079c-4055-8afe-c45907c0dab9, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:23,679 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:23,680 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:23,681 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, a6335558-249f-46c7-8c03-e44f7e28b381, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:23,682 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, a6335558-249f-46c7-8c03-e44f7e28b381, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:23,704 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, a6335558-249f-46c7-8c03-e44f7e28b381, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:24,555 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:24,556 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 1129904, 'BlobCount': 384}}
2025-11-21 14:08:24,557 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:24,557 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 0b222ac4-6e99-42d7-be89-22a4ed9a02fc, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:24,558 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 0b222ac4-6e99-42d7-be89-22a4ed9a02fc, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:24,577 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 0b222ac4-6e99-42d7-be89-22a4ed9a02fc, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:30,413 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:30,414 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:30,415 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 4b0a5715-46ae-421d-b184-17a7d45455bb, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:30,416 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 4b0a5715-46ae-421d-b184-17a7d45455bb, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:30,418 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 4b0a5715-46ae-421d-b184-17a7d45455bb, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:31,120 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:31,121 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:31,122 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, d95d98c5-9847-4315-b4d2-5889e4afc3fc, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:31,123 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, d95d98c5-9847-4315-b4d2-5889e4afc3fc, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:31,125 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, d95d98c5-9847-4315-b4d2-5889e4afc3fc, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:32,781 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:32,782 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1507088, 'BlobCount': 512}}
2025-11-21 14:08:32,782 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:32,783 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 89a8a312-7b58-4b0f-bf8d-6c69b589b913, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:32,784 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 89a8a312-7b58-4b0f-bf8d-6c69b589b913, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:32,787 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 89a8a312-7b58-4b0f-bf8d-6c69b589b913, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:38,387 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:38,388 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:38,388 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 02c75517-de9e-4e46-94f1-285f0a1fd563, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:38,389 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 02c75517-de9e-4e46-94f1-285f0a1fd563, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:38,395 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 02c75517-de9e-4e46-94f1-285f0a1fd563, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:39,207 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:39,208 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:39,209 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 7a0a40ab-42a5-4b35-9305-14fe55c05259, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:39,210 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 7a0a40ab-42a5-4b35-9305-14fe55c05259, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:39,222 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 7a0a40ab-42a5-4b35-9305-14fe55c05259, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:40,342 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:40,343 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1883952, 'BlobCount': 640}}
2025-11-21 14:08:40,343 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:40,344 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, df50d149-7a0e-48dd-b416-ebe527c6c02b, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:40,345 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, df50d149-7a0e-48dd-b416-ebe527c6c02b, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:40,363 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, df50d149-7a0e-48dd-b416-ebe527c6c02b, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:45,908 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:45,909 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:45,909 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, b05c53c4-3275-40dd-80a3-206be732fe01, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:45,910 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, b05c53c4-3275-40dd-80a3-206be732fe01, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:45,918 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, b05c53c4-3275-40dd-80a3-206be732fe01, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:46,535 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:46,536 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:46,537 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 2291d730-c03e-4b1b-9ecc-3d1b8b7f6f10, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:46,538 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 2291d730-c03e-4b1b-9ecc-3d1b8b7f6f10, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:46,542 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 2291d730-c03e-4b1b-9ecc-3d1b8b7f6f10, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:47,489 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:47,490 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 2261152, 'BlobCount': 768}}
2025-11-21 14:08:47,490 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:47,491 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 64d8e4a9-10d6-4913-b727-1c8cc40ebfb9, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:47,492 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 64d8e4a9-10d6-4913-b727-1c8cc40ebfb9, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:47,494 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 64d8e4a9-10d6-4913-b727-1c8cc40ebfb9, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:49,630 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 14:08:49,631 - DEBUG - ydb.connection.1133532  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 14:08:49,650 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Resolving endpoints for database /Root
2025-11-21 14:08:49,651 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ListEndpoints, 492be847-fcf8-4799-995e-e7efccc9c1f2, localhost:24841): creating call state
2025-11-21 14:08:49,651 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ListEndpoints, 492be847-fcf8-4799-995e-e7efccc9c1f2, localhost:24841): request = { database: "/Root" }
2025-11-21 14:08:49,658 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ListEndpoints, 492be847-fcf8-4799-995e-e7efccc9c1f2, localhost:24841): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-acwccf6kvy.auto.internal" port: 24841 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-11-21 14:08:49,660 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-acwccf6kvy.auto.internal:24841, location 1, ssl: False>]>
2025-11-21 14:08:49,661 - DEBUG - ydb.connection.1133532  - close: Closing channel for endpoint localhost:24841
2025-11-21 14:08:50,764 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:50,765 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:50,766 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, ca0b6b2f-6473-4bb5-880b-7360974dda2f, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:50,767 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, ca0b6b2f-6473-4bb5-880b-7360974dda2f, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:50,771 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, ca0b6b2f-6473-4bb5-880b-7360974dda2f, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:51,563 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:51,564 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:51,564 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, e9db72fd-78f3-4fb6-b1b3-2603e32fc1ed, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:51,565 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, e9db72fd-78f3-4fb6-b1b3-2603e32fc1ed, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:51,567 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, e9db72fd-78f3-4fb6-b1b3-2603e32fc1ed, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:08:53,186 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:53,187 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2638128, 'BlobCount': 896}}
2025-11-21 14:08:53,187 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:08:53,188 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, fa0d6fb5-361a-4bc8-ae0e-795555dedee9, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:08:53,189 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, fa0d6fb5-361a-4bc8-ae0e-795555dedee9, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:08:53,216 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, fa0d6fb5-361a-4bc8-ae0e-795555dedee9, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:00,144 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:00,145 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:00,145 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, b181a33f-beb2-4c56-afcb-f66b9714af3b, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:00,146 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, b181a33f-beb2-4c56-afcb-f66b9714af3b, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:00,152 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, b181a33f-beb2-4c56-afcb-f66b9714af3b, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:01,026 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:01,027 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:01,027 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, fbfa39ac-c010-4baf-96ba-3c5a0594a3df, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:01,028 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, fbfa39ac-c010-4baf-96ba-3c5a0594a3df, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:01,031 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, fbfa39ac-c010-4baf-96ba-3c5a0594a3df, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:02,515 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:02,516 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 3015296, 'BlobCount': 1024}}
2025-11-21 14:09:02,517 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:02,517 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, e94ca214-31c0-447c-b101-b0ca4a0e9ab3, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:02,518 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, e94ca214-31c0-447c-b101-b0ca4a0e9ab3, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:02,520 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, e94ca214-31c0-447c-b101-b0ca4a0e9ab3, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:08,283 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:08,284 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:08,285 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 3378a2c9-9b6e-467a-88c0-91f4041ff5cc, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:08,286 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 3378a2c9-9b6e-467a-88c0-91f4041ff5cc, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:08,292 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 3378a2c9-9b6e-467a-88c0-91f4041ff5cc, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:09,117 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:09,118 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:09,119 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 849336ab-6154-48a3-a4df-6ef73f6bdc5a, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:09,182 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 849336ab-6154-48a3-a4df-6ef73f6bdc5a, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:09,183 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 849336ab-6154-48a3-a4df-6ef73f6bdc5a, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:10,272 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:10,273 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 3392304, 'BlobCount': 1152}}
2025-11-21 14:09:10,273 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:10,274 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, accdcab8-1059-4a20-afa3-2dad032e29da, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:10,275 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, accdcab8-1059-4a20-afa3-2dad032e29da, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:10,277 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, accdcab8-1059-4a20-afa3-2dad032e29da, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:14,402 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:14,404 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:14,405 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 7602bfb9-cc20-4a69-a863-ff011c9f081e, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:14,405 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 7602bfb9-cc20-4a69-a863-ff011c9f081e, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:14,414 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 7602bfb9-cc20-4a69-a863-ff011c9f081e, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:15,049 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:15,050 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:15,050 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, ecf7d0d3-16c3-4aae-8568-c48421e12d2f, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:15,051 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, ecf7d0d3-16c3-4aae-8568-c48421e12d2f, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:15,063 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, ecf7d0d3-16c3-4aae-8568-c48421e12d2f, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:16,193 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:16,194 - INFO - __tests__.alter_compression.1133532  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3769616, 'BlobCount': 1280}}
2025-11-21 14:09:16,194 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:16,195 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 92a26774-a71a-40f6-af83-daa547915088, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:16,196 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 92a26774-a71a-40f6-af83-daa547915088, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_12_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:16,198 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 92a26774-a71a-40f6-af83-daa547915088, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:18,527 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:18,528 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:18,529 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 98d0fc27-eb76-44a7-b3e0-f5d356efdaa5, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:18,530 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 98d0fc27-eb76-44a7-b3e0-f5d356efdaa5, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:18,543 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 98d0fc27-eb76-44a7-b3e0-f5d356efdaa5, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:19,100 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:19,100 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:19,101 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 6592055d-df7c-415d-9d62-ccf14dd87732, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:19,102 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 6592055d-df7c-415d-9d62-ccf14dd87732, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:19,104 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 6592055d-df7c-415d-9d62-ccf14dd87732, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:19,656 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:19,657 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:19,658 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 180a8095-7382-4f6c-a8b8-749bc9955e44, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:19,659 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 180a8095-7382-4f6c-a8b8-749bc9955e44, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:19,661 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 180a8095-7382-4f6c-a8b8-749bc9955e44, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:26,572 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:36,615 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:36,616 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 1c810abe-a134-4bae-8c72-dab8a8180a74, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:36,617 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 1c810abe-a134-4bae-8c72-dab8a8180a74, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:36,619 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 1c810abe-a134-4bae-8c72-dab8a8180a74, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:46,712 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:46,787 - INFO - root.1133532  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_12_compression`, volumes `value` (8000000, 1884808)
2025-11-21 14:09:46,788 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:46,789 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 3796cffe-902e-49df-ba5a-7f00144f5977, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:46,790 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 3796cffe-902e-49df-ba5a-7f00144f5977, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:46,799 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 3796cffe-902e-49df-ba5a-7f00144f5977, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:47,672 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:47,673 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:47,674 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 348b971c-344d-4b5b-b354-73c868efc7a0, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:09:47,674 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 348b971c-344d-4b5b-b354-73c868efc7a0, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:09:47,679 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 348b971c-344d-4b5b-b354-73c868efc7a0, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:09:56,188 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:09:58,838 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 14:09:58,838 - DEBUG - ydb.connection.1133532  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-21 14:09:58,859 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - context_resolve: Resolving endpoints for database /Root
2025-11-21 14:09:58,860 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ListEndpoints, 48d9d126-af60-4ac5-8d36-e3c780e01246, localhost:24841): creating call state
2025-11-21 14:09:58,861 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ListEndpoints, 48d9d126-af60-4ac5-8d36-e3c780e01246, localhost:24841): request = { database: "/Root" }
2025-11-21 14:09:58,868 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ListEndpoints, 48d9d126-af60-4ac5-8d36-e3c780e01246, localhost:24841): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-acwccf6kvy.auto.internal" port: 24841 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-11-21 14:09:58,869 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1133532  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-acwccf6kvy.auto.internal:24841, location 1, ssl: False>]>
2025-11-21 14:09:58,869 - DEBUG - ydb.connection.1133532  - close: Closing channel for endpoint localhost:24841
2025-11-21 14:10:06,232 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:10:06,233 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 64021df7-f3d2-4612-be52-e40b3d6e3957, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:10:06,233 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 64021df7-f3d2-4612-be52-e40b3d6e3957, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:06,235 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 64021df7-f3d2-4612-be52-e40b3d6e3957, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:13,235 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:10:23,299 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:10:23,300 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, c96c1343-c7ac-4b67-b40c-7e20cb044a3f, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:10:23,301 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, c96c1343-c7ac-4b67-b40c-7e20cb044a3f, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:23,302 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, c96c1343-c7ac-4b67-b40c-7e20cb044a3f, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:27,798 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:10:37,806 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:10:37,807 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, e77c039c-e3c3-4d87-8823-3685b6c523b3, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:10:37,808 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, e77c039c-e3c3-4d87-8823-3685b6c523b3, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:37,809 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, e77c039c-e3c3-4d87-8823-3685b6c523b3, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:39,449 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:10:49,454 - DEBUG - ydb.query.pool.1133532  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:10:49,455 - DEBUG - ydb.connection.1133532  - _prepare_call: RpcState(ExecuteQuery, 70833a68-7469-4008-ad6c-d23bac0f7fb5, ghrun-acwccf6kvy.auto.internal:24841): creating call state
2025-11-21 14:10:49,456 - DEBUG - ydb.connection.1133532  - _log_request: RpcState(ExecuteQuery, 70833a68-7469-4008-ad6c-d23bac0f7fb5, ghrun-acwccf6kvy.auto.internal:24841): request = { session_id: "ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 14:10:49,458 - DEBUG - ydb.connection.1133532  - _log_response: RpcState(ExecuteQuery, 70833a68-7469-4008-ad6c-d23bac0f7fb5, ghrun-acwccf6kvy.auto.internal:24841): response = { <_MultiThreadedRendezvous object> }
2025-11-21 14:10:50,880 - DEBUG - ydb.query.pool.1133532  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjIzNjM3MzEtYjM4NGRjY2ItNWNmNzc0NWItNGIzMjdlZWE=
2025-11-21 14:10:50,885 - INFO - root.1133532  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_12_compression`, volumes `value` (8000000, 1465648)
2025-11-21 14:10:50,885 - INFO - root.1133532  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_12_compression` 8009216 / 1465648: 5.464624521030971
2025-11-21 14:10:50,919 - INFO - ya.test.1133532  - pytest_runtest_teardown: Test teardown
