2025-12-23 14:49:08,177 - INFO - ya.test.50224  - pytest_runtest_setup: ####################################################################################################
2025-12-23 14:49:08,177 - INFO - ya.test.50224  - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-12-23 14:49:08,177 - INFO - ya.test.50224  - pytest_runtest_setup: ####################################################################################################
2025-12-23 14:49:08,178 - INFO - ya.test.50224  - pytest_runtest_setup: Test setup
2025-12-23 14:49:08,230 - DEBUG - ya.test.50224  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-23 14:49:08,251 - DEBUG - ya.test.50224  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-23 14:49:08,262 - DEBUG - ya.test.50224  - execute: Executing '['/home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 14:49:08,317 - DEBUG - ya.test.50224  - execute: Command pid: 51327
2025-12-23 14:49:17,364 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) rc: 0
2025-12-23 14:49:17,365 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) elapsed time (sec): 9.030923128128052
2025-12-23 14:49:17,365 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) idrss: 0
2025-12-23 14:49:17,365 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) inblock: 0
2025-12-23 14:49:17,366 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) isrss: 0
2025-12-23 14:49:17,366 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) ixrss: 0
2025-12-23 14:49:17,366 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) majflt: 0
2025-12-23 14:49:17,367 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) maxrss: 487716
2025-12-23 14:49:17,367 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) minflt: 45844
2025-12-23 14:49:17,367 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) msgrcv: 0
2025-12-23 14:49:17,367 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) msgsnd: 0
2025-12-23 14:49:17,368 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) nivcsw: 1181
2025-12-23 14:49:17,368 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) nsignals: 0
2025-12-23 14:49:17,368 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) nswap: 0
2025-12-23 14:49:17,369 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) nvcsw: 14
2025-12-23 14:49:17,369 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) oublock: 8
2025-12-23 14:49:17,369 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) stime: 3.7558439999999997
2025-12-23 14:49:17,370 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) utime: 0.49918799999999997
2025-12-23 14:49:17,370 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) wtime: 9.087
2025-12-23 14:49:17,370 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) output:
Git info:
    Commit: 3721e81110a4ca0f02ed59c4d99ef3713666bc76
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge ce87dd182c77f9bcd7fcbbd7a5787626f73db5bf into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-hclevy2dda    Host information: 
        Linux ghrun-hclevy2dda 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-23 14:49:17,371 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 51327) errors:

2025-12-23 14:49:17,371 - INFO - __tests__.base.50224  - _setup_ydb: Git info:
    Commit: 3721e81110a4ca0f02ed59c4d99ef3713666bc76
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge ce87dd182c77f9bcd7fcbbd7a5787626f73db5bf into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-hclevy2dda    Host information: 
        Linux ghrun-hclevy2dda 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-23 14:49:17,841 - DEBUG - ya.test.50224  - get_binary: Binary was found by /home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd
2025-12-23 14:49:17,843 - DEBUG - library.python.filelock.50224  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16707
2025-12-23 14:49:17,856 - DEBUG - ydb.tests.library.harness.kikimr_runner.50224  - start: Working directory: /home/runner/.ya/build/build_root/p2vv/001b1c/r3tmp/kikimr_cluster_etiowj1v
2025-12-23 14:49:18,138 - DEBUG - ya.test.50224  - get_binary: Binary was found by /home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd
2025-12-23 14:49:18,156 - DEBUG - library.python.filelock.50224  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1516
2025-12-23 14:49:18,159 - DEBUG - library.python.filelock.50224  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14810
2025-12-23 14:49:18,166 - DEBUG - library.python.filelock.50224  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61197
2025-12-23 14:49:18,172 - DEBUG - library.python.filelock.50224  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6079
2025-12-23 14:49:18,179 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/p2vv/001b1c/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-12-23 14:49:18,182 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - __make_run_command: Final command: /home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/p2vv/001b1c/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_r1_boakf.log --grpc-port=1516 --mon-port=14810 --ic-port=16707
2025-12-23 14:49:18,186 - DEBUG - ydb.tests.library.harness.kikimr_runner.50224  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:1516/1, disk_size 68719476736
2025-12-23 14:49:18,190 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/p2vv/001b1c/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-12-23 14:49:18,198 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - __make_run_command: Final command: /home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/p2vv/001b1c/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_r1_boakf.log --grpc-port=1516 --mon-port=14810 --ic-port=16707
2025-12-23 14:49:18,208 - DEBUG - ya.test.50224  - execute: Executing '('/home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/p2vv/001b1c/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/p2vv/001b1c/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_r1_boakf.log', '--grpc-port=1516', '--mon-port=14810', '--ic-port=16707')' in '/home/runner/.ya/build/build_root/p2vv/001b1c/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-12-23 14:49:18,258 - DEBUG - ya.test.50224  - execute: Command pid: 53049
2025-12-23 14:49:18,265 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - start: Started node localhost:1516/1
2025-12-23 14:49:18,276 - DEBUG - ya.test.50224  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-23 14:49:18,298 - DEBUG - ya.test.50224  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-23 14:49:18,312 - DEBUG - ya.test.50224  - execute: Executing '['/home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 14:49:18,339 - DEBUG - ya.test.50224  - execute: Command pid: 53053
2025-12-23 14:49:26,305 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) rc: 0
2025-12-23 14:49:26,318 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) elapsed time (sec): 7.953091859817505
2025-12-23 14:49:26,321 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) idrss: 0
2025-12-23 14:49:26,329 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) inblock: 0
2025-12-23 14:49:26,339 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) isrss: 0
2025-12-23 14:49:26,341 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) ixrss: 0
2025-12-23 14:49:26,354 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) majflt: 0
2025-12-23 14:49:26,371 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) maxrss: 487604
2025-12-23 14:49:26,373 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) minflt: 45778
2025-12-23 14:49:26,384 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) msgrcv: 0
2025-12-23 14:49:26,386 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) msgsnd: 0
2025-12-23 14:49:26,408 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) nivcsw: 907
2025-12-23 14:49:26,410 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) nsignals: 0
2025-12-23 14:49:26,427 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) nswap: 0
2025-12-23 14:49:26,428 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) nvcsw: 14
2025-12-23 14:49:26,428 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) oublock: 8
2025-12-23 14:49:26,428 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) stime: 3.192511
2025-12-23 14:49:26,429 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) utime: 0.5583469999999999
2025-12-23 14:49:26,429 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) wtime: 7.982
2025-12-23 14:49:26,429 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) output:
Git info:
    Commit: 3721e81110a4ca0f02ed59c4d99ef3713666bc76
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge ce87dd182c77f9bcd7fcbbd7a5787626f73db5bf into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-hclevy2dda    Host information: 
        Linux ghrun-hclevy2dda 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-23 14:49:26,430 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 53053) errors:

2025-12-23 14:49:26,430 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - start: Node 1 version:
Git info:
    Commit: 3721e81110a4ca0f02ed59c4d99ef3713666bc76
    Branch: 
    Author: Alexey Efimov <xeno@prnwatch.com>
    Summary: Merge ce87dd182c77f9bcd7fcbbd7a5787626f73db5bf into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-hclevy2dda    Host information: 
        Linux ghrun-hclevy2dda 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-23 14:50:13,833 - DEBUG - ya.test.50224  - get_binary: Binary was found by /home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd
2025-12-23 14:50:13,833 - DEBUG - ydb.tests.library.harness.kikimr_runner.50224  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1516', '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: 16707\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-23 14:50:13,835 - DEBUG - ya.test.50224  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-23 14:50:13,836 - DEBUG - ya.test.50224  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-23 14:50:13,837 - DEBUG - ya.test.50224  - execute: Executing '['/home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1516', '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: 16707\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 14:50:13,858 - DEBUG - ya.test.50224  - execute: Command pid: 58227
2025-12-23 14:50:15,692 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) rc: 0
2025-12-23 14:50:15,692 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) elapsed time (sec): 1.8329253196716309
2025-12-23 14:50:15,693 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) idrss: 0
2025-12-23 14:50:15,693 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) inblock: 256
2025-12-23 14:50:15,693 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) isrss: 0
2025-12-23 14:50:15,694 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) ixrss: 0
2025-12-23 14:50:15,694 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) majflt: 5
2025-12-23 14:50:15,695 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) maxrss: 603772
2025-12-23 14:50:15,695 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) minflt: 52303
2025-12-23 14:50:15,695 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) msgrcv: 0
2025-12-23 14:50:15,696 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) msgsnd: 0
2025-12-23 14:50:15,696 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) nivcsw: 312
2025-12-23 14:50:15,696 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) nsignals: 0
2025-12-23 14:50:15,697 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) nswap: 0
2025-12-23 14:50:15,697 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) nvcsw: 645
2025-12-23 14:50:15,697 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) oublock: 8
2025-12-23 14:50:15,698 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) stime: 0.39360599999999996
2025-12-23 14:50:15,698 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) utime: 0.5611149999999999
2025-12-23 14:50:15,699 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) wtime: 1.854
2025-12-23 14:50:15,712 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-23 14:50:15,712 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 58227) errors:

2025-12-23 14:50:15,714 - DEBUG - ya.test.50224  - get_binary: Binary was found by /home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd
2025-12-23 14:50:15,714 - DEBUG - ydb.tests.library.harness.kikimr_runner.50224  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1516', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-23 14:50:15,716 - DEBUG - ya.test.50224  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-23 14:50:15,717 - DEBUG - ya.test.50224  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-23 14:50:15,717 - DEBUG - ya.test.50224  - execute: Executing '['/home/runner/.ya/build/build_root/p2vv/001b1c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:1516', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 14:50:15,743 - DEBUG - ya.test.50224  - execute: Command pid: 59239
2025-12-23 14:50:17,198 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) rc: 0
2025-12-23 14:50:17,198 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) elapsed time (sec): 1.450575351715088
2025-12-23 14:50:17,199 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) idrss: 0
2025-12-23 14:50:17,199 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) inblock: 0
2025-12-23 14:50:17,199 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) isrss: 0
2025-12-23 14:50:17,199 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) ixrss: 0
2025-12-23 14:50:17,200 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) majflt: 1
2025-12-23 14:50:17,200 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) maxrss: 604596
2025-12-23 14:50:17,200 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) minflt: 52358
2025-12-23 14:50:17,200 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) msgrcv: 0
2025-12-23 14:50:17,201 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) msgsnd: 0
2025-12-23 14:50:17,201 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) nivcsw: 296
2025-12-23 14:50:17,201 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) nsignals: 0
2025-12-23 14:50:17,201 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) nswap: 0
2025-12-23 14:50:17,202 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) nvcsw: 616
2025-12-23 14:50:17,202 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) oublock: 8
2025-12-23 14:50:17,202 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) stime: 0.345085
2025-12-23 14:50:17,202 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) utime: 0.549263
2025-12-23 14:50:17,203 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) wtime: 1.477
2025-12-23 14:50:17,204 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-23 14:50:17,204 - DEBUG - ya.test.50224  - _save_outputs: Command (pid 59239) errors:

2025-12-23 14:50:17,205 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-23 14:50:17,405 - INFO - ydb.tests.library.harness.kikimr_runner.50224  - __run: Cluster started and initialized
2025-12-23 14:50:17,473 - DEBUG - ydb.connection.50224  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 14:50:17,487 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 14:50:17,488 - DEBUG - ydb.connection.50224  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 14:50:17,535 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - context_resolve: Resolving endpoints for database /Root
2025-12-23 14:50:17,536 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ListEndpoints, 9f0dc6cd-2eab-4083-8c75-e75cb036a8f2, localhost:1516): creating call state
2025-12-23 14:50:17,537 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ListEndpoints, 9f0dc6cd-2eab-4083-8c75-e75cb036a8f2, localhost:1516): request = { database: "/Root" }
2025-12-23 14:50:18,142 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ListEndpoints, 9f0dc6cd-2eab-4083-8c75-e75cb036a8f2, localhost:1516): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-hclevy2dda.auto.internal" port: 1516 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-23 14:50:18,148 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-hclevy2dda.auto.internal:1516, location 1, ssl: False>]>
2025-12-23 14:50:18,149 - DEBUG - ydb.connection.50224  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 14:50:18,192 - DEBUG - ydb.connection.50224  - close: Closing channel for endpoint localhost:1516
2025-12-23 14:50:18,195 - DEBUG - ydb.query.pool.50224  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-23 14:50:18,197 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(CreateSession, c1e0730d-b7e7-4954-855f-41d88ab6ba68, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:18,198 - DEBUG - ydb.connection.50224  - _log_request: RpcState(CreateSession, c1e0730d-b7e7-4954-855f-41d88ab6ba68, ghrun-hclevy2dda.auto.internal:1516): request = {  }
2025-12-23 14:50:18,211 - DEBUG - ydb.connection.50224  - _log_response: RpcState(CreateSession, c1e0730d-b7e7-4954-855f-41d88ab6ba68, ghrun-hclevy2dda.auto.internal:1516): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" node_id: 1 }
2025-12-23 14:50:18,212 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(AttachSession, 71f1f0e6-b390-43f6-9ab9-e97308b21f19, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:18,213 - DEBUG - ydb.connection.50224  - _log_request: RpcState(AttachSession, 71f1f0e6-b390-43f6-9ab9-e97308b21f19, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" }
2025-12-23 14:50:18,227 - DEBUG - ydb.connection.50224  - _log_response: RpcState(AttachSession, 71f1f0e6-b390-43f6-9ab9-e97308b21f19, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:18,275 - DEBUG - ydb.query.pool.50224  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:18,328 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, f3233ce9-d311-44ec-b223-8e52e26fc66a, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:18,329 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, f3233ce9-d311-44ec-b223-8e52e26fc66a, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:18,348 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, f3233ce9-d311-44ec-b223-8e52e26fc66a, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:23,247 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:23,248 - INFO - __tests__.alter_compression.50224  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-23 14:50:23,249 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:23,249 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, f11deb78-6c69-47ff-ba42-7ffe81e8389d, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:23,250 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, f11deb78-6c69-47ff-ba42-7ffe81e8389d, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:23,255 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, f11deb78-6c69-47ff-ba42-7ffe81e8389d, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:25,241 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:25,242 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:25,243 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, fb73540c-2190-45f7-841e-4d2590580b69, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:25,244 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, fb73540c-2190-45f7-841e-4d2590580b69, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:25,248 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, fb73540c-2190-45f7-841e-4d2590580b69, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:27,697 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:27,698 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:27,699 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 1a8b771c-736f-4f02-83ec-4a728dd10232, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:27,699 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 1a8b771c-736f-4f02-83ec-4a728dd10232, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:27,701 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 1a8b771c-736f-4f02-83ec-4a728dd10232, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:29,089 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:29,090 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:29,090 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, a8d1ed74-d9cc-4530-b3e7-0ca0c62a3ad0, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:29,091 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, a8d1ed74-d9cc-4530-b3e7-0ca0c62a3ad0, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:29,093 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, a8d1ed74-d9cc-4530-b3e7-0ca0c62a3ad0, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:31,869 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:31,870 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-12-23 14:50:31,871 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:31,872 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 581713a0-eecb-441d-ba8d-a2c008569127, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:31,873 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 581713a0-eecb-441d-ba8d-a2c008569127, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:31,874 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 581713a0-eecb-441d-ba8d-a2c008569127, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:33,667 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:33,667 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:33,668 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 4ce78b8c-561a-4d23-afde-fe992dadfa88, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:33,669 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 4ce78b8c-561a-4d23-afde-fe992dadfa88, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:33,671 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 4ce78b8c-561a-4d23-afde-fe992dadfa88, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:34,204 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:34,205 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:34,205 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 07059506-9a35-4711-8195-84073010571c, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:34,206 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 07059506-9a35-4711-8195-84073010571c, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:34,211 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 07059506-9a35-4711-8195-84073010571c, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:35,381 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:35,382 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-12-23 14:50:35,382 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:35,383 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 45b69bd2-82bd-4e48-80aa-3bf5ba5846dc, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:35,384 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 45b69bd2-82bd-4e48-80aa-3bf5ba5846dc, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:35,400 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 45b69bd2-82bd-4e48-80aa-3bf5ba5846dc, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:36,734 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:36,735 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:36,736 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 55dfbfcf-ac16-4974-aa99-663c9fa0cd65, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:36,737 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 55dfbfcf-ac16-4974-aa99-663c9fa0cd65, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:36,738 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 55dfbfcf-ac16-4974-aa99-663c9fa0cd65, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:36,980 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:36,981 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:36,981 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, d25f2d85-ba95-4e43-9d82-a74539d99962, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:36,982 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, d25f2d85-ba95-4e43-9d82-a74539d99962, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:36,985 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, d25f2d85-ba95-4e43-9d82-a74539d99962, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:37,319 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:37,320 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-12-23 14:50:37,320 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:37,321 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 6f96b02f-e1e2-4fe3-8511-4375f650151f, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:37,322 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 6f96b02f-e1e2-4fe3-8511-4375f650151f, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:37,324 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 6f96b02f-e1e2-4fe3-8511-4375f650151f, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:38,381 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:38,381 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:38,382 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, eedf7eea-049f-4c32-bef3-67769053115a, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:38,383 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, eedf7eea-049f-4c32-bef3-67769053115a, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:38,393 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, eedf7eea-049f-4c32-bef3-67769053115a, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:39,065 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:39,066 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:39,067 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, c0b2264a-6e31-4c45-8b6b-5afee96011b9, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:39,068 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, c0b2264a-6e31-4c45-8b6b-5afee96011b9, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:39,071 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, c0b2264a-6e31-4c45-8b6b-5afee96011b9, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:40,114 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:40,115 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-12-23 14:50:40,115 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:40,116 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 812b099f-5d50-492d-8ad0-f8f188f68be5, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:40,117 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 812b099f-5d50-492d-8ad0-f8f188f68be5, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:40,137 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 812b099f-5d50-492d-8ad0-f8f188f68be5, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:41,695 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:41,696 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:41,696 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 6cd3077c-3695-4da8-b869-5741332adaac, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:41,697 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 6cd3077c-3695-4da8-b869-5741332adaac, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:41,699 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 6cd3077c-3695-4da8-b869-5741332adaac, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:44,021 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:44,022 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:44,023 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 206603fb-9896-4973-a7d9-5c17129bfd05, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:44,024 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 206603fb-9896-4973-a7d9-5c17129bfd05, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:44,040 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 206603fb-9896-4973-a7d9-5c17129bfd05, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:45,260 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:45,261 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-12-23 14:50:45,262 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:45,262 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 6733ca9c-8093-40aa-89ce-8f9ffa2a180e, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:45,263 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 6733ca9c-8093-40aa-89ce-8f9ffa2a180e, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:45,265 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 6733ca9c-8093-40aa-89ce-8f9ffa2a180e, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:47,259 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:47,260 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:47,260 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 971c84a2-0d9d-4a44-8410-eb2e08223eec, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:47,261 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 971c84a2-0d9d-4a44-8410-eb2e08223eec, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:47,263 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 971c84a2-0d9d-4a44-8410-eb2e08223eec, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:47,732 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:47,733 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:47,734 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, b3dc284e-05bf-405c-a61f-5e502ea90766, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:47,735 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, b3dc284e-05bf-405c-a61f-5e502ea90766, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:47,737 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, b3dc284e-05bf-405c-a61f-5e502ea90766, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:48,549 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:48,550 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-12-23 14:50:48,550 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:48,551 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, cc16278d-44c5-4fc5-9c65-38efc6f2c24b, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:48,552 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, cc16278d-44c5-4fc5-9c65-38efc6f2c24b, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:48,563 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, cc16278d-44c5-4fc5-9c65-38efc6f2c24b, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:49,924 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:49,964 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:49,964 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, b3b9065c-8a36-4098-b6b1-f982aac5dc42, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:49,965 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, b3b9065c-8a36-4098-b6b1-f982aac5dc42, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:49,972 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, b3b9065c-8a36-4098-b6b1-f982aac5dc42, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:50,476 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:50,477 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:50,478 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 2deffe1a-3e43-45db-9ec3-f4cb8617b92a, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:50,480 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 2deffe1a-3e43-45db-9ec3-f4cb8617b92a, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:50,481 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 2deffe1a-3e43-45db-9ec3-f4cb8617b92a, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:52,568 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:52,569 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-12-23 14:50:52,569 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:52,570 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, a5bb184f-a44b-46e9-ad8d-ef7a187e9cce, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:52,571 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, a5bb184f-a44b-46e9-ad8d-ef7a187e9cce, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:52,577 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, a5bb184f-a44b-46e9-ad8d-ef7a187e9cce, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:54,381 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:54,382 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:54,383 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, d1b46d88-9ee3-41b6-99e3-257469b278c5, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:54,384 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, d1b46d88-9ee3-41b6-99e3-257469b278c5, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:54,385 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, d1b46d88-9ee3-41b6-99e3-257469b278c5, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:54,749 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:54,750 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:54,751 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 4315c2c4-19bf-4005-86b4-93077a1b18de, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:54,752 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 4315c2c4-19bf-4005-86b4-93077a1b18de, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:54,753 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 4315c2c4-19bf-4005-86b4-93077a1b18de, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:55,595 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:55,595 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-12-23 14:50:55,596 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:55,597 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, da6527c2-7eb7-4d93-9caa-ec2392fd2e03, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:55,598 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, da6527c2-7eb7-4d93-9caa-ec2392fd2e03, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:55,605 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, da6527c2-7eb7-4d93-9caa-ec2392fd2e03, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:56,781 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:56,782 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:56,783 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, cbc1e4f7-df54-4ea0-9dd2-04550eb86e88, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:56,784 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, cbc1e4f7-df54-4ea0-9dd2-04550eb86e88, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:56,814 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, cbc1e4f7-df54-4ea0-9dd2-04550eb86e88, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:57,346 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:57,347 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:57,348 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 9d230995-8e4d-4d59-881a-0a919667c94c, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:57,349 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 9d230995-8e4d-4d59-881a-0a919667c94c, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:57,351 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 9d230995-8e4d-4d59-881a-0a919667c94c, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:58,168 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:58,169 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-12-23 14:50:58,169 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:58,170 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, fbaffa36-a60f-420a-9fe9-cadffa1d3c93, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:58,171 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, fbaffa36-a60f-420a-9fe9-cadffa1d3c93, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:58,173 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, fbaffa36-a60f-420a-9fe9-cadffa1d3c93, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:59,440 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:59,441 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:59,442 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, a216f421-573a-4f65-8405-9c00f37d7e77, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:59,442 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, a216f421-573a-4f65-8405-9c00f37d7e77, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:59,444 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, a216f421-573a-4f65-8405-9c00f37d7e77, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:50:59,905 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:59,906 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:50:59,906 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 377550b6-3d85-4a26-8d84-cc676a1dfc79, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:50:59,908 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 377550b6-3d85-4a26-8d84-cc676a1dfc79, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:50:59,909 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 377550b6-3d85-4a26-8d84-cc676a1dfc79, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:01,369 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:01,369 - INFO - __tests__.alter_compression.50224  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-12-23 14:51:01,370 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:01,371 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, e145ab49-cc34-4046-bcb9-163be7a08d84, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:01,372 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, e145ab49-cc34-4046-bcb9-163be7a08d84, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:01,389 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, e145ab49-cc34-4046-bcb9-163be7a08d84, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:02,916 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:02,917 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:02,917 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 5d60a28c-52de-42ee-95fb-c2bb896175bb, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:02,918 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 5d60a28c-52de-42ee-95fb-c2bb896175bb, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:02,921 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 5d60a28c-52de-42ee-95fb-c2bb896175bb, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:03,679 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:03,680 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:03,681 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, d2db7235-2976-4d48-9a7a-33b976fafad4, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:03,682 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, d2db7235-2976-4d48-9a7a-33b976fafad4, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:03,690 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, d2db7235-2976-4d48-9a7a-33b976fafad4, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:04,354 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:04,355 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:04,356 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 10268e84-58bc-4ad3-909a-ab0916856ea0, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:04,357 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 10268e84-58bc-4ad3-909a-ab0916856ea0, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:04,359 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 10268e84-58bc-4ad3-909a-ab0916856ea0, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:12,988 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:23,136 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:23,137 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, fb9e92ff-9967-41c2-9293-42665e4e64c3, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:23,138 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, fb9e92ff-9967-41c2-9293-42665e4e64c3, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:23,140 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, fb9e92ff-9967-41c2-9293-42665e4e64c3, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:26,858 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:36,890 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:36,891 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, d7ef4a0b-eebd-405b-836d-dc85fd26322c, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:36,892 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, d7ef4a0b-eebd-405b-836d-dc85fd26322c, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:36,894 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, d7ef4a0b-eebd-405b-836d-dc85fd26322c, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:37,610 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:47,619 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:47,620 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 26cbffa3-d361-48b5-94fb-1670c82af09d, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:47,621 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 26cbffa3-d361-48b5-94fb-1670c82af09d, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:47,622 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 26cbffa3-d361-48b5-94fb-1670c82af09d, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:48,510 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:48,514 - INFO - root.50224  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-23 14:51:48,515 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:48,516 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 55641b23-3c68-4fc4-b1c3-9b3777769744, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:48,517 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 55641b23-3c68-4fc4-b1c3-9b3777769744, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:48,518 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 55641b23-3c68-4fc4-b1c3-9b3777769744, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:51:49,411 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:59,419 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:51:59,420 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, dc0bfabd-80b5-4e9e-8d1f-8c527ff5f148, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:51:59,421 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, dc0bfabd-80b5-4e9e-8d1f-8c527ff5f148, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:51:59,422 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, dc0bfabd-80b5-4e9e-8d1f-8c527ff5f148, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:00,865 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:00,870 - INFO - root.50224  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-23 14:52:00,871 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:00,873 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, b0b145a6-cf5c-4b69-8d4f-0b5cc8279b27, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:00,874 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, b0b145a6-cf5c-4b69-8d4f-0b5cc8279b27, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:00,876 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, b0b145a6-cf5c-4b69-8d4f-0b5cc8279b27, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:01,442 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:01,445 - INFO - ya.test.50224  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"])
2025-12-23 14:52:01,446 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:01,447 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 9fd0f375-55d4-4895-a859-b483f4768402, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:01,455 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 9fd0f375-55d4-4895-a859-b483f4768402, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:01,456 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 9fd0f375-55d4-4895-a859-b483f4768402, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:07,856 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:07,857 - INFO - __tests__.alter_compression.50224  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-12-23 14:52:07,857 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:07,858 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, b8379aea-8965-445c-b49d-e296cf9242d7, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:07,859 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, b8379aea-8965-445c-b49d-e296cf9242d7, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:07,861 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, b8379aea-8965-445c-b49d-e296cf9242d7, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:09,073 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:09,074 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:09,075 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, ff43c8e6-1a97-4870-bc4d-99fbeb21b321, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:09,076 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, ff43c8e6-1a97-4870-bc4d-99fbeb21b321, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:09,091 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, ff43c8e6-1a97-4870-bc4d-99fbeb21b321, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:10,643 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:10,644 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:10,645 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 1576e25f-1a78-4a99-9254-50ac8ed8c904, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:10,646 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 1576e25f-1a78-4a99-9254-50ac8ed8c904, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:10,647 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 1576e25f-1a78-4a99-9254-50ac8ed8c904, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:12,224 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:12,225 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:12,225 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 57e84b07-dc97-4ed7-b871-b8839f817288, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:12,226 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 57e84b07-dc97-4ed7-b871-b8839f817288, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:12,240 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 57e84b07-dc97-4ed7-b871-b8839f817288, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:14,351 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:14,352 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:14,353 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:14,353 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 4bdd0cc1-0c07-496a-892d-71175bba8af2, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:14,354 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 4bdd0cc1-0c07-496a-892d-71175bba8af2, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:14,356 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 4bdd0cc1-0c07-496a-892d-71175bba8af2, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:14,511 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 14:52:14,512 - DEBUG - ydb.connection.50224  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 14:52:14,550 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - context_resolve: Resolving endpoints for database /Root
2025-12-23 14:52:14,551 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ListEndpoints, 896cc73d-cce2-4aa8-a010-47de3364f887, localhost:1516): creating call state
2025-12-23 14:52:14,551 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ListEndpoints, 896cc73d-cce2-4aa8-a010-47de3364f887, localhost:1516): request = { database: "/Root" }
2025-12-23 14:52:14,566 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ListEndpoints, 896cc73d-cce2-4aa8-a010-47de3364f887, localhost:1516): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-hclevy2dda.auto.internal" port: 1516 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-23 14:52:14,567 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.50224  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-hclevy2dda.auto.internal:1516, location 1, ssl: False>]>
2025-12-23 14:52:14,567 - DEBUG - ydb.connection.50224  - close: Closing channel for endpoint localhost:1516
2025-12-23 14:52:15,812 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:15,813 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:15,814 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 53b8982e-3c95-46e4-b47a-1bcfb157bf05, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:15,815 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 53b8982e-3c95-46e4-b47a-1bcfb157bf05, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:15,816 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 53b8982e-3c95-46e4-b47a-1bcfb157bf05, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:16,204 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:16,205 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:16,206 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, e92f848d-e707-42aa-9512-8666441619f2, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:16,207 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, e92f848d-e707-42aa-9512-8666441619f2, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:16,213 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, e92f848d-e707-42aa-9512-8666441619f2, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:16,645 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:16,645 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:16,646 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:16,647 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, e9913d57-f40d-4d6d-93d4-6bc90a930263, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:16,648 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, e9913d57-f40d-4d6d-93d4-6bc90a930263, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:16,655 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, e9913d57-f40d-4d6d-93d4-6bc90a930263, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:18,301 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:18,301 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:18,302 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, b9019469-6d89-4566-ac32-9b1a5b068b2c, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:18,303 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, b9019469-6d89-4566-ac32-9b1a5b068b2c, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:18,304 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, b9019469-6d89-4566-ac32-9b1a5b068b2c, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:18,652 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:18,652 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:18,653 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 1e4f4f20-592c-4b60-b669-edea1d329e55, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:18,654 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 1e4f4f20-592c-4b60-b669-edea1d329e55, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:18,660 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 1e4f4f20-592c-4b60-b669-edea1d329e55, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:19,161 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:19,162 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:19,163 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:19,163 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 75092e0d-aeb7-44e1-8fdb-d40a68c16b90, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:19,165 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 75092e0d-aeb7-44e1-8fdb-d40a68c16b90, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:19,166 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 75092e0d-aeb7-44e1-8fdb-d40a68c16b90, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:20,696 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:20,697 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:20,698 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, a62f5da7-77de-4df2-acd9-8c5a106321c5, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:20,699 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, a62f5da7-77de-4df2-acd9-8c5a106321c5, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:20,700 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, a62f5da7-77de-4df2-acd9-8c5a106321c5, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:21,096 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:21,097 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:21,097 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 0ff0fe20-f80a-47f1-a683-66fe767c19c4, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:21,098 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 0ff0fe20-f80a-47f1-a683-66fe767c19c4, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:21,100 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 0ff0fe20-f80a-47f1-a683-66fe767c19c4, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:22,421 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:22,421 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:22,422 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:22,423 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 4801631d-fff1-46ad-816b-3e3c06a96fd5, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:22,424 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 4801631d-fff1-46ad-816b-3e3c06a96fd5, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:22,431 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 4801631d-fff1-46ad-816b-3e3c06a96fd5, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:23,827 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:23,828 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:23,829 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 65df576d-c5a5-445a-b86e-120b72492927, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:23,830 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 65df576d-c5a5-445a-b86e-120b72492927, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:23,831 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 65df576d-c5a5-445a-b86e-120b72492927, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:24,204 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:24,205 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:24,205 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, cfadf595-f63c-4dc5-9f1d-73380e9ac76b, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:24,206 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, cfadf595-f63c-4dc5-9f1d-73380e9ac76b, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:24,208 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, cfadf595-f63c-4dc5-9f1d-73380e9ac76b, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:25,238 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:25,238 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:25,239 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:25,240 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 7c0db3d3-64ed-4595-b385-976cf8d9fb9f, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:25,241 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 7c0db3d3-64ed-4595-b385-976cf8d9fb9f, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:25,242 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 7c0db3d3-64ed-4595-b385-976cf8d9fb9f, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:26,442 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:26,442 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:26,443 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, aef11035-8a17-4e95-b9a3-6c27e7366134, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:26,444 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, aef11035-8a17-4e95-b9a3-6c27e7366134, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:26,446 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, aef11035-8a17-4e95-b9a3-6c27e7366134, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:26,792 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:26,793 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:26,794 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 4e72321b-6769-494e-86d7-3c20a427789e, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:26,795 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 4e72321b-6769-494e-86d7-3c20a427789e, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:26,796 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 4e72321b-6769-494e-86d7-3c20a427789e, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:27,391 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:27,392 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:27,393 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:27,393 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 3fb118e0-5e92-4a58-a3ba-a4e9ae0efaae, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:27,394 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 3fb118e0-5e92-4a58-a3ba-a4e9ae0efaae, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:27,400 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 3fb118e0-5e92-4a58-a3ba-a4e9ae0efaae, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:28,796 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:28,797 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:28,798 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 4de43a16-d6e4-4be0-b719-5523c8c27a88, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:28,799 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 4de43a16-d6e4-4be0-b719-5523c8c27a88, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:28,800 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 4de43a16-d6e4-4be0-b719-5523c8c27a88, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:29,100 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:29,100 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:29,101 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, f012155e-10d1-40f0-bb38-747d0355d2f4, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:29,102 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, f012155e-10d1-40f0-bb38-747d0355d2f4, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:29,103 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, f012155e-10d1-40f0-bb38-747d0355d2f4, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:30,049 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:30,050 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:30,050 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:30,051 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, e2dcf6ed-1148-49f3-9112-b656dcc6fd3c, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:30,052 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, e2dcf6ed-1148-49f3-9112-b656dcc6fd3c, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:30,054 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, e2dcf6ed-1148-49f3-9112-b656dcc6fd3c, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:31,453 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:31,453 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:31,454 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, ece3589b-4784-4462-a42d-b84e8dbe00d3, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:31,455 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, ece3589b-4784-4462-a42d-b84e8dbe00d3, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:31,456 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, ece3589b-4784-4462-a42d-b84e8dbe00d3, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:31,849 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:31,850 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:31,851 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 52226c22-ecea-4657-bc53-d6d088ff2f36, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:31,852 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 52226c22-ecea-4657-bc53-d6d088ff2f36, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:31,854 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 52226c22-ecea-4657-bc53-d6d088ff2f36, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:32,672 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:32,673 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:32,673 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:32,674 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 47cbd551-7786-4b1f-bc88-00c8c4c16d53, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:32,675 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 47cbd551-7786-4b1f-bc88-00c8c4c16d53, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:32,677 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 47cbd551-7786-4b1f-bc88-00c8c4c16d53, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:34,067 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:34,068 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:34,069 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, b4c1ee76-2075-47c6-a3da-49896e4c1873, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:34,070 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, b4c1ee76-2075-47c6-a3da-49896e4c1873, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:34,072 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, b4c1ee76-2075-47c6-a3da-49896e4c1873, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:35,085 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:35,085 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:35,086 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 1f0847fc-b294-4a8c-8064-4c6f83cc3c3f, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:35,087 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 1f0847fc-b294-4a8c-8064-4c6f83cc3c3f, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:35,089 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 1f0847fc-b294-4a8c-8064-4c6f83cc3c3f, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:36,307 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:36,308 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:36,308 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:36,309 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 8675c588-02a0-40c7-be6b-99fa3185a4df, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:36,310 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 8675c588-02a0-40c7-be6b-99fa3185a4df, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:36,323 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 8675c588-02a0-40c7-be6b-99fa3185a4df, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:37,755 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:37,756 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:37,757 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, eb87e775-d9a7-4439-b1e1-37bc7e14799c, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:37,758 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, eb87e775-d9a7-4439-b1e1-37bc7e14799c, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:37,760 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, eb87e775-d9a7-4439-b1e1-37bc7e14799c, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:38,289 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:38,355 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:38,356 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, f0f0df2a-5931-422b-b645-4df895c9cfb0, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:38,357 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, f0f0df2a-5931-422b-b645-4df895c9cfb0, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:38,380 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, f0f0df2a-5931-422b-b645-4df895c9cfb0, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:39,425 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:39,425 - INFO - __tests__.alter_compression.50224  - 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-12-23 14:52:39,426 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:39,427 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 73be55a2-93d7-4627-bcc7-8cc3ced45d7d, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:39,428 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 73be55a2-93d7-4627-bcc7-8cc3ced45d7d, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:39,440 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 73be55a2-93d7-4627-bcc7-8cc3ced45d7d, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:41,168 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:41,169 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:41,170 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 2407a2ae-c71a-49f0-9cfa-b5f6fa367ecb, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:41,171 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 2407a2ae-c71a-49f0-9cfa-b5f6fa367ecb, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:41,173 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 2407a2ae-c71a-49f0-9cfa-b5f6fa367ecb, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:41,596 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:41,597 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:41,598 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 5f543712-cf6c-4a98-affe-3dd66cd0ca59, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:41,600 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 5f543712-cf6c-4a98-affe-3dd66cd0ca59, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:41,601 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 5f543712-cf6c-4a98-affe-3dd66cd0ca59, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:41,915 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:41,916 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:41,916 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 576a4e50-cbc2-4433-84bb-463c85a00c93, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:41,917 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 576a4e50-cbc2-4433-84bb-463c85a00c93, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:41,921 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 576a4e50-cbc2-4433-84bb-463c85a00c93, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:52:47,073 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:57,148 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:52:57,149 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 0de5f266-dc59-46a6-964c-c078fbbb01f0, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:52:57,150 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 0de5f266-dc59-46a6-964c-c078fbbb01f0, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:52:57,152 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 0de5f266-dc59-46a6-964c-c078fbbb01f0, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:53:02,694 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:53:02,806 - INFO - root.50224  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4369632)
2025-12-23 14:53:02,806 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:53:02,807 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 31a17149-31e1-4c74-9dc6-fbcab758b0f6, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:53:02,809 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 31a17149-31e1-4c74-9dc6-fbcab758b0f6, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:53:02,810 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 31a17149-31e1-4c74-9dc6-fbcab758b0f6, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:53:03,262 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:53:03,263 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:53:03,264 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, fdf6555e-32db-4b3f-a2d7-3ae8cf8062ad, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:53:03,264 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, fdf6555e-32db-4b3f-a2d7-3ae8cf8062ad, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:53:03,266 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, fdf6555e-32db-4b3f-a2d7-3ae8cf8062ad, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:53:07,413 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:53:17,479 - DEBUG - ydb.query.pool.50224  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:53:17,480 - DEBUG - ydb.connection.50224  - _prepare_call: RpcState(ExecuteQuery, 87c138a4-e8f7-4efe-9109-87e138570175, ghrun-hclevy2dda.auto.internal:1516): creating call state
2025-12-23 14:53:17,481 - DEBUG - ydb.connection.50224  - _log_request: RpcState(ExecuteQuery, 87c138a4-e8f7-4efe-9109-87e138570175, ghrun-hclevy2dda.auto.internal:1516): request = { session_id: "ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 14:53:17,482 - DEBUG - ydb.connection.50224  - _log_response: RpcState(ExecuteQuery, 87c138a4-e8f7-4efe-9109-87e138570175, ghrun-hclevy2dda.auto.internal:1516): response = { <_MultiThreadedRendezvous object> }
2025-12-23 14:53:18,213 - DEBUG - ydb.query.pool.50224  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTJkNTU2YmYtNzgxZWNkMWItNzNkMGE3NGEtODA5NGYxMDc=
2025-12-23 14:53:18,250 - INFO - root.50224  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-12-23 14:53:18,251 - INFO - root.50224  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8009216 / 4258592: 1.8807192612018244
2025-12-23 14:53:18,264 - INFO - ya.test.50224  - pytest_runtest_teardown: Test teardown
