2025-11-06 10:13:39,641 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:13:39,642 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-11-06 10:13:39,642 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:13:39,643 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-06 10:13:39,645 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-06 10:13:39,646 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-06 10:13:39,647 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:13:39,655 - DEBUG - ya.test - execute: Command pid: 487395
2025-11-06 10:13:40,558 - DEBUG - ya.test - _save_outputs: Command (pid 487395) rc: 0
2025-11-06 10:13:40,559 - DEBUG - ya.test - _save_outputs: Command (pid 487395) elapsed time (sec): 0.9005568027496338
2025-11-06 10:13:40,559 - DEBUG - ya.test - _save_outputs: Command (pid 487395) idrss: 0
2025-11-06 10:13:40,559 - DEBUG - ya.test - _save_outputs: Command (pid 487395) inblock: 0
2025-11-06 10:13:40,560 - DEBUG - ya.test - _save_outputs: Command (pid 487395) isrss: 0
2025-11-06 10:13:40,560 - DEBUG - ya.test - _save_outputs: Command (pid 487395) ixrss: 0
2025-11-06 10:13:40,561 - DEBUG - ya.test - _save_outputs: Command (pid 487395) majflt: 0
2025-11-06 10:13:40,561 - DEBUG - ya.test - _save_outputs: Command (pid 487395) maxrss: 494056
2025-11-06 10:13:40,561 - DEBUG - ya.test - _save_outputs: Command (pid 487395) minflt: 45438
2025-11-06 10:13:40,562 - DEBUG - ya.test - _save_outputs: Command (pid 487395) msgrcv: 0
2025-11-06 10:13:40,562 - DEBUG - ya.test - _save_outputs: Command (pid 487395) msgsnd: 0
2025-11-06 10:13:40,562 - DEBUG - ya.test - _save_outputs: Command (pid 487395) nivcsw: 114
2025-11-06 10:13:40,563 - DEBUG - ya.test - _save_outputs: Command (pid 487395) nsignals: 0
2025-11-06 10:13:40,563 - DEBUG - ya.test - _save_outputs: Command (pid 487395) nswap: 0
2025-11-06 10:13:40,563 - DEBUG - ya.test - _save_outputs: Command (pid 487395) nvcsw: 14
2025-11-06 10:13:40,564 - DEBUG - ya.test - _save_outputs: Command (pid 487395) oublock: 8
2025-11-06 10:13:40,564 - DEBUG - ya.test - _save_outputs: Command (pid 487395) stime: 0.187798
2025-11-06 10:13:40,564 - DEBUG - ya.test - _save_outputs: Command (pid 487395) utime: 0.539504
2025-11-06 10:13:40,564 - DEBUG - ya.test - _save_outputs: Command (pid 487395) wtime: 0.91
2025-11-06 10:13:40,565 - DEBUG - ya.test - _save_outputs: Command (pid 487395) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ew6xquenpy
    Host information: 
        Linux ghrun-ew6xquenpy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-06 10:13:40,565 - DEBUG - ya.test - _save_outputs: Command (pid 487395) errors:

2025-11-06 10:13:40,566 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ew6xquenpy
    Host information: 
        Linux ghrun-ew6xquenpy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-06 10:13:40,717 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd
2025-11-06 10:13:40,719 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/65346
2025-11-06 10:13:40,721 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/2dji/004541/r3tmp/kikimr_cluster_5t1zihr5
2025-11-06 10:13:40,762 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd
2025-11-06 10:13:40,762 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29812
2025-11-06 10:13:40,763 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6851
2025-11-06 10:13:40,763 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14092
2025-11-06 10:13:40,764 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30965
2025-11-06 10:13:40,765 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/2dji/004541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-11-06 10:13:40,765 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/2dji/004541/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/2dji/004541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_y4_rorkj.log --grpc-port=29812 --mon-port=6851 --ic-port=65346
2025-11-06 10:13:40,766 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:29812/1, disk_size 68719476736
2025-11-06 10:13:40,766 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/2dji/004541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-11-06 10:13:40,766 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/2dji/004541/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/2dji/004541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_y4_rorkj.log --grpc-port=29812 --mon-port=6851 --ic-port=65346
2025-11-06 10:13:40,768 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/2dji/004541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/2dji/004541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_y4_rorkj.log', '--grpc-port=29812', '--mon-port=6851', '--ic-port=65346')' in '/home/runner/.ya/build/build_root/2dji/004541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1' (no wait)
2025-11-06 10:13:40,781 - DEBUG - ya.test - execute: Command pid: 488129
2025-11-06 10:13:40,792 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:29812/1
2025-11-06 10:13:40,793 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-06 10:13:40,794 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-06 10:13:40,794 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:13:40,815 - DEBUG - ya.test - execute: Command pid: 488138
2025-11-06 10:13:41,689 - DEBUG - ya.test - _save_outputs: Command (pid 488138) rc: 0
2025-11-06 10:13:41,690 - DEBUG - ya.test - _save_outputs: Command (pid 488138) elapsed time (sec): 0.8730275630950928
2025-11-06 10:13:41,690 - DEBUG - ya.test - _save_outputs: Command (pid 488138) idrss: 0
2025-11-06 10:13:41,691 - DEBUG - ya.test - _save_outputs: Command (pid 488138) inblock: 0
2025-11-06 10:13:41,691 - DEBUG - ya.test - _save_outputs: Command (pid 488138) isrss: 0
2025-11-06 10:13:41,691 - DEBUG - ya.test - _save_outputs: Command (pid 488138) ixrss: 0
2025-11-06 10:13:41,691 - DEBUG - ya.test - _save_outputs: Command (pid 488138) majflt: 0
2025-11-06 10:13:41,692 - DEBUG - ya.test - _save_outputs: Command (pid 488138) maxrss: 495760
2025-11-06 10:13:41,692 - DEBUG - ya.test - _save_outputs: Command (pid 488138) minflt: 45347
2025-11-06 10:13:41,692 - DEBUG - ya.test - _save_outputs: Command (pid 488138) msgrcv: 0
2025-11-06 10:13:41,692 - DEBUG - ya.test - _save_outputs: Command (pid 488138) msgsnd: 0
2025-11-06 10:13:41,693 - DEBUG - ya.test - _save_outputs: Command (pid 488138) nivcsw: 138
2025-11-06 10:13:41,693 - DEBUG - ya.test - _save_outputs: Command (pid 488138) nsignals: 0
2025-11-06 10:13:41,693 - DEBUG - ya.test - _save_outputs: Command (pid 488138) nswap: 0
2025-11-06 10:13:41,694 - DEBUG - ya.test - _save_outputs: Command (pid 488138) nvcsw: 14
2025-11-06 10:13:41,694 - DEBUG - ya.test - _save_outputs: Command (pid 488138) oublock: 8
2025-11-06 10:13:41,694 - DEBUG - ya.test - _save_outputs: Command (pid 488138) stime: 0.221852
2025-11-06 10:13:41,694 - DEBUG - ya.test - _save_outputs: Command (pid 488138) utime: 0.5124529999999999
2025-11-06 10:13:41,703 - DEBUG - ya.test - _save_outputs: Command (pid 488138) wtime: 0.886
2025-11-06 10:13:41,703 - DEBUG - ya.test - _save_outputs: Command (pid 488138) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ew6xquenpy
    Host information: 
        Linux ghrun-ew6xquenpy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-11-06 10:13:41,704 - DEBUG - ya.test - _save_outputs: Command (pid 488138) errors:

2025-11-06 10:13:41,704 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3
2025-11-06 10:13:46,840 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd
2025-11-06 10:13:46,841 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29812', '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: 65346\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-06 10:13:46,842 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-06 10:13:46,843 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-06 10:13:46,843 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29812', '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: 65346\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-06 10:13:46,849 - DEBUG - ya.test - execute: Command pid: 491239
2025-11-06 10:13:48,017 - DEBUG - ya.test - _save_outputs: Command (pid 491239) rc: 0
2025-11-06 10:13:48,018 - DEBUG - ya.test - _save_outputs: Command (pid 491239) elapsed time (sec): 1.16794753074646
2025-11-06 10:13:48,018 - DEBUG - ya.test - _save_outputs: Command (pid 491239) idrss: 0
2025-11-06 10:13:48,018 - DEBUG - ya.test - _save_outputs: Command (pid 491239) inblock: 0
2025-11-06 10:13:48,018 - DEBUG - ya.test - _save_outputs: Command (pid 491239) isrss: 0
2025-11-06 10:13:48,018 - DEBUG - ya.test - _save_outputs: Command (pid 491239) ixrss: 0
2025-11-06 10:13:48,019 - DEBUG - ya.test - _save_outputs: Command (pid 491239) majflt: 0
2025-11-06 10:13:48,019 - DEBUG - ya.test - _save_outputs: Command (pid 491239) maxrss: 591760
2025-11-06 10:13:48,019 - DEBUG - ya.test - _save_outputs: Command (pid 491239) minflt: 51696
2025-11-06 10:13:48,019 - DEBUG - ya.test - _save_outputs: Command (pid 491239) msgrcv: 0
2025-11-06 10:13:48,019 - DEBUG - ya.test - _save_outputs: Command (pid 491239) msgsnd: 0
2025-11-06 10:13:48,022 - DEBUG - ya.test - _save_outputs: Command (pid 491239) nivcsw: 191
2025-11-06 10:13:48,023 - DEBUG - ya.test - _save_outputs: Command (pid 491239) nsignals: 0
2025-11-06 10:13:48,023 - DEBUG - ya.test - _save_outputs: Command (pid 491239) nswap: 0
2025-11-06 10:13:48,023 - DEBUG - ya.test - _save_outputs: Command (pid 491239) nvcsw: 675
2025-11-06 10:13:48,023 - DEBUG - ya.test - _save_outputs: Command (pid 491239) oublock: 8
2025-11-06 10:13:48,024 - DEBUG - ya.test - _save_outputs: Command (pid 491239) stime: 0.328721
2025-11-06 10:13:48,024 - DEBUG - ya.test - _save_outputs: Command (pid 491239) utime: 0.565538
2025-11-06 10:13:48,024 - DEBUG - ya.test - _save_outputs: Command (pid 491239) wtime: 1.173
2025-11-06 10:13:48,025 - DEBUG - ya.test - _save_outputs: Command (pid 491239) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-06 10:13:48,025 - DEBUG - ya.test - _save_outputs: Command (pid 491239) errors:

2025-11-06 10:13:48,028 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd
2025-11-06 10:13:48,029 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29812', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-06 10:13:48,030 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-06 10:13:48,032 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-06 10:13:48,032 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/2dji/004541/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:29812', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-06 10:13:48,035 - DEBUG - ya.test - execute: Command pid: 491736
2025-11-06 10:13:49,006 - DEBUG - ya.test - _save_outputs: Command (pid 491736) rc: 0
2025-11-06 10:13:49,006 - DEBUG - ya.test - _save_outputs: Command (pid 491736) elapsed time (sec): 0.9699909687042236
2025-11-06 10:13:49,006 - DEBUG - ya.test - _save_outputs: Command (pid 491736) idrss: 0
2025-11-06 10:13:49,007 - DEBUG - ya.test - _save_outputs: Command (pid 491736) inblock: 0
2025-11-06 10:13:49,007 - DEBUG - ya.test - _save_outputs: Command (pid 491736) isrss: 0
2025-11-06 10:13:49,007 - DEBUG - ya.test - _save_outputs: Command (pid 491736) ixrss: 0
2025-11-06 10:13:49,008 - DEBUG - ya.test - _save_outputs: Command (pid 491736) majflt: 0
2025-11-06 10:13:49,008 - DEBUG - ya.test - _save_outputs: Command (pid 491736) maxrss: 591760
2025-11-06 10:13:49,008 - DEBUG - ya.test - _save_outputs: Command (pid 491736) minflt: 51711
2025-11-06 10:13:49,008 - DEBUG - ya.test - _save_outputs: Command (pid 491736) msgrcv: 0
2025-11-06 10:13:49,008 - DEBUG - ya.test - _save_outputs: Command (pid 491736) msgsnd: 0
2025-11-06 10:13:49,009 - DEBUG - ya.test - _save_outputs: Command (pid 491736) nivcsw: 164
2025-11-06 10:13:49,009 - DEBUG - ya.test - _save_outputs: Command (pid 491736) nsignals: 0
2025-11-06 10:13:49,009 - DEBUG - ya.test - _save_outputs: Command (pid 491736) nswap: 0
2025-11-06 10:13:49,009 - DEBUG - ya.test - _save_outputs: Command (pid 491736) nvcsw: 933
2025-11-06 10:13:49,010 - DEBUG - ya.test - _save_outputs: Command (pid 491736) oublock: 8
2025-11-06 10:13:49,010 - DEBUG - ya.test - _save_outputs: Command (pid 491736) stime: 0.252428
2025-11-06 10:13:49,010 - DEBUG - ya.test - _save_outputs: Command (pid 491736) utime: 0.522354
2025-11-06 10:13:49,010 - DEBUG - ya.test - _save_outputs: Command (pid 491736) wtime: 0.973
2025-11-06 10:13:49,011 - DEBUG - ya.test - _save_outputs: Command (pid 491736) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-06 10:13:49,011 - DEBUG - ya.test - _save_outputs: Command (pid 491736) errors:

2025-11-06 10:13:49,011 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-06 10:13:49,115 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-06 10:13:49,150 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-06 10:13:49,153 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:13:49,154 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-06 10:13:49,175 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-06 10:13:49,175 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, fc984b3f-4740-45e5-ae57-9701df3f0e40, localhost:29812): creating call state
2025-11-06 10:13:49,176 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, fc984b3f-4740-45e5-ae57-9701df3f0e40, localhost:29812): request = { database: "/Root" }
2025-11-06 10:13:49,186 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, fc984b3f-4740-45e5-ae57-9701df3f0e40, localhost:29812): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ew6xquenpy.auto.internal" port: 29812 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-06 10:13:49,187 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ew6xquenpy.auto.internal:29812, location 1, ssl: False>]>
2025-11-06 10:13:49,188 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-06 10:13:49,207 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:29812
2025-11-06 10:13:49,208 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-06 10:13:49,209 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, b845bf53-d22e-497d-ae1b-a9ea1237d6b2, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:49,209 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, b845bf53-d22e-497d-ae1b-a9ea1237d6b2, ghrun-ew6xquenpy.auto.internal:29812): request = {  }
2025-11-06 10:13:49,220 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, b845bf53-d22e-497d-ae1b-a9ea1237d6b2, ghrun-ew6xquenpy.auto.internal:29812): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" node_id: 1 }
2025-11-06 10:13:49,221 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, c4aee9d9-1c0b-4d12-8bf8-7bd7f81f5b7c, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:49,223 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, c4aee9d9-1c0b-4d12-8bf8-7bd7f81f5b7c, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" }
2025-11-06 10:13:49,225 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, c4aee9d9-1c0b-4d12-8bf8-7bd7f81f5b7c, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:49,230 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:49,231 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ff889c0d-c727-49e7-b96c-532dadf13c34, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:49,232 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ff889c0d-c727-49e7-b96c-532dadf13c34, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:49,234 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ff889c0d-c727-49e7-b96c-532dadf13c34, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:52,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:52,081 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-06 10:13:52,082 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:52,082 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2fad68f0-3253-44c2-bb40-9645e1819fb8, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:52,083 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2fad68f0-3253-44c2-bb40-9645e1819fb8, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:52,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2fad68f0-3253-44c2-bb40-9645e1819fb8, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:53,151 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:53,152 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:53,152 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e30b6e40-bd89-46bb-9813-45f0c17ef4a1, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:53,153 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e30b6e40-bd89-46bb-9813-45f0c17ef4a1, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:53,154 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e30b6e40-bd89-46bb-9813-45f0c17ef4a1, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:54,352 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:54,353 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:54,353 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 45b0a071-cdb0-49dc-893d-780dbafb17c4, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:54,354 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 45b0a071-cdb0-49dc-893d-780dbafb17c4, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:54,355 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 45b0a071-cdb0-49dc-893d-780dbafb17c4, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:55,397 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:55,398 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:55,399 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8fbd83d-5b87-43e9-928d-0fb702e9c358, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:55,400 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8fbd83d-5b87-43e9-928d-0fb702e9c358, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:55,401 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8fbd83d-5b87-43e9-928d-0fb702e9c358, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:57,043 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:57,044 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-11-06 10:13:57,044 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:57,045 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fdee69f3-004f-4457-a870-0c089b177562, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:57,046 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fdee69f3-004f-4457-a870-0c089b177562, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:57,047 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fdee69f3-004f-4457-a870-0c089b177562, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:57,983 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:57,984 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:57,985 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d527e964-52d9-4b7b-b8c2-e649cb7b778c, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:57,986 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d527e964-52d9-4b7b-b8c2-e649cb7b778c, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:57,987 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d527e964-52d9-4b7b-b8c2-e649cb7b778c, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:58,362 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:58,363 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:58,364 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68fbc50a-bd3e-4fdc-bc58-3d9a962819b5, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:58,365 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68fbc50a-bd3e-4fdc-bc58-3d9a962819b5, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:58,375 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68fbc50a-bd3e-4fdc-bc58-3d9a962819b5, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:58,857 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:58,858 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-11-06 10:13:58,858 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:13:58,859 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd19f46b-0e17-4ddc-a7ad-c76d32eab9f4, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:13:58,860 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd19f46b-0e17-4ddc-a7ad-c76d32eab9f4, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:13:58,862 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd19f46b-0e17-4ddc-a7ad-c76d32eab9f4, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:00,040 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:00,041 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:00,041 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ae05393-c8c9-4741-a09e-46ad22f43953, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:00,042 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ae05393-c8c9-4741-a09e-46ad22f43953, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:00,044 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ae05393-c8c9-4741-a09e-46ad22f43953, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:00,448 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:00,449 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:00,449 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9be0fd29-a00d-498f-a793-9fd322477adf, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:00,450 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9be0fd29-a00d-498f-a793-9fd322477adf, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:00,451 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9be0fd29-a00d-498f-a793-9fd322477adf, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:00,989 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:00,990 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-11-06 10:14:00,991 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:00,992 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5e73d1bb-dadc-41ea-8f6c-dacf9f000dd8, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:00,992 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5e73d1bb-dadc-41ea-8f6c-dacf9f000dd8, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:00,994 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5e73d1bb-dadc-41ea-8f6c-dacf9f000dd8, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:02,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:02,025 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:02,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa7072d2-ab15-4b62-9287-507323591873, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:02,026 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa7072d2-ab15-4b62-9287-507323591873, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:02,027 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa7072d2-ab15-4b62-9287-507323591873, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:02,292 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:02,293 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:02,294 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9ccb046-6f9d-4007-be06-944f45c2876b, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:02,295 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9ccb046-6f9d-4007-be06-944f45c2876b, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:02,303 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9ccb046-6f9d-4007-be06-944f45c2876b, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:02,903 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:02,904 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-11-06 10:14:02,905 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:02,905 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 06263275-b5f1-4302-aef8-10b141be0ace, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:02,911 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 06263275-b5f1-4302-aef8-10b141be0ace, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:02,912 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 06263275-b5f1-4302-aef8-10b141be0ace, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:04,084 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:04,085 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:04,085 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c244b37-7209-4acc-b32d-af187e8bb088, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:04,086 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c244b37-7209-4acc-b32d-af187e8bb088, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:04,089 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c244b37-7209-4acc-b32d-af187e8bb088, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:04,438 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:04,439 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:04,440 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5900487a-7394-46ea-8351-d1f8a84c6f19, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:04,440 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5900487a-7394-46ea-8351-d1f8a84c6f19, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:04,444 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5900487a-7394-46ea-8351-d1f8a84c6f19, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:04,965 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:04,965 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-11-06 10:14:04,966 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:04,973 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 55dda4cd-f8d6-4938-b6d2-95ccfb748092, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:04,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 55dda4cd-f8d6-4938-b6d2-95ccfb748092, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:04,975 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 55dda4cd-f8d6-4938-b6d2-95ccfb748092, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:06,267 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:06,268 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:06,268 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a94b5f2c-429c-4846-a7f6-deb24d01aa4d, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:06,269 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a94b5f2c-429c-4846-a7f6-deb24d01aa4d, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:06,271 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a94b5f2c-429c-4846-a7f6-deb24d01aa4d, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:06,683 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:06,684 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:06,691 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c5262f0-20f5-4705-9136-b621ec788119, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:06,692 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c5262f0-20f5-4705-9136-b621ec788119, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:06,694 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c5262f0-20f5-4705-9136-b621ec788119, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:07,457 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:07,458 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-11-06 10:14:07,459 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:07,460 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c09cfb74-0517-4bbb-ad1d-9d21c6e19b39, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:07,461 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c09cfb74-0517-4bbb-ad1d-9d21c6e19b39, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:07,470 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c09cfb74-0517-4bbb-ad1d-9d21c6e19b39, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:08,631 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:08,631 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:08,632 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29aa435c-aa4e-4cf4-b2b4-2dd75cb02044, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:08,633 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29aa435c-aa4e-4cf4-b2b4-2dd75cb02044, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:08,638 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29aa435c-aa4e-4cf4-b2b4-2dd75cb02044, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:09,087 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:09,088 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:09,089 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c2325c4-4388-4a99-b467-fdb064626be5, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:09,090 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c2325c4-4388-4a99-b467-fdb064626be5, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:09,092 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c2325c4-4388-4a99-b467-fdb064626be5, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:09,855 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:09,856 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-11-06 10:14:09,860 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:09,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e3dffe8-6177-4576-b8b4-0cff490a26e2, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:09,862 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e3dffe8-6177-4576-b8b4-0cff490a26e2, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:09,866 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e3dffe8-6177-4576-b8b4-0cff490a26e2, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:10,960 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:10,961 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:10,962 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f94c9868-c98b-443d-97a2-ea04c59c6dbc, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:10,967 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f94c9868-c98b-443d-97a2-ea04c59c6dbc, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:10,969 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f94c9868-c98b-443d-97a2-ea04c59c6dbc, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:11,301 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:11,302 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:11,303 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 51c76e38-92c9-4995-9c97-56a1c7eee5bd, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:11,304 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 51c76e38-92c9-4995-9c97-56a1c7eee5bd, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:11,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 51c76e38-92c9-4995-9c97-56a1c7eee5bd, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:11,977 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:11,978 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-11-06 10:14:11,980 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:11,981 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bdf47b1-64e3-4060-b8d3-ecb021c1cc79, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:11,982 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bdf47b1-64e3-4060-b8d3-ecb021c1cc79, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:11,983 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bdf47b1-64e3-4060-b8d3-ecb021c1cc79, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:13,066 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:13,067 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:13,068 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7651b24-4bb9-4caf-ab19-83492114ea42, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:13,069 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7651b24-4bb9-4caf-ab19-83492114ea42, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:13,071 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7651b24-4bb9-4caf-ab19-83492114ea42, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:13,390 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:13,391 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:13,392 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ebcbcae-450c-4365-a295-a2b6a149efd6, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:13,393 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ebcbcae-450c-4365-a295-a2b6a149efd6, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:13,395 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ebcbcae-450c-4365-a295-a2b6a149efd6, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:14,258 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:14,259 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-11-06 10:14:14,260 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:14,260 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 84fd42e2-8437-46c2-96cf-f58c517cdde0, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:14,261 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 84fd42e2-8437-46c2-96cf-f58c517cdde0, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:14,268 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 84fd42e2-8437-46c2-96cf-f58c517cdde0, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:15,471 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:15,472 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:15,472 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af945eb1-0bde-46bb-b0b0-86c56af35a8c, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:15,473 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af945eb1-0bde-46bb-b0b0-86c56af35a8c, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:15,475 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af945eb1-0bde-46bb-b0b0-86c56af35a8c, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:15,792 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:15,793 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:15,793 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2fcee9d7-789a-4774-9286-1216493872b9, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:15,794 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2fcee9d7-789a-4774-9286-1216493872b9, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:15,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2fcee9d7-789a-4774-9286-1216493872b9, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:16,495 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:16,496 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-11-06 10:14:16,503 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:16,503 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c895b2a3-49c5-4e06-a12b-5877329ce520, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:16,504 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c895b2a3-49c5-4e06-a12b-5877329ce520, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:16,505 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c895b2a3-49c5-4e06-a12b-5877329ce520, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:17,495 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:17,496 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:17,496 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, becb6994-c8d3-43e3-b6e3-6a5a875d7ac1, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:17,497 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, becb6994-c8d3-43e3-b6e3-6a5a875d7ac1, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:17,499 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, becb6994-c8d3-43e3-b6e3-6a5a875d7ac1, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:17,833 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:17,833 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:17,834 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b06c5d6-6a6c-47b1-97e6-95c89ba28f51, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:17,835 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b06c5d6-6a6c-47b1-97e6-95c89ba28f51, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:17,836 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b06c5d6-6a6c-47b1-97e6-95c89ba28f51, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:18,155 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:18,156 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:18,157 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 65388064-0ab2-43bb-942b-35120b3352e5, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:18,158 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 65388064-0ab2-43bb-942b-35120b3352e5, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:18,159 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 65388064-0ab2-43bb-942b-35120b3352e5, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:22,235 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:32,294 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:32,295 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b25be342-0625-448b-a59a-0bd7739838a7, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:32,296 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b25be342-0625-448b-a59a-0bd7739838a7, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:32,297 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b25be342-0625-448b-a59a-0bd7739838a7, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:36,161 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:36,245 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-06 10:14:36,246 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:36,247 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef4600fc-078b-4017-aee2-5b2131d0e366, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:36,248 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef4600fc-078b-4017-aee2-5b2131d0e366, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:36,249 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef4600fc-078b-4017-aee2-5b2131d0e366, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:39,350 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:49,387 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:49,388 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1e937e91-b104-4631-9415-d0702ebedd36, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:49,388 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1e937e91-b104-4631-9415-d0702ebedd36, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:49,390 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1e937e91-b104-4631-9415-d0702ebedd36, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:53,571 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:53,614 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-06 10:14:53,615 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:53,616 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 144ce0c2-c6d2-4139-be7a-7ccf7697f31b, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:53,617 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 144ce0c2-c6d2-4139-be7a-7ccf7697f31b, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:53,618 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 144ce0c2-c6d2-4139-be7a-7ccf7697f31b, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:54,016 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:54,019 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"])
2025-11-06 10:14:54,020 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:54,021 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e60b28ac-264e-4c45-b38c-d37ffe130c4d, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:54,022 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e60b28ac-264e-4c45-b38c-d37ffe130c4d, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/lz4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"lz4\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:54,023 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e60b28ac-264e-4c45-b38c-d37ffe130c4d, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:57,504 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:57,504 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-11-06 10:14:57,505 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:57,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2630ed69-e2d0-43bc-b671-82116338cb39, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:57,506 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2630ed69-e2d0-43bc-b671-82116338cb39, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:57,507 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2630ed69-e2d0-43bc-b671-82116338cb39, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:58,483 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:58,484 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:58,485 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fe3c08c4-5333-415b-aaa9-ba25e7506252, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:58,486 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fe3c08c4-5333-415b-aaa9-ba25e7506252, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:58,487 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fe3c08c4-5333-415b-aaa9-ba25e7506252, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:59,816 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:59,816 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:14:59,817 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29cc9788-6fc1-4aee-8db2-ddea933e7d56, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:14:59,818 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29cc9788-6fc1-4aee-8db2-ddea933e7d56, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:14:59,819 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29cc9788-6fc1-4aee-8db2-ddea933e7d56, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:00,845 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:00,846 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:00,847 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44501963-2763-40f2-9d51-0762296840c5, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:00,848 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44501963-2763-40f2-9d51-0762296840c5, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:00,850 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44501963-2763-40f2-9d51-0762296840c5, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:03,023 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:03,024 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 872992, 'BlobCount': 128}}
2025-11-06 10:15:03,025 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:03,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cfd5ac52-e323-4ac1-b746-499f9fd846e1, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:03,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cfd5ac52-e323-4ac1-b746-499f9fd846e1, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:03,036 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cfd5ac52-e323-4ac1-b746-499f9fd846e1, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:04,109 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:04,110 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:04,111 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7a45828a-0f03-4d39-8c7f-4e8dd5aabd9e, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:04,112 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7a45828a-0f03-4d39-8c7f-4e8dd5aabd9e, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:04,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7a45828a-0f03-4d39-8c7f-4e8dd5aabd9e, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:04,394 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:04,395 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:04,395 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 01e19a52-f5b6-4210-aa6a-8714d4387859, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:04,396 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 01e19a52-f5b6-4210-aa6a-8714d4387859, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:04,398 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 01e19a52-f5b6-4210-aa6a-8714d4387859, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:04,707 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:04,708 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 1747088, 'BlobCount': 256}}
2025-11-06 10:15:04,708 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:04,709 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c6490a03-a52f-44ae-9a2d-5cbbcf5227ce, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:04,710 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c6490a03-a52f-44ae-9a2d-5cbbcf5227ce, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:04,714 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c6490a03-a52f-44ae-9a2d-5cbbcf5227ce, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:05,779 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:05,779 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:05,780 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd4c5f35-2844-42e5-b89e-45f8df7b89ee, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:05,781 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd4c5f35-2844-42e5-b89e-45f8df7b89ee, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:05,782 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd4c5f35-2844-42e5-b89e-45f8df7b89ee, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:06,104 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:06,105 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:06,105 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3c6efbe3-1599-44dd-99ab-18cc05cdb529, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:06,106 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3c6efbe3-1599-44dd-99ab-18cc05cdb529, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:06,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3c6efbe3-1599-44dd-99ab-18cc05cdb529, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:06,519 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:06,520 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 2620992, 'BlobCount': 384}}
2025-11-06 10:15:06,520 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:06,521 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a5dcf52a-054f-4762-8e80-b345d386b894, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:06,522 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a5dcf52a-054f-4762-8e80-b345d386b894, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:06,523 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a5dcf52a-054f-4762-8e80-b345d386b894, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:07,396 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:07,396 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:07,397 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4f3ac05d-9ae7-41e4-b6a3-6538f35b649a, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:07,398 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4f3ac05d-9ae7-41e4-b6a3-6538f35b649a, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:07,399 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4f3ac05d-9ae7-41e4-b6a3-6538f35b649a, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:07,692 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:07,693 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:07,694 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 009720f0-64cd-4cd2-9bbe-191f7c5d1ffc, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:07,695 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 009720f0-64cd-4cd2-9bbe-191f7c5d1ffc, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:07,696 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 009720f0-64cd-4cd2-9bbe-191f7c5d1ffc, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:08,128 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:08,129 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 3495088, 'BlobCount': 512}}
2025-11-06 10:15:08,129 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:08,130 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4109e8c0-5f8f-4125-972b-7626aa6cda88, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:08,131 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4109e8c0-5f8f-4125-972b-7626aa6cda88, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:08,138 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4109e8c0-5f8f-4125-972b-7626aa6cda88, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:09,328 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:09,329 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:09,330 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dafed38b-6ae7-4c97-ad20-75851a02e65d, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:09,331 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dafed38b-6ae7-4c97-ad20-75851a02e65d, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:09,332 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dafed38b-6ae7-4c97-ad20-75851a02e65d, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:09,731 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:09,732 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:09,733 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b9f5240-a716-4fda-8fd1-b60f9e1c1ad9, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:09,734 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b9f5240-a716-4fda-8fd1-b60f9e1c1ad9, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:09,736 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b9f5240-a716-4fda-8fd1-b60f9e1c1ad9, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:10,255 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:10,256 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 4368976, 'BlobCount': 640}}
2025-11-06 10:15:10,256 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:10,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 836df64c-eb87-404d-bbfb-34aafa00fee9, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:10,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 836df64c-eb87-404d-bbfb-34aafa00fee9, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:10,260 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 836df64c-eb87-404d-bbfb-34aafa00fee9, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:11,568 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:11,569 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:11,570 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0d050cb-53cc-4907-9c9b-2ff4ced2134a, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:11,571 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0d050cb-53cc-4907-9c9b-2ff4ced2134a, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:11,572 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0d050cb-53cc-4907-9c9b-2ff4ced2134a, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:12,018 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:12,019 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:12,020 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e097c431-b952-43f3-a29f-612ffab0059a, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:12,021 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e097c431-b952-43f3-a29f-612ffab0059a, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:12,023 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e097c431-b952-43f3-a29f-612ffab0059a, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:12,707 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:12,708 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 5242960, 'BlobCount': 768}}
2025-11-06 10:15:12,708 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:12,709 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3e05375-97da-4bef-9bcc-61a597771015, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:12,710 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3e05375-97da-4bef-9bcc-61a597771015, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:12,712 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3e05375-97da-4bef-9bcc-61a597771015, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:14,031 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:14,032 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:14,033 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e4644760-63e4-4478-b821-9dacf87cba4c, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:14,033 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e4644760-63e4-4478-b821-9dacf87cba4c, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:14,035 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e4644760-63e4-4478-b821-9dacf87cba4c, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:14,544 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:14,545 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:14,546 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c713881-8226-42ce-add9-1791a93826d1, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:14,547 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c713881-8226-42ce-add9-1791a93826d1, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:14,548 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c713881-8226-42ce-add9-1791a93826d1, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:15,294 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:15,295 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 6117040, 'BlobCount': 896}}
2025-11-06 10:15:15,295 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:15,295 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 603ee1e8-4425-4808-8f04-ee701fffaa29, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:15,296 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 603ee1e8-4425-4808-8f04-ee701fffaa29, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:15,297 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 603ee1e8-4425-4808-8f04-ee701fffaa29, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:16,720 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:16,720 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:16,721 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76aa84c9-32bb-4329-ac5c-53b5417d24dc, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:16,722 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76aa84c9-32bb-4329-ac5c-53b5417d24dc, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:16,723 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76aa84c9-32bb-4329-ac5c-53b5417d24dc, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:17,047 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:17,048 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:17,048 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 206b5173-e365-40a0-9d7d-5a958ebf2cd4, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:17,050 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 206b5173-e365-40a0-9d7d-5a958ebf2cd4, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:17,056 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 206b5173-e365-40a0-9d7d-5a958ebf2cd4, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:17,807 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:17,808 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 6991184, 'BlobCount': 1024}}
2025-11-06 10:15:17,808 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:17,809 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4660df79-3520-4015-b7bf-1b551eb6177c, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:17,809 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4660df79-3520-4015-b7bf-1b551eb6177c, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:17,811 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4660df79-3520-4015-b7bf-1b551eb6177c, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:19,039 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:19,041 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:19,042 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8deccef9-6179-4682-b2d8-0fa0beb26a3a, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:19,043 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8deccef9-6179-4682-b2d8-0fa0beb26a3a, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:19,045 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8deccef9-6179-4682-b2d8-0fa0beb26a3a, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:19,439 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:19,440 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:19,441 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 452cd539-fb95-4300-9081-9e51d3830157, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:19,441 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 452cd539-fb95-4300-9081-9e51d3830157, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:19,443 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 452cd539-fb95-4300-9081-9e51d3830157, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:20,487 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:20,488 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 7865088, 'BlobCount': 1152}}
2025-11-06 10:15:20,489 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:20,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cfe9d0f4-25f0-4b34-9631-9715b8b5f9f3, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:20,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cfe9d0f4-25f0-4b34-9631-9715b8b5f9f3, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:20,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cfe9d0f4-25f0-4b34-9631-9715b8b5f9f3, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:21,758 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:21,758 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:21,759 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1768a76b-0533-4402-9a11-b34729e2b675, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:21,760 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1768a76b-0533-4402-9a11-b34729e2b675, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:21,761 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1768a76b-0533-4402-9a11-b34729e2b675, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:22,172 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:22,173 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:22,174 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 960dfba8-bea8-41a7-9d98-a74ea7a8040c, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:22,175 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 960dfba8-bea8-41a7-9d98-a74ea7a8040c, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:22,176 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 960dfba8-bea8-41a7-9d98-a74ea7a8040c, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:23,159 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:23,160 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 8739264, 'BlobCount': 1280}}
2025-11-06 10:15:23,160 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:23,161 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 62dff493-32c4-4fef-bd08-f820d4c2bc54, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:23,162 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 62dff493-32c4-4fef-bd08-f820d4c2bc54, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:23,168 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 62dff493-32c4-4fef-bd08-f820d4c2bc54, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:24,724 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:24,725 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:24,726 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f816775-6149-428c-bea5-e267498a3a32, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:24,727 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f816775-6149-428c-bea5-e267498a3a32, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:24,728 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f816775-6149-428c-bea5-e267498a3a32, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:25,148 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:25,149 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:25,150 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d6cce377-52a7-4709-902b-97a22ee67021, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:25,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d6cce377-52a7-4709-902b-97a22ee67021, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:25,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d6cce377-52a7-4709-902b-97a22ee67021, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:25,564 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:25,565 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:25,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d1a3bbd-c2cf-438b-896d-647a64c6b7e5, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:25,566 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d1a3bbd-c2cf-438b-896d-647a64c6b7e5, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:25,568 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d1a3bbd-c2cf-438b-896d-647a64c6b7e5, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:30,705 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:33,626 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:15:33,627 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-06 10:15:33,667 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-06 10:15:33,667 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 81bc4848-1491-4ecd-8e41-27177c725036, localhost:29812): creating call state
2025-11-06 10:15:33,668 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 81bc4848-1491-4ecd-8e41-27177c725036, localhost:29812): request = { database: "/Root" }
2025-11-06 10:15:33,676 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 81bc4848-1491-4ecd-8e41-27177c725036, localhost:29812): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ew6xquenpy.auto.internal" port: 29812 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-06 10:15:33,677 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ew6xquenpy.auto.internal:29812, location 1, ssl: False>]>
2025-11-06 10:15:33,678 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:29812
2025-11-06 10:15:40,799 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:40,800 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa87aff8-2ece-43ed-b29c-95349080a8ab, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:40,801 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa87aff8-2ece-43ed-b29c-95349080a8ab, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:40,802 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa87aff8-2ece-43ed-b29c-95349080a8ab, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:44,674 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:44,708 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4369632)
2025-11-06 10:15:44,709 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:44,709 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3f87841f-3043-49b1-a9af-1219f89ea17f, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:44,710 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3f87841f-3043-49b1-a9af-1219f89ea17f, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:44,711 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3f87841f-3043-49b1-a9af-1219f89ea17f, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:45,031 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:45,032 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:45,033 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a863cb17-cb2c-4fb5-8965-7bc31121711f, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:45,034 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a863cb17-cb2c-4fb5-8965-7bc31121711f, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:45,035 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a863cb17-cb2c-4fb5-8965-7bc31121711f, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:49,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:59,069 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:15:59,071 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 563a4f6c-84fb-4ed8-a8bb-030425f72cc5, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:15:59,071 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 563a4f6c-84fb-4ed8-a8bb-030425f72cc5, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:59,073 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 563a4f6c-84fb-4ed8-a8bb-030425f72cc5, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:02,159 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:16:12,183 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:16:12,184 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c283390b-2f0d-4039-92d4-0cdf99db1e75, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:16:12,184 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c283390b-2f0d-4039-92d4-0cdf99db1e75, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:12,186 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c283390b-2f0d-4039-92d4-0cdf99db1e75, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:12,853 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:16:22,857 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:16:22,858 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 281940f6-d7a5-4e5f-8576-e3fd145797c4, ghrun-ew6xquenpy.auto.internal:29812): creating call state
2025-11-06 10:16:22,859 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 281940f6-d7a5-4e5f-8576-e3fd145797c4, ghrun-ew6xquenpy.auto.internal:29812): request = { session_id: "ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:22,860 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 281940f6-d7a5-4e5f-8576-e3fd145797c4, ghrun-ew6xquenpy.auto.internal:29812): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:23,735 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWJkMTIyODUtY2UyZGVmOGUtNDMwNjE1NjEtNzM2YThmMDk=
2025-11-06 10:16:23,739 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-11-06 10:16:23,739 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8092160 / 4258592: 1.900196121159294
2025-11-06 10:16:23,741 - INFO - ya.test - pytest_runtest_teardown: Test teardown
