2025-12-23 15:26:18,107 - INFO - ya.test.712425  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:26:18,108 - INFO - ya.test.712425  - pytest_runtest_setup: test_all_supported_compression[zstd_4_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 4]
2025-12-23 15:26:18,109 - INFO - ya.test.712425  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:26:18,109 - INFO - ya.test.712425  - pytest_runtest_setup: Test setup
2025-12-23 15:26:18,112 - DEBUG - ya.test.712425  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-23 15:26:18,113 - DEBUG - ya.test.712425  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-23 15:26:18,113 - DEBUG - ya.test.712425  - execute: Executing '['/home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 15:26:18,125 - DEBUG - ya.test.712425  - execute: Command pid: 716585
2025-12-23 15:26:19,183 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) rc: 0
2025-12-23 15:26:19,184 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) elapsed time (sec): 1.0356671810150146
2025-12-23 15:26:19,186 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) idrss: 0
2025-12-23 15:26:19,189 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) inblock: 0
2025-12-23 15:26:19,195 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) isrss: 0
2025-12-23 15:26:19,203 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) ixrss: 0
2025-12-23 15:26:19,204 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) majflt: 0
2025-12-23 15:26:19,206 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) maxrss: 496420
2025-12-23 15:26:19,207 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) minflt: 45800
2025-12-23 15:26:19,207 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) msgrcv: 0
2025-12-23 15:26:19,207 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) msgsnd: 0
2025-12-23 15:26:19,208 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) nivcsw: 233
2025-12-23 15:26:19,212 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) nsignals: 0
2025-12-23 15:26:19,212 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) nswap: 0
2025-12-23 15:26:19,213 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) nvcsw: 14
2025-12-23 15:26:19,213 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) oublock: 8
2025-12-23 15:26:19,213 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) stime: 0.265648
2025-12-23 15:26:19,214 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) utime: 0.475491
2025-12-23 15:26:19,214 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) wtime: 1.069
2025-12-23 15:26:19,214 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) output:
Git info:
    Commit: 3721e81110a4ca0f02ed59c4d99ef3713666bc76
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge ce87dd182c77f9bcd7fcbbd7a5787626f73db5bf into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-hclevy2dda    Host information: 
        Linux ghrun-hclevy2dda 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-23 15:26:19,216 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 716585) errors:

2025-12-23 15:26:19,217 - INFO - __tests__.base.712425  - _setup_ydb: Git info:
    Commit: 3721e81110a4ca0f02ed59c4d99ef3713666bc76
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge ce87dd182c77f9bcd7fcbbd7a5787626f73db5bf into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-hclevy2dda    Host information: 
        Linux ghrun-hclevy2dda 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-23 15:26:19,401 - DEBUG - ya.test.712425  - get_binary: Binary was found by /home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd
2025-12-23 15:26:19,402 - DEBUG - library.python.filelock.712425  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63596
2025-12-23 15:26:19,405 - DEBUG - ydb.tests.library.harness.kikimr_runner.712425  - start: Working directory: /home/runner/.ya/build/build_root/p2vv/001b31/r3tmp/kikimr_cluster_h9fgvm1e
2025-12-23 15:26:19,449 - DEBUG - ya.test.712425  - get_binary: Binary was found by /home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd
2025-12-23 15:26:19,450 - DEBUG - library.python.filelock.712425  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5471
2025-12-23 15:26:19,450 - DEBUG - library.python.filelock.712425  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31409
2025-12-23 15:26:19,451 - DEBUG - library.python.filelock.712425  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2793
2025-12-23 15:26:19,452 - DEBUG - library.python.filelock.712425  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11616
2025-12-23 15:26:19,454 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/p2vv/001b31/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs"
2025-12-23 15:26:19,455 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - __make_run_command: Final command: /home/runner/.ya/build/build_root/p2vv/001b31/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/p2vv/001b31/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_aq5eu9qg.log --grpc-port=5471 --mon-port=31409 --ic-port=63596
2025-12-23 15:26:19,456 - DEBUG - ydb.tests.library.harness.kikimr_runner.712425  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:5471/1, disk_size 68719476736
2025-12-23 15:26:19,456 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/p2vv/001b31/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs"
2025-12-23 15:26:19,457 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - __make_run_command: Final command: /home/runner/.ya/build/build_root/p2vv/001b31/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/p2vv/001b31/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_aq5eu9qg.log --grpc-port=5471 --mon-port=31409 --ic-port=63596
2025-12-23 15:26:19,458 - DEBUG - ya.test.712425  - execute: Executing '('/home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/p2vv/001b31/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/p2vv/001b31/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_aq5eu9qg.log', '--grpc-port=5471', '--mon-port=31409', '--ic-port=63596')' in '/home/runner/.ya/build/build_root/p2vv/001b31/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1' (no wait)
2025-12-23 15:26:19,476 - DEBUG - ya.test.712425  - execute: Command pid: 718114
2025-12-23 15:26:19,477 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - start: Started node localhost:5471/1
2025-12-23 15:26:19,479 - DEBUG - ya.test.712425  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-23 15:26:19,480 - DEBUG - ya.test.712425  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-23 15:26:19,480 - DEBUG - ya.test.712425  - execute: Executing '['/home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 15:26:19,507 - DEBUG - ya.test.712425  - execute: Command pid: 718146
2025-12-23 15:26:20,480 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) rc: 0
2025-12-23 15:26:20,481 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) elapsed time (sec): 0.9697082042694092
2025-12-23 15:26:20,481 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) idrss: 0
2025-12-23 15:26:20,482 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) inblock: 0
2025-12-23 15:26:20,482 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) isrss: 0
2025-12-23 15:26:20,483 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) ixrss: 0
2025-12-23 15:26:20,483 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) majflt: 0
2025-12-23 15:26:20,484 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) maxrss: 501120
2025-12-23 15:26:20,484 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) minflt: 45785
2025-12-23 15:26:20,485 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) msgrcv: 0
2025-12-23 15:26:20,486 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) msgsnd: 0
2025-12-23 15:26:20,487 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) nivcsw: 173
2025-12-23 15:26:20,487 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) nsignals: 0
2025-12-23 15:26:20,489 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) nswap: 0
2025-12-23 15:26:20,489 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) nvcsw: 14
2025-12-23 15:26:20,490 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) oublock: 8
2025-12-23 15:26:20,490 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) stime: 0.21148899999999998
2025-12-23 15:26:20,490 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) utime: 0.537216
2025-12-23 15:26:20,491 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) wtime: 0.999
2025-12-23 15:26:20,495 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) output:
Git info:
    Commit: 3721e81110a4ca0f02ed59c4d99ef3713666bc76
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge ce87dd182c77f9bcd7fcbbd7a5787626f73db5bf into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-hclevy2dda    Host information: 
        Linux ghrun-hclevy2dda 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-12-23 15:26:20,496 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 718146) errors:

2025-12-23 15:26:20,500 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - start: Node 1 version:
Git info:
    Commit: 3721e81110a4ca0f02ed59c4d99ef3713666bc76
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge ce87dd182c77f9bcd7fcbbd7a5787626f73db5bf into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-hclevy2dda    Host information: 
        Linux ghrun-hclevy2dda 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-23 15:26:25,539 - DEBUG - ya.test.712425  - get_binary: Binary was found by /home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd
2025-12-23 15:26:25,540 - DEBUG - ydb.tests.library.harness.kikimr_runner.712425  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5471', '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: 63596\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-23 15:26:25,542 - DEBUG - ya.test.712425  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-23 15:26:25,542 - DEBUG - ya.test.712425  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-23 15:26:25,543 - DEBUG - ya.test.712425  - execute: Executing '['/home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5471', '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: 63596\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 15:26:25,544 - DEBUG - ya.test.712425  - execute: Command pid: 723561
2025-12-23 15:26:26,408 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) rc: 0
2025-12-23 15:26:26,408 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) elapsed time (sec): 0.8623051643371582
2025-12-23 15:26:26,409 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) idrss: 0
2025-12-23 15:26:26,409 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) inblock: 0
2025-12-23 15:26:26,409 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) isrss: 0
2025-12-23 15:26:26,410 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) ixrss: 0
2025-12-23 15:26:26,410 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) majflt: 1
2025-12-23 15:26:26,410 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) maxrss: 596660
2025-12-23 15:26:26,411 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) minflt: 52202
2025-12-23 15:26:26,412 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) msgrcv: 0
2025-12-23 15:26:26,412 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) msgsnd: 0
2025-12-23 15:26:26,413 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) nivcsw: 115
2025-12-23 15:26:26,413 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) nsignals: 0
2025-12-23 15:26:26,413 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) nswap: 0
2025-12-23 15:26:26,414 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) nvcsw: 796
2025-12-23 15:26:26,414 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) oublock: 8
2025-12-23 15:26:26,414 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) stime: 0.309621
2025-12-23 15:26:26,415 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) utime: 0.484031
2025-12-23 15:26:26,415 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) wtime: 0.864
2025-12-23 15:26:26,431 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-23 15:26:26,432 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 723561) errors:

2025-12-23 15:26:26,433 - DEBUG - ya.test.712425  - get_binary: Binary was found by /home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd
2025-12-23 15:26:26,433 - DEBUG - ydb.tests.library.harness.kikimr_runner.712425  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5471', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-23 15:26:26,435 - DEBUG - ya.test.712425  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-23 15:26:26,446 - DEBUG - ya.test.712425  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-23 15:26:26,447 - DEBUG - ya.test.712425  - execute: Executing '['/home/runner/.ya/build/build_root/p2vv/001b31/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5471', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 15:26:26,455 - DEBUG - ya.test.712425  - execute: Command pid: 724766
2025-12-23 15:26:27,746 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) rc: 0
2025-12-23 15:26:27,747 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) elapsed time (sec): 1.2877588272094727
2025-12-23 15:26:27,747 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) idrss: 0
2025-12-23 15:26:27,747 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) inblock: 0
2025-12-23 15:26:27,747 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) isrss: 0
2025-12-23 15:26:27,748 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) ixrss: 0
2025-12-23 15:26:27,748 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) majflt: 0
2025-12-23 15:26:27,748 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) maxrss: 596660
2025-12-23 15:26:27,748 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) minflt: 52368
2025-12-23 15:26:27,749 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) msgrcv: 0
2025-12-23 15:26:27,749 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) msgsnd: 0
2025-12-23 15:26:27,749 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) nivcsw: 179
2025-12-23 15:26:27,749 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) nsignals: 0
2025-12-23 15:26:27,750 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) nswap: 0
2025-12-23 15:26:27,750 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) nvcsw: 787
2025-12-23 15:26:27,750 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) oublock: 8
2025-12-23 15:26:27,751 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) stime: 0.30321
2025-12-23 15:26:27,751 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) utime: 0.545674
2025-12-23 15:26:27,760 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) wtime: 1.299
2025-12-23 15:26:27,760 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-23 15:26:27,767 - DEBUG - ya.test.712425  - _save_outputs: Command (pid 724766) errors:

2025-12-23 15:26:27,768 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-23 15:26:27,990 - INFO - ydb.tests.library.harness.kikimr_runner.712425  - __run: Cluster started and initialized
2025-12-23 15:26:28,108 - DEBUG - ydb.connection.712425  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:26:28,121 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:26:28,122 - DEBUG - ydb.connection.712425  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:26:28,163 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:26:28,164 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ListEndpoints, 83679e8a-7009-4305-a93f-9ef3193aa889, localhost:5471): creating call state
2025-12-23 15:26:28,165 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ListEndpoints, 83679e8a-7009-4305-a93f-9ef3193aa889, localhost:5471): request = { database: "/Root" }
2025-12-23 15:26:28,177 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ListEndpoints, 83679e8a-7009-4305-a93f-9ef3193aa889, localhost:5471): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-hclevy2dda.auto.internal" port: 5471 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-23 15:26:28,178 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-hclevy2dda.auto.internal:5471, location 1, ssl: False>]>
2025-12-23 15:26:28,181 - DEBUG - ydb.connection.712425  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:26:28,227 - DEBUG - ydb.connection.712425  - close: Closing channel for endpoint localhost:5471
2025-12-23 15:26:28,228 - DEBUG - ydb.query.pool.712425  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-23 15:26:28,233 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(CreateSession, 4120badc-d7f5-41ac-b458-5a1b4c50bff0, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:28,234 - DEBUG - ydb.connection.712425  - _log_request: RpcState(CreateSession, 4120badc-d7f5-41ac-b458-5a1b4c50bff0, ghrun-hclevy2dda.auto.internal:5471): request = {  }
2025-12-23 15:26:28,241 - DEBUG - ydb.connection.712425  - _log_response: RpcState(CreateSession, 4120badc-d7f5-41ac-b458-5a1b4c50bff0, ghrun-hclevy2dda.auto.internal:5471): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" node_id: 1 }
2025-12-23 15:26:28,242 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(AttachSession, 697a31e4-d51b-47ff-bebf-cc4cf2e5d977, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:28,243 - DEBUG - ydb.connection.712425  - _log_request: RpcState(AttachSession, 697a31e4-d51b-47ff-bebf-cc4cf2e5d977, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" }
2025-12-23 15:26:28,255 - DEBUG - ydb.connection.712425  - _log_response: RpcState(AttachSession, 697a31e4-d51b-47ff-bebf-cc4cf2e5d977, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:28,262 - DEBUG - ydb.query.pool.712425  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:28,263 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, e47ed32c-3df4-43b6-a6be-84d623303e68, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:28,264 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, e47ed32c-3df4-43b6-a6be-84d623303e68, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:28,266 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, e47ed32c-3df4-43b6-a6be-84d623303e68, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:31,959 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:31,959 - INFO - __tests__.alter_compression.712425  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-23 15:26:31,960 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:31,961 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, d6266845-efce-4d3e-80c3-2a70c19f2e3c, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:31,962 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, d6266845-efce-4d3e-80c3-2a70c19f2e3c, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:31,964 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, d6266845-efce-4d3e-80c3-2a70c19f2e3c, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:33,239 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:33,241 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:33,242 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, d42f68fc-b7ec-49b4-82e1-6e34a8389b73, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:33,243 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, d42f68fc-b7ec-49b4-82e1-6e34a8389b73, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:33,244 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, d42f68fc-b7ec-49b4-82e1-6e34a8389b73, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:34,556 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:34,556 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:34,557 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, a4bbfa17-b23c-4021-ba29-953af0352416, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:34,557 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, a4bbfa17-b23c-4021-ba29-953af0352416, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:34,558 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, a4bbfa17-b23c-4021-ba29-953af0352416, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:35,644 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:35,644 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:35,645 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, a9188a39-7954-4e90-b0b5-5d443c01e5ef, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:35,646 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, a9188a39-7954-4e90-b0b5-5d443c01e5ef, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:35,650 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, a9188a39-7954-4e90-b0b5-5d443c01e5ef, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:37,489 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:37,489 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-12-23 15:26:37,490 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:37,490 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 659104d2-c76d-43a6-a1bf-629bcd84703c, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:37,491 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 659104d2-c76d-43a6-a1bf-629bcd84703c, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:37,492 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 659104d2-c76d-43a6-a1bf-629bcd84703c, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:38,745 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:38,745 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:38,746 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b3b1b963-4f41-4f21-8387-1ef7f6cb38a9, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:38,747 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b3b1b963-4f41-4f21-8387-1ef7f6cb38a9, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:38,748 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b3b1b963-4f41-4f21-8387-1ef7f6cb38a9, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:39,077 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:39,078 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:39,079 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 5748cfca-be17-448c-a696-e898bb899d3d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:39,081 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 5748cfca-be17-448c-a696-e898bb899d3d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:39,083 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 5748cfca-be17-448c-a696-e898bb899d3d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:39,660 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:39,660 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-12-23 15:26:39,661 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:39,661 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, bd4b9ea9-8bd1-45b7-905d-71310bd76bf4, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:39,662 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, bd4b9ea9-8bd1-45b7-905d-71310bd76bf4, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:39,672 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, bd4b9ea9-8bd1-45b7-905d-71310bd76bf4, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:40,773 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:40,773 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:40,774 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, f0c1402b-56fb-4955-847f-b77643dfb480, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:40,775 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, f0c1402b-56fb-4955-847f-b77643dfb480, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:40,777 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, f0c1402b-56fb-4955-847f-b77643dfb480, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:41,073 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:41,074 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:41,075 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 78101215-21d2-49c4-a437-0049f5996305, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:41,076 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 78101215-21d2-49c4-a437-0049f5996305, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:41,108 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 78101215-21d2-49c4-a437-0049f5996305, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:41,542 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:41,542 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-12-23 15:26:41,543 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:41,544 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 0abc4df6-6011-4b71-ba7f-75072b993652, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:41,545 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 0abc4df6-6011-4b71-ba7f-75072b993652, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:41,546 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 0abc4df6-6011-4b71-ba7f-75072b993652, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:42,797 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:42,797 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:42,798 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, f416bf87-9ed3-4fa4-9a72-4cd99577d679, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:42,799 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, f416bf87-9ed3-4fa4-9a72-4cd99577d679, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:42,800 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, f416bf87-9ed3-4fa4-9a72-4cd99577d679, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:43,167 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:43,168 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:43,169 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, ec5e85ad-f1b0-4e18-985c-262fe74dd8ef, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:43,170 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, ec5e85ad-f1b0-4e18-985c-262fe74dd8ef, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:43,173 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, ec5e85ad-f1b0-4e18-985c-262fe74dd8ef, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:43,707 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:43,708 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-12-23 15:26:43,708 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:43,709 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 48034e4e-82e7-47c0-9957-c0cec91b4f6c, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:43,710 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 48034e4e-82e7-47c0-9957-c0cec91b4f6c, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:43,712 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 48034e4e-82e7-47c0-9957-c0cec91b4f6c, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:44,896 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:44,897 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:44,898 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 61be6948-6df8-47d4-961a-b1f2dcd0bb4d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:44,899 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 61be6948-6df8-47d4-961a-b1f2dcd0bb4d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:44,900 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 61be6948-6df8-47d4-961a-b1f2dcd0bb4d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:45,200 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:45,201 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:45,201 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 63946c99-06f0-42b6-9fbb-f388ee6ec73a, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:45,202 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 63946c99-06f0-42b6-9fbb-f388ee6ec73a, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:45,204 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 63946c99-06f0-42b6-9fbb-f388ee6ec73a, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:45,785 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:45,786 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-12-23 15:26:45,786 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:45,787 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 3a241a82-53ea-4d95-9fa4-d25c284d64a6, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:45,788 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 3a241a82-53ea-4d95-9fa4-d25c284d64a6, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:45,790 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 3a241a82-53ea-4d95-9fa4-d25c284d64a6, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:47,014 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:47,015 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:47,019 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 73d09ce3-eb78-4fb5-9aec-cdf6dd371f45, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:47,022 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 73d09ce3-eb78-4fb5-9aec-cdf6dd371f45, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:47,024 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 73d09ce3-eb78-4fb5-9aec-cdf6dd371f45, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:47,385 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:47,386 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:47,386 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b2259260-4c4a-4fa2-9477-1bfcc88f85ae, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:47,387 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b2259260-4c4a-4fa2-9477-1bfcc88f85ae, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:47,394 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b2259260-4c4a-4fa2-9477-1bfcc88f85ae, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:48,008 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:48,008 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-12-23 15:26:48,009 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:48,010 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 5e879e51-ee49-4d1e-8c58-76157be10f59, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:48,011 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 5e879e51-ee49-4d1e-8c58-76157be10f59, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:48,025 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 5e879e51-ee49-4d1e-8c58-76157be10f59, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:49,233 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:49,234 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:49,235 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 15cf841a-8fcb-4b8e-a37b-75efb26f8e09, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:49,236 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 15cf841a-8fcb-4b8e-a37b-75efb26f8e09, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:49,237 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 15cf841a-8fcb-4b8e-a37b-75efb26f8e09, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:49,677 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:49,677 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:49,678 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 08015c38-744a-4b56-a575-04165561e609, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:49,679 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 08015c38-744a-4b56-a575-04165561e609, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:49,681 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 08015c38-744a-4b56-a575-04165561e609, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:50,537 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:50,538 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-12-23 15:26:50,543 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:50,544 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 5a5af65f-a39f-4961-a405-59cf516f416d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:50,545 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 5a5af65f-a39f-4961-a405-59cf516f416d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:50,553 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 5a5af65f-a39f-4961-a405-59cf516f416d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:51,968 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:51,969 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:51,970 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 3997b350-2bbd-426d-aad3-359725053303, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:51,980 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 3997b350-2bbd-426d-aad3-359725053303, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:51,981 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 3997b350-2bbd-426d-aad3-359725053303, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:52,251 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:52,252 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:52,253 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 9c6225e0-8fc4-4af2-86ed-3a1327c4312c, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:52,254 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 9c6225e0-8fc4-4af2-86ed-3a1327c4312c, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:52,256 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 9c6225e0-8fc4-4af2-86ed-3a1327c4312c, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:53,388 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:53,389 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-12-23 15:26:53,389 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:53,390 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8ff4a90c-5595-404e-894b-c6a3c4384457, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:53,391 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8ff4a90c-5595-404e-894b-c6a3c4384457, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:53,401 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8ff4a90c-5595-404e-894b-c6a3c4384457, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:54,857 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:54,857 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:54,858 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, e1c6a590-7cb9-4bfd-9ada-f4df40e7a6ac, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:54,859 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, e1c6a590-7cb9-4bfd-9ada-f4df40e7a6ac, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:54,861 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, e1c6a590-7cb9-4bfd-9ada-f4df40e7a6ac, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:55,637 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:55,638 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:55,639 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, ce173425-4c63-43ee-829d-6361f316735e, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:55,640 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, ce173425-4c63-43ee-829d-6361f316735e, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:55,644 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, ce173425-4c63-43ee-829d-6361f316735e, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:56,849 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:56,850 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-12-23 15:26:56,850 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:56,851 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8520d481-3f7f-4b4a-8f89-8bc20a14dea6, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:56,852 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8520d481-3f7f-4b4a-8f89-8bc20a14dea6, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:56,860 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8520d481-3f7f-4b4a-8f89-8bc20a14dea6, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:58,209 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:58,209 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:58,210 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b2bdea5b-b53f-4355-ae74-00d90cc3a72d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:58,211 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b2bdea5b-b53f-4355-ae74-00d90cc3a72d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:58,214 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b2bdea5b-b53f-4355-ae74-00d90cc3a72d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:58,657 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:58,658 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:58,659 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, cde2a6c4-66e9-49a2-9387-b7e4181dc5aa, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:58,660 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, cde2a6c4-66e9-49a2-9387-b7e4181dc5aa, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:58,662 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, cde2a6c4-66e9-49a2-9387-b7e4181dc5aa, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:26:59,491 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:59,492 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-12-23 15:26:59,493 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:26:59,493 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, ae0e9772-5e84-463c-8239-a7d2116d4f79, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:26:59,494 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, ae0e9772-5e84-463c-8239-a7d2116d4f79, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:26:59,496 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, ae0e9772-5e84-463c-8239-a7d2116d4f79, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:27:01,049 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:01,050 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:01,051 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 47b5c8ae-d388-4598-bb13-14e14eb5a6fa, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:27:01,052 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 47b5c8ae-d388-4598-bb13-14e14eb5a6fa, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:27:01,054 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 47b5c8ae-d388-4598-bb13-14e14eb5a6fa, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:27:01,445 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:01,446 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:01,447 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, ac688725-3fa0-40fb-a564-5cfdaa2fd87a, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:27:01,448 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, ac688725-3fa0-40fb-a564-5cfdaa2fd87a, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:27:01,450 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, ac688725-3fa0-40fb-a564-5cfdaa2fd87a, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:27:01,873 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:01,873 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:01,874 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 502d28b1-058a-435f-8011-edbee29ef8bb, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:27:01,875 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 502d28b1-058a-435f-8011-edbee29ef8bb, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:27:01,876 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 502d28b1-058a-435f-8011-edbee29ef8bb, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:27:07,205 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:17,259 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:17,260 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 98ee56dd-5dd9-4be2-a292-fda3ada8e2fe, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:27:17,261 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 98ee56dd-5dd9-4be2-a292-fda3ada8e2fe, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:27:17,263 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 98ee56dd-5dd9-4be2-a292-fda3ada8e2fe, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:27:22,012 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:22,093 - INFO - root.712425  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-23 15:27:22,094 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:22,095 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, f4853c25-7be3-4fc6-89d0-9e3c4b8b8175, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:27:22,096 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, f4853c25-7be3-4fc6-89d0-9e3c4b8b8175, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:27:22,097 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, f4853c25-7be3-4fc6-89d0-9e3c4b8b8175, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:27:26,316 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:36,358 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:36,359 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 2a6c64e3-b845-4271-8784-8f9c9c7a8b23, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:27:36,360 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 2a6c64e3-b845-4271-8784-8f9c9c7a8b23, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:27:36,362 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 2a6c64e3-b845-4271-8784-8f9c9c7a8b23, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:27:39,081 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:49,106 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:27:49,107 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 81fd761a-91c2-45ec-aaee-96ae2afc30e3, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:27:49,108 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 81fd761a-91c2-45ec-aaee-96ae2afc30e3, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:27:49,109 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 81fd761a-91c2-45ec-aaee-96ae2afc30e3, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:27:50,007 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:00,015 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:00,016 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 3fbd033e-1d5b-420b-8743-cfc5a569e415, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:00,017 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 3fbd033e-1d5b-420b-8743-cfc5a569e415, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:00,018 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 3fbd033e-1d5b-420b-8743-cfc5a569e415, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:01,004 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:01,009 - INFO - root.712425  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-23 15:28:01,010 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:01,010 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, ad8ec3a6-d494-4eea-99b7-4a7fbd10ab4b, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:01,011 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, ad8ec3a6-d494-4eea-99b7-4a7fbd10ab4b, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:01,015 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, ad8ec3a6-d494-4eea-99b7-4a7fbd10ab4b, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:01,344 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:01,347 - INFO - ya.test.712425  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_4_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 4])
2025-12-23 15:28:01,348 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:01,349 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8558c5c5-90c5-43fc-ab3d-6d6c8cb9ee3d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:01,350 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8558c5c5-90c5-43fc-ab3d-6d6c8cb9ee3d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 4)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:01,351 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8558c5c5-90c5-43fc-ab3d-6d6c8cb9ee3d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:04,697 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:04,698 - INFO - __tests__.alter_compression.712425  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_4_compression created
2025-12-23 15:28:04,698 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:04,699 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, cc28946e-6288-4f8b-9d61-3a9453b509e8, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:04,700 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, cc28946e-6288-4f8b-9d61-3a9453b509e8, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_4_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:04,703 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, cc28946e-6288-4f8b-9d61-3a9453b509e8, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:05,654 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:05,655 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:05,655 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 0629eebd-a05e-4605-8983-2c2a0a2d2070, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:05,656 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 0629eebd-a05e-4605-8983-2c2a0a2d2070, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:05,658 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 0629eebd-a05e-4605-8983-2c2a0a2d2070, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:07,067 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:07,067 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:07,068 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 1baff610-c5bd-474b-bb5e-db500e021ac1, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:07,069 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 1baff610-c5bd-474b-bb5e-db500e021ac1, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:07,070 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 1baff610-c5bd-474b-bb5e-db500e021ac1, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:07,883 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:07,884 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:07,885 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 666d2886-65fe-46a8-8425-e5248bed9bc6, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:07,886 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 666d2886-65fe-46a8-8425-e5248bed9bc6, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:07,887 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 666d2886-65fe-46a8-8425-e5248bed9bc6, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:09,717 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:09,717 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318960, 'BlobCount': 128}}
2025-12-23 15:28:09,718 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:09,719 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, c975c831-27bd-463e-b84c-38b4270e6fd8, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:09,721 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, c975c831-27bd-463e-b84c-38b4270e6fd8, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:09,722 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, c975c831-27bd-463e-b84c-38b4270e6fd8, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:11,106 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:11,107 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:11,107 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8a6e4378-fc93-4753-92ef-0e2d99435a87, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:11,108 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8a6e4378-fc93-4753-92ef-0e2d99435a87, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:11,109 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8a6e4378-fc93-4753-92ef-0e2d99435a87, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:11,437 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:11,438 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:11,439 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b714ad89-135f-4095-bb27-3bad9d043b95, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:11,439 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b714ad89-135f-4095-bb27-3bad9d043b95, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:11,441 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b714ad89-135f-4095-bb27-3bad9d043b95, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:11,877 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:11,877 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638256, 'BlobCount': 256}}
2025-12-23 15:28:11,878 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:11,879 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b0d70368-76f9-419a-8208-24fcd8b89d97, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:11,880 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b0d70368-76f9-419a-8208-24fcd8b89d97, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:11,881 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b0d70368-76f9-419a-8208-24fcd8b89d97, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:13,183 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:13,183 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:13,184 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8a4c59d9-577d-4204-9fdc-fdb1f76ad384, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:13,185 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8a4c59d9-577d-4204-9fdc-fdb1f76ad384, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:13,187 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8a4c59d9-577d-4204-9fdc-fdb1f76ad384, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:13,615 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:13,616 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:13,617 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, fcbeec5f-5906-4307-ac53-0f5fe3b5ada6, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:13,618 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, fcbeec5f-5906-4307-ac53-0f5fe3b5ada6, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:13,619 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, fcbeec5f-5906-4307-ac53-0f5fe3b5ada6, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:14,192 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:14,193 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957296, 'BlobCount': 384}}
2025-12-23 15:28:14,193 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:14,194 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, ce0f59f9-4e2e-4b87-ae7d-ba389cbc02de, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:14,195 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, ce0f59f9-4e2e-4b87-ae7d-ba389cbc02de, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:14,196 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, ce0f59f9-4e2e-4b87-ae7d-ba389cbc02de, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:15,783 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:15,784 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:15,785 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, d1f61720-3236-4c3a-9c2f-3d671d6c40a7, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:15,786 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, d1f61720-3236-4c3a-9c2f-3d671d6c40a7, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:15,788 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, d1f61720-3236-4c3a-9c2f-3d671d6c40a7, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:16,353 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:16,353 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:16,354 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b99f2ae4-4553-415c-baf3-1e6866d9db7d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:16,355 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b99f2ae4-4553-415c-baf3-1e6866d9db7d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:16,366 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b99f2ae4-4553-415c-baf3-1e6866d9db7d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:16,986 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:16,987 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276656, 'BlobCount': 512}}
2025-12-23 15:28:16,988 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:16,988 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 313516b7-1e31-411c-b9ef-904520631956, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:16,989 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 313516b7-1e31-411c-b9ef-904520631956, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:16,992 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 313516b7-1e31-411c-b9ef-904520631956, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:18,261 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:18,262 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:18,262 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 362cf87b-6d2b-4deb-bff7-1ce6d3c6309a, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:18,263 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 362cf87b-6d2b-4deb-bff7-1ce6d3c6309a, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:18,264 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 362cf87b-6d2b-4deb-bff7-1ce6d3c6309a, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:18,701 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:18,702 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:18,703 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 2934eb4e-ba26-42d4-82d1-e4ccc14312f4, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:18,704 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 2934eb4e-ba26-42d4-82d1-e4ccc14312f4, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:18,706 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 2934eb4e-ba26-42d4-82d1-e4ccc14312f4, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:19,304 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:19,305 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595616, 'BlobCount': 640}}
2025-12-23 15:28:19,305 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:19,306 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, c2271ad1-36b0-4380-a522-e26ed2ba78a9, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:19,308 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, c2271ad1-36b0-4380-a522-e26ed2ba78a9, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:19,310 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, c2271ad1-36b0-4380-a522-e26ed2ba78a9, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:20,655 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:28:20,656 - DEBUG - ydb.connection.712425  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:28:20,691 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:20,692 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:20,693 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 7929c103-88c7-4e03-846a-d4f438d3145e, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:20,700 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 7929c103-88c7-4e03-846a-d4f438d3145e, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:20,702 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 7929c103-88c7-4e03-846a-d4f438d3145e, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:20,695 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:28:20,708 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ListEndpoints, 0ecaee57-5faa-4ad7-9821-c7a66e404032, localhost:5471): creating call state
2025-12-23 15:28:20,709 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ListEndpoints, 0ecaee57-5faa-4ad7-9821-c7a66e404032, localhost:5471): request = { database: "/Root" }
2025-12-23 15:28:20,716 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ListEndpoints, 0ecaee57-5faa-4ad7-9821-c7a66e404032, localhost:5471): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-hclevy2dda.auto.internal" port: 5471 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-23 15:28:20,717 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.712425  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-hclevy2dda.auto.internal:5471, location 1, ssl: False>]>
2025-12-23 15:28:20,718 - DEBUG - ydb.connection.712425  - close: Closing channel for endpoint localhost:5471
2025-12-23 15:28:21,125 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:21,125 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:21,126 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, a83ee496-96c2-4ab3-baae-e26e5e295c34, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:21,127 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, a83ee496-96c2-4ab3-baae-e26e5e295c34, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:21,128 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, a83ee496-96c2-4ab3-baae-e26e5e295c34, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:21,772 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:21,773 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914800, 'BlobCount': 768}}
2025-12-23 15:28:21,773 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:21,774 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 65a02c25-f598-4294-9a29-09bec2f653c0, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:21,776 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 65a02c25-f598-4294-9a29-09bec2f653c0, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:21,778 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 65a02c25-f598-4294-9a29-09bec2f653c0, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:23,127 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:23,128 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:23,128 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 1cf1f32a-a880-4531-b574-c95cb6c10130, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:23,129 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 1cf1f32a-a880-4531-b574-c95cb6c10130, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:23,130 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 1cf1f32a-a880-4531-b574-c95cb6c10130, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:23,398 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:23,398 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:23,399 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 1a1f2f6e-b953-4874-b478-964d31270eb2, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:23,400 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 1a1f2f6e-b953-4874-b478-964d31270eb2, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:23,402 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 1a1f2f6e-b953-4874-b478-964d31270eb2, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:24,021 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:24,021 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233936, 'BlobCount': 896}}
2025-12-23 15:28:24,022 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:24,023 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, f66492aa-fd5b-46ee-948d-1d242ab75552, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:24,024 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, f66492aa-fd5b-46ee-948d-1d242ab75552, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:24,025 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, f66492aa-fd5b-46ee-948d-1d242ab75552, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:25,054 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:25,065 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:25,067 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, d299abe2-4cc0-431a-b0e4-a986255e5c56, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:25,068 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, d299abe2-4cc0-431a-b0e4-a986255e5c56, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:25,069 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, d299abe2-4cc0-431a-b0e4-a986255e5c56, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:25,292 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:25,293 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:25,294 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 06ac79c4-20ae-40a4-8d98-09f6513aa393, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:25,295 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 06ac79c4-20ae-40a4-8d98-09f6513aa393, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:25,297 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 06ac79c4-20ae-40a4-8d98-09f6513aa393, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:25,839 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:25,840 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553424, 'BlobCount': 1024}}
2025-12-23 15:28:25,841 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:25,841 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8ca36f6e-21b1-4ddb-903b-7a0849070979, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:25,842 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8ca36f6e-21b1-4ddb-903b-7a0849070979, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:25,844 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8ca36f6e-21b1-4ddb-903b-7a0849070979, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:26,951 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:26,952 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:26,953 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 58d6804b-e8f2-4fe6-b737-fa8a0e8f1f5d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:26,954 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 58d6804b-e8f2-4fe6-b737-fa8a0e8f1f5d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:26,955 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 58d6804b-e8f2-4fe6-b737-fa8a0e8f1f5d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:27,197 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:27,198 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:27,198 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 98acb515-b435-4d27-b19f-c2cf71279c7e, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:27,199 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 98acb515-b435-4d27-b19f-c2cf71279c7e, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:27,201 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 98acb515-b435-4d27-b19f-c2cf71279c7e, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:27,767 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:27,768 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872432, 'BlobCount': 1152}}
2025-12-23 15:28:27,769 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:27,769 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8d2b494c-6526-4205-b789-c71467d6ff07, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:27,770 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8d2b494c-6526-4205-b789-c71467d6ff07, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:27,772 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8d2b494c-6526-4205-b789-c71467d6ff07, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:28,807 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:28,808 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:28,808 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 24a4cfd8-1f83-47a3-b30b-ca0fb4bc649d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:28,809 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 24a4cfd8-1f83-47a3-b30b-ca0fb4bc649d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:28,811 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 24a4cfd8-1f83-47a3-b30b-ca0fb4bc649d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:29,028 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:29,029 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:29,030 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, e5103a54-2c00-43bb-bc6b-68da02ffa2ce, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:29,031 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, e5103a54-2c00-43bb-bc6b-68da02ffa2ce, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:29,032 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, e5103a54-2c00-43bb-bc6b-68da02ffa2ce, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:29,644 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:29,644 - INFO - __tests__.alter_compression.712425  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191936, 'BlobCount': 1280}}
2025-12-23 15:28:29,645 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:29,645 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 06b1968f-a35b-4204-b392-31cb131278e7, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:29,646 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 06b1968f-a35b-4204-b392-31cb131278e7, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_4_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:29,648 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 06b1968f-a35b-4204-b392-31cb131278e7, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:30,645 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:30,646 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:30,647 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, dae64862-77e1-4a5b-a4a6-8715b7579145, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:30,647 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, dae64862-77e1-4a5b-a4a6-8715b7579145, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:30,649 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, dae64862-77e1-4a5b-a4a6-8715b7579145, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:31,104 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:31,105 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:31,106 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, a595730d-74ee-44ab-9157-745e91b6873c, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:31,107 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, a595730d-74ee-44ab-9157-745e91b6873c, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:31,111 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, a595730d-74ee-44ab-9157-745e91b6873c, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:31,440 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:31,441 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:31,441 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, d27cbfd0-1773-4d67-9e53-4dcbed2e3109, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:31,442 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, d27cbfd0-1773-4d67-9e53-4dcbed2e3109, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:31,449 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, d27cbfd0-1773-4d67-9e53-4dcbed2e3109, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:35,104 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:45,143 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:45,144 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 165740ed-6fc3-45c8-a130-deb8d0be425d, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:45,153 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 165740ed-6fc3-45c8-a130-deb8d0be425d, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:45,155 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 165740ed-6fc3-45c8-a130-deb8d0be425d, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:48,728 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:48,771 - INFO - root.712425  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_4_compression`, volumes `value` (8000000, 1595968)
2025-12-23 15:28:48,772 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:48,773 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 7853017e-6c58-427a-91a5-1c52f2a30701, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:48,775 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 7853017e-6c58-427a-91a5-1c52f2a30701, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:48,776 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 7853017e-6c58-427a-91a5-1c52f2a30701, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:49,159 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:49,159 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:28:49,160 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 1fdd09e1-197f-4e65-90fc-691d3ab2b87f, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:28:49,161 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 1fdd09e1-197f-4e65-90fc-691d3ab2b87f, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:28:49,163 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 1fdd09e1-197f-4e65-90fc-691d3ab2b87f, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:28:52,359 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:02,407 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:02,418 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 9444fbe4-5348-441a-9e3e-8bb2af7b9e4b, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:02,421 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 9444fbe4-5348-441a-9e3e-8bb2af7b9e4b, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:02,426 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 9444fbe4-5348-441a-9e3e-8bb2af7b9e4b, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:05,539 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:15,587 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:15,588 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, 8361c01c-b2e6-4441-9d68-5492944b2258, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:15,589 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, 8361c01c-b2e6-4441-9d68-5492944b2258, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:15,596 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, 8361c01c-b2e6-4441-9d68-5492944b2258, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:16,347 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:26,351 - DEBUG - ydb.query.pool.712425  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:26,352 - DEBUG - ydb.connection.712425  - _prepare_call: RpcState(ExecuteQuery, b7131304-3ce2-4fcf-a371-48e4f7266a8f, ghrun-hclevy2dda.auto.internal:5471): creating call state
2025-12-23 15:29:26,353 - DEBUG - ydb.connection.712425  - _log_request: RpcState(ExecuteQuery, b7131304-3ce2-4fcf-a371-48e4f7266a8f, ghrun-hclevy2dda.auto.internal:5471): request = { session_id: "ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:29:26,355 - DEBUG - ydb.connection.712425  - _log_response: RpcState(ExecuteQuery, b7131304-3ce2-4fcf-a371-48e4f7266a8f, ghrun-hclevy2dda.auto.internal:5471): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:29:27,087 - DEBUG - ydb.query.pool.712425  - release: Session returned to queue: ydb://session/3?node_id=1&id=MjE4NWExZjYtNmIzOWFhM2QtM2E1YjExMjctYTBmNmNkYTU=
2025-12-23 15:29:27,091 - INFO - root.712425  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_4_compression`, volumes `value` (8000000, 1466456)
2025-12-23 15:29:27,093 - INFO - root.712425  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_4_compression` 8092160 / 1466456: 5.518174428690666
2025-12-23 15:29:27,108 - INFO - ya.test.712425  - pytest_runtest_teardown: Test teardown
