2026-04-11 06:49:42,236 - INFO - ya.test.53805  - pytest_runtest_setup: ####################################################################################################
2026-04-11 06:49:42,237 - INFO - ya.test.53805  - pytest_runtest_setup: test_single_dml_query_logged[upsert]
2026-04-11 06:49:42,237 - INFO - ya.test.53805  - pytest_runtest_setup: ####################################################################################################
2026-04-11 06:49:42,237 - INFO - ya.test.53805  - pytest_runtest_setup: Test setup
2026-04-11 06:49:42,473 - DEBUG - ya.test.53805  - get_binary: Binary was found by /home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd
2026-04-11 06:49:42,475 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23795
2026-04-11 06:49:42,478 - INFO - ydb.tests.library.fixtures.53805  - ydb_cluster: setup ydb_cluster for __tests__.test_auditlog
2026-04-11 06:49:42,478 - INFO - ydb.tests.library.fixtures.53805  - ydb_cluster: setup ydb_cluster as local
2026-04-11 06:49:42,479 - DEBUG - ydb.tests.library.harness.kikimr_runner.53805  - start: Working directory: /home/runner/.ya/build/build_root/7x6p/002759/r3tmp/kikimr_cluster_ar56z3b6
2026-04-11 06:49:42,521 - DEBUG - ya.test.53805  - get_binary: Binary was found by /home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd
2026-04-11 06:49:42,522 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24819
2026-04-11 06:49:42,522 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63190
2026-04-11 06:49:42,523 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22750
2026-04-11 06:49:42,523 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10250
2026-04-11 06:49:42,525 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/kikimr_configs"
2026-04-11 06:49:42,525 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __make_run_command: Final command: /home/runner/.ya/build/build_root/7x6p/002759/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/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/node_1/logfile_1erbibx_.log --grpc-port=24819 --mon-port=63190 --ic-port=23795
2026-04-11 06:49:42,526 - DEBUG - ydb.tests.library.harness.kikimr_runner.53805  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:24819/1, disk_size 68719476736
2026-04-11 06:49:42,526 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/kikimr_configs"
2026-04-11 06:49:42,526 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __make_run_command: Final command: /home/runner/.ya/build/build_root/7x6p/002759/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/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/node_1/logfile_1erbibx_.log --grpc-port=24819 --mon-port=63190 --ic-port=23795
2026-04-11 06:49:42,528 - DEBUG - ya.test.53805  - execute: Executing '('/home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/node_1/logfile_1erbibx_.log', '--grpc-port=24819', '--mon-port=63190', '--ic-port=23795')' in '/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/node_1' (no wait)
2026-04-11 06:49:42,553 - DEBUG - ya.test.53805  - execute: Command pid: 54456
2026-04-11 06:49:42,554 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - start: Started node localhost:24819/1
2026-04-11 06:49:42,565 - DEBUG - ya.test.53805  - _get_command_output_file: Command out will be placed to ydbd.out
2026-04-11 06:49:42,566 - DEBUG - ya.test.53805  - _get_command_output_file: Command err will be placed to ydbd.err
2026-04-11 06:49:42,566 - DEBUG - ya.test.53805  - execute: Executing '['/home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2026-04-11 06:49:42,592 - DEBUG - ya.test.53805  - execute: Command pid: 54458
2026-04-11 06:49:43,633 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) rc: 0
2026-04-11 06:49:43,634 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) elapsed time (sec): 1.0383355617523193
2026-04-11 06:49:43,634 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) idrss: 0
2026-04-11 06:49:43,634 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) inblock: 0
2026-04-11 06:49:43,635 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) isrss: 0
2026-04-11 06:49:43,635 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) ixrss: 0
2026-04-11 06:49:43,635 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) majflt: 0
2026-04-11 06:49:43,635 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) maxrss: 519892
2026-04-11 06:49:43,636 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) minflt: 47407
2026-04-11 06:49:43,636 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) msgrcv: 0
2026-04-11 06:49:43,636 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) msgsnd: 0
2026-04-11 06:49:43,636 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) nivcsw: 210
2026-04-11 06:49:43,637 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) nsignals: 0
2026-04-11 06:49:43,637 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) nswap: 0
2026-04-11 06:49:43,637 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) nvcsw: 14
2026-04-11 06:49:43,637 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) oublock: 8
2026-04-11 06:49:43,638 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) stime: 0.19794
2026-04-11 06:49:43,638 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) utime: 0.553292
2026-04-11 06:49:43,638 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) wtime: 1.064
2026-04-11 06:49:43,638 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) output:
Git info:
    Commit: 5163578e8000637feea522d27ab85ffccb333187
    Branch: 
    Author: Kirill Pleshivtsev <drbasic@yandex-team.ru>
    Summary: Merge 2103ab916833803b3c9ba754a510e48dacd40b35 into 20d91ca4e45ddaf9c54c1a4bfd3b4ae44be3b885
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tt6w5as7qa    Host information: 
        Linux ghrun-tt6w5as7qa 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++

2026-04-11 06:49:43,639 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 54458) errors:

2026-04-11 06:49:43,639 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - start: Node 1 version:
Git info:
    Commit: 5163578e8000637feea522d27ab85ffccb333187
    Branch: 
    Author: Kirill Pleshivtsev <drbasic@yandex-team.ru>
    Summary: Merge 2103ab916833803b3c9ba754a510e48dacd40b35 into 20d91ca4e45ddaf9c54c1a4bfd3b4ae44be3b885
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tt6w5as7qa    Host information: 
        Linux ghrun-tt6w5as7qa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2026-04-11 06:49:49,821 - DEBUG - ya.test.53805  - get_binary: Binary was found by /home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd
2026-04-11 06:49:49,822 - DEBUG - ydb.tests.library.harness.kikimr_runner.53805  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24819', '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: 23795\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2026-04-11 06:49:49,823 - DEBUG - ya.test.53805  - _get_command_output_file: Command out will be placed to ydbd.out.0
2026-04-11 06:49:49,824 - DEBUG - ya.test.53805  - _get_command_output_file: Command err will be placed to ydbd.err.0
2026-04-11 06:49:49,824 - DEBUG - ya.test.53805  - execute: Executing '['/home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24819', '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: 23795\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2026-04-11 06:49:49,834 - DEBUG - ya.test.53805  - execute: Command pid: 55906
2026-04-11 06:49:50,971 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) rc: 0
2026-04-11 06:49:50,971 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) elapsed time (sec): 1.13492751121521
2026-04-11 06:49:50,972 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) idrss: 0
2026-04-11 06:49:50,972 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) inblock: 0
2026-04-11 06:49:50,972 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) isrss: 0
2026-04-11 06:49:50,973 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) ixrss: 0
2026-04-11 06:49:50,973 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) majflt: 0
2026-04-11 06:49:50,973 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) maxrss: 581256
2026-04-11 06:49:50,974 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) minflt: 51518
2026-04-11 06:49:50,974 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) msgrcv: 0
2026-04-11 06:49:50,974 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) msgsnd: 0
2026-04-11 06:49:50,975 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) nivcsw: 131
2026-04-11 06:49:50,975 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) nsignals: 0
2026-04-11 06:49:50,976 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) nswap: 0
2026-04-11 06:49:50,976 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) nvcsw: 473
2026-04-11 06:49:50,977 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) oublock: 8
2026-04-11 06:49:50,977 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) stime: 0.35788
2026-04-11 06:49:50,977 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) utime: 0.668018
2026-04-11 06:49:50,978 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) wtime: 1.144
2026-04-11 06:49:50,978 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2026-04-11 06:49:50,979 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 55906) errors:

2026-04-11 06:49:50,981 - DEBUG - ya.test.53805  - get_binary: Binary was found by /home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd
2026-04-11 06:49:50,982 - DEBUG - ydb.tests.library.harness.kikimr_runner.53805  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24819', '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']
2026-04-11 06:49:50,983 - DEBUG - ya.test.53805  - _get_command_output_file: Command out will be placed to ydbd.out.1
2026-04-11 06:49:50,985 - DEBUG - ya.test.53805  - _get_command_output_file: Command err will be placed to ydbd.err.1
2026-04-11 06:49:50,985 - DEBUG - ya.test.53805  - execute: Executing '['/home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24819', '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)
2026-04-11 06:49:50,987 - DEBUG - ya.test.53805  - execute: Command pid: 56019
2026-04-11 06:49:52,038 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) rc: 0
2026-04-11 06:49:52,038 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) elapsed time (sec): 0.9351451396942139
2026-04-11 06:49:52,039 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) idrss: 0
2026-04-11 06:49:52,039 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) inblock: 0
2026-04-11 06:49:52,039 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) isrss: 0
2026-04-11 06:49:52,039 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) ixrss: 0
2026-04-11 06:49:52,040 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) majflt: 0
2026-04-11 06:49:52,040 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) maxrss: 581836
2026-04-11 06:49:52,040 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) minflt: 51397
2026-04-11 06:49:52,041 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) msgrcv: 0
2026-04-11 06:49:52,041 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) msgsnd: 0
2026-04-11 06:49:52,041 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) nivcsw: 192
2026-04-11 06:49:52,042 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) nsignals: 0
2026-04-11 06:49:52,042 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) nswap: 0
2026-04-11 06:49:52,042 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) nvcsw: 375
2026-04-11 06:49:52,043 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) oublock: 8
2026-04-11 06:49:52,043 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) stime: 0.224568
2026-04-11 06:49:52,043 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) utime: 0.590776
2026-04-11 06:49:52,044 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) wtime: 0.937
2026-04-11 06:49:52,044 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2026-04-11 06:49:52,045 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56019) errors:

2026-04-11 06:49:52,045 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2026-04-11 06:49:52,113 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __run: Cluster started and initialized
2026-04-11 06:49:52,153 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - remove_database: /Root/ydb/tests/functional/audit/test_auditlog.py
2026-04-11 06:49:52,154 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_send_op: /Root/ydb/tests/functional/audit/test_auditlog.py: send console operation, token None
2026-04-11 06:49:52,171 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_send_op: /Root/ydb/tests/functional/audit/test_auditlog.py: response from console: RemoveTenantResponse {
  Response {
    operation {
      ready: true
      status: NOT_FOUND
      issues {
        message: "Database \'/Root/ydb/tests/functional/audit/test_auditlog.py\' doesn\'t exist"
        severity: 1
      }
    }
  }
}
Status {
  Code: NOT_FOUND
  Reason: "Database \'/Root/ydb/tests/functional/audit/test_auditlog.py\' doesn\'t exist"
}

2026-04-11 06:49:52,172 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_wait_op: /Root/ydb/tests/functional/audit/test_auditlog.py: wait console operation done
2026-04-11 06:49:52,695 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_wait_op: /Root/ydb/tests/functional/audit/test_auditlog.py: console operation done
2026-04-11 06:49:52,695 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_wait_tenant_gone: /Root/ydb/tests/functional/audit/test_auditlog.py: wait tenant gone
2026-04-11 06:49:52,741 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_wait_tenant_gone: /Root/ydb/tests/functional/audit/test_auditlog.py: tenant gone
2026-04-11 06:49:52,741 - DEBUG - ydb.tests.library.fixtures.53805  - ydb_database_ctx: create database /Root/ydb/tests/functional/audit/test_auditlog.py: create path and declare internals
2026-04-11 06:49:53,301 - DEBUG - ydb.tests.library.fixtures.53805  - ydb_database_ctx: create database /Root/ydb/tests/functional/audit/test_auditlog.py: start nodes and construct internals
2026-04-11 06:49:53,302 - DEBUG - ya.test.53805  - get_binary: Binary was found by /home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd
2026-04-11 06:49:53,302 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8832
2026-04-11 06:49:53,303 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28378
2026-04-11 06:49:53,303 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4084
2026-04-11 06:49:53,304 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6661
2026-04-11 06:49:53,304 - DEBUG - library.python.filelock.53805  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61215
2026-04-11 06:49:53,305 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/kikimr_configs"
2026-04-11 06:49:53,306 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __make_run_command: Final command: /home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:24819 --tenant=/Root/ydb/tests/functional/audit/test_auditlog.py --log-file-name=/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/slot_1/logfile_t6ex9ner.log --grpc-port=8832 --mon-port=28378 --ic-port=4084
2026-04-11 06:49:53,306 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/kikimr_configs"
2026-04-11 06:49:53,306 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - __make_run_command: Final command: /home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node-broker=localhost:24819 --tenant=/Root/ydb/tests/functional/audit/test_auditlog.py --log-file-name=/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/slot_1/logfile_t6ex9ner.log --grpc-port=8832 --mon-port=28378 --ic-port=4084
2026-04-11 06:49:53,308 - DEBUG - ya.test.53805  - execute: Executing '('/home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/kikimr_configs/config.yaml', '--node-broker=localhost:24819', '--tenant=/Root/ydb/tests/functional/audit/test_auditlog.py', '--log-file-name=/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/slot_1/logfile_t6ex9ner.log', '--grpc-port=8832', '--mon-port=28378', '--ic-port=4084')' in '/home/runner/.ya/build/build_root/7x6p/002759/ydb/tests/functional/audit/test-results/py3test/testing_out_stuff/test_auditlog/chunk22/testing_out_stuff/test_auditlog.py.test_single_dml_query_logged.upsert/cluster/slot_1' (no wait)
2026-04-11 06:49:53,313 - DEBUG - ya.test.53805  - execute: Command pid: 56369
2026-04-11 06:49:53,313 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - start: Started node localhost:8832/1
2026-04-11 06:49:53,315 - DEBUG - ya.test.53805  - _get_command_output_file: Command out will be placed to ydbd.out.2
2026-04-11 06:49:53,316 - DEBUG - ya.test.53805  - _get_command_output_file: Command err will be placed to ydbd.err.2
2026-04-11 06:49:53,328 - DEBUG - ya.test.53805  - execute: Executing '['/home/runner/.ya/build/build_root/7x6p/002759/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2026-04-11 06:49:53,336 - DEBUG - ya.test.53805  - execute: Command pid: 56371
2026-04-11 06:49:54,244 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) rc: 0
2026-04-11 06:49:54,245 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) elapsed time (sec): 0.8910698890686035
2026-04-11 06:49:54,245 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) idrss: 0
2026-04-11 06:49:54,246 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) inblock: 0
2026-04-11 06:49:54,246 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) isrss: 0
2026-04-11 06:49:54,246 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) ixrss: 0
2026-04-11 06:49:54,246 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) majflt: 0
2026-04-11 06:49:54,247 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) maxrss: 594640
2026-04-11 06:49:54,247 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) minflt: 47459
2026-04-11 06:49:54,247 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) msgrcv: 0
2026-04-11 06:49:54,248 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) msgsnd: 0
2026-04-11 06:49:54,248 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) nivcsw: 118
2026-04-11 06:49:54,248 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) nsignals: 0
2026-04-11 06:49:54,249 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) nswap: 0
2026-04-11 06:49:54,249 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) nvcsw: 14
2026-04-11 06:49:54,249 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) oublock: 8
2026-04-11 06:49:54,249 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) stime: 0.229898
2026-04-11 06:49:54,250 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) utime: 0.534176
2026-04-11 06:49:54,250 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) wtime: 0.911
2026-04-11 06:49:54,250 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) output:
Git info:
    Commit: 5163578e8000637feea522d27ab85ffccb333187
    Branch: 
    Author: Kirill Pleshivtsev <drbasic@yandex-team.ru>
    Summary: Merge 2103ab916833803b3c9ba754a510e48dacd40b35 into 20d91ca4e45ddaf9c54c1a4bfd3b4ae44be3b885
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tt6w5as7qa    Host information: 
        Linux ghrun-tt6w5as7qa 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++

2026-04-11 06:49:54,251 - DEBUG - ya.test.53805  - _save_outputs: Command (pid 56371) errors:

2026-04-11 06:49:54,251 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - start: Node 1 version:
Git info:
    Commit: 5163578e8000637feea522d27ab85ffccb333187
    Branch: 
    Author: Kirill Pleshivtsev <drbasic@yandex-team.ru>
    Summary: Merge 2103ab916833803b3c9ba754a510e48dacd40b35 into 20d91ca4e45ddaf9c54c1a4bfd3b4ae44be3b885
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-tt6w5as7qa    Host information: 
        Linux ghrun-tt6w5as7qa 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2026-04-11 06:49:54,252 - DEBUG - ydb.tests.library.fixtures.53805  - ydb_database_ctx: create database /Root/ydb/tests/functional/audit/test_auditlog.py: wait construction done
2026-04-11 06:50:00,303 - DEBUG - ydb.tests.library.fixtures.53805  - ydb_database_ctx: create database /Root/ydb/tests/functional/audit/test_auditlog.py: database up
2026-04-11 06:50:00,304 - DEBUG - ydb.connection.53805  - 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)]
2026-04-11 06:50:00,308 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.53805  - context_resolve: Preparing initial endpoint to resolve endpoints
2026-04-11 06:50:00,310 - DEBUG - ydb.connection.53805  - 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)]
2026-04-11 06:50:00,316 - DEBUG - ydb.table.SessionPool.53805  - subscribe: Active session queue is empty, subscribe waiter for a session
2026-04-11 06:50:00,320 - DEBUG - ydb.table.SessionPool.53805  - subscribe: Subscribe waiter <Future at 0x7c7b03fd3ee0 state=pending>
2026-04-11 06:50:00,321 - DEBUG - ydb.table.SessionPool.53805  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2026-04-11 06:50:00,321 - DEBUG - ydb.table.SessionPool.53805  - _create: Created session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:00,322 - DEBUG - ydb.table.SessionPool.53805  - _prepare: Preparing session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:00,324 - ERROR - ydb.table.SessionPool.53805  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2026-04-11 06:50:00,329 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.53805  - context_resolve: Resolving endpoints for database /Root/ydb/tests/functional/audit/test_auditlog.py
2026-04-11 06:50:00,329 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(ListEndpoints, b7b80913-f0a2-4dfd-a4bf-82ccfc174d9d, localhost:24819): creating call state
2026-04-11 06:50:00,330 - DEBUG - ydb.connection.53805  - _log_request: RpcState(ListEndpoints, b7b80913-f0a2-4dfd-a4bf-82ccfc174d9d, localhost:24819): request = { database: "/Root/ydb/tests/functional/audit/test_auditlog.py" }
2026-04-11 06:50:00,341 - DEBUG - ydb.connection.53805  - _log_response: RpcState(ListEndpoints, b7b80913-f0a2-4dfd-a4bf-82ccfc174d9d, localhost:24819): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tt6w5as7qa.auto.internal" port: 8832 service: "nbs" 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" node_id: 50000 } self_location: "1" } } } }
2026-04-11 06:50:00,342 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.53805  - _add_debug_details: Resolved endpoints for database /Root/ydb/tests/functional/audit/test_auditlog.py: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tt6w5as7qa.auto.internal:8832, location , ssl: False>]>
2026-04-11 06:50:00,343 - DEBUG - ydb.connection.53805  - 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)]
2026-04-11 06:50:00,363 - DEBUG - ydb.connection.53805  - close: Closing channel for endpoint localhost:24819
2026-04-11 06:50:00,364 - DEBUG - ydb.table.SessionPool.53805  - _prepare: Preparing session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:00,368 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(CreateSession, 9bffaa1b-632f-4d4b-8d2a-bb95c92260b2, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:00,370 - DEBUG - ydb.connection.53805  - _log_request: RpcState(CreateSession, 9bffaa1b-632f-4d4b-8d2a-bb95c92260b2, ghrun-tt6w5as7qa.auto.internal:8832): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-04-11 06:50:00,381 - DEBUG - ydb.connection.53805  - _on_response_callback: RpcState(CreateSession, 9bffaa1b-632f-4d4b-8d2a-bb95c92260b2, ghrun-tt6w5as7qa.auto.internal:8832): on response callback started
2026-04-11 06:50:00,382 - DEBUG - ydb.connection.53805  - _log_response: RpcState(CreateSession, 9bffaa1b-632f-4d4b-8d2a-bb95c92260b2, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=YzRmYTViN2ItNjcwMDMzOGItYTBmMmJlZGMtNmVhOGIyZGE%3D" } } } }
2026-04-11 06:50:00,383 - DEBUG - ydb.table.SessionPool.53805  - put: Put on session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:00,384 - DEBUG - ydb.table.SessionPool.53805  - put: Replying to waiter with a session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:00,389 - DEBUG - ydb.connection.53805  - _on_response_callback: RpcState(CreateSession, 9bffaa1b-632f-4d4b-8d2a-bb95c92260b2, ghrun-tt6w5as7qa.auto.internal:8832): on response callback success
2026-04-11 06:50:00,385 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(ExecuteSchemeQuery, c325278e-2398-4ea0-b8d7-5c76c50f1eb1, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:00,393 - DEBUG - ydb.connection.53805  - _log_request: RpcState(ExecuteSchemeQuery, c325278e-2398-4ea0-b8d7-5c76c50f1eb1, ghrun-tt6w5as7qa.auto.internal:8832): request = { session_id: "ydb://session/3?node_id=50000&id=YzRmYTViN2ItNjcwMDMzOGItYTBmMmJlZGMtNmVhOGIyZGE%3D" yql_text: "\n            grant \'ydb.database.connect\' on `/Root/ydb/tests/functional/audit/test_auditlog.py` to `other-user@builtin`\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-04-11 06:50:00,620 - DEBUG - ydb.connection.53805  - _log_response: RpcState(ExecuteSchemeQuery, c325278e-2398-4ea0-b8d7-5c76c50f1eb1, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS } }
2026-04-11 06:50:00,621 - DEBUG - ydb.table.SessionPool.53805  - put: Put on session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:00,623 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(ExecuteSchemeQuery, dbb44947-eb8f-4bb3-a233-f8f7c29ba3b2, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:00,624 - DEBUG - ydb.connection.53805  - _log_request: RpcState(ExecuteSchemeQuery, dbb44947-eb8f-4bb3-a233-f8f7c29ba3b2, ghrun-tt6w5as7qa.auto.internal:8832): request = { session_id: "ydb://session/3?node_id=50000&id=YzRmYTViN2ItNjcwMDMzOGItYTBmMmJlZGMtNmVhOGIyZGE%3D" yql_text: "\n            grant \'ydb.database.connect\' on `/Root/ydb/tests/functional/audit/test_auditlog.py` to `__bad__@builtin`\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-04-11 06:50:00,659 - DEBUG - ydb.connection.53805  - _log_response: RpcState(ExecuteSchemeQuery, dbb44947-eb8f-4bb3-a233-f8f7c29ba3b2, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS } }
2026-04-11 06:50:00,660 - DEBUG - ydb.table.SessionPool.53805  - put: Put on session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:00,662 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(ExecuteSchemeQuery, 5bdd7931-083b-4675-a2ad-113213743802, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:00,664 - DEBUG - ydb.connection.53805  - _log_request: RpcState(ExecuteSchemeQuery, 5bdd7931-083b-4675-a2ad-113213743802, ghrun-tt6w5as7qa.auto.internal:8832): request = { session_id: "ydb://session/3?node_id=50000&id=YzRmYTViN2ItNjcwMDMzOGItYTBmMmJlZGMtNmVhOGIyZGE%3D" yql_text: "\n            create table `/Root/ydb/tests/functional/audit/test_auditlog.py/test-table` (\n                id int64,\n                value int64,\n                primary key (id)\n            );\n        " operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-04-11 06:50:01,232 - DEBUG - ydb.connection.53805  - _log_response: RpcState(ExecuteSchemeQuery, 5bdd7931-083b-4675-a2ad-113213743802, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS } }
2026-04-11 06:50:01,233 - DEBUG - ydb.table.SessionPool.53805  - put: Put on session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:01,235 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(ExecuteDataQuery, 11a10e36-d253-46c5-9979-1f4cd9faa145, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:01,237 - DEBUG - ydb.connection.53805  - _log_request: RpcState(ExecuteDataQuery, 11a10e36-d253-46c5-9979-1f4cd9faa145, ghrun-tt6w5as7qa.auto.internal:8832): request = { session_id: "ydb://session/3?node_id=50000&id=YzRmYTViN2ItNjcwMDMzOGItYTBmMmJlZGMtNmVhOGIyZGE%3D" tx_control { begin_tx { serializable_read_write { } } } query { yql_text: "\n            insert into `/Root/ydb/tests/functional/audit/test_auditlog.py/test-table` (id, value) values (1, 1), (2, 2)\n        " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-04-11 06:50:01,666 - DEBUG - ydb.connection.53805  - _log_response: RpcState(ExecuteDataQuery, 11a10e36-d253-46c5-9979-1f4cd9faa145, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { id: "01knxmvz0yc8ztmdyn63c52hrs" } } } } }
2026-04-11 06:50:01,667 - DEBUG - ydb.table.SessionPool.53805  - put: Put on session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:02,723 - DEBUG - ydb.connection.53805  - 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)]
2026-04-11 06:50:02,727 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.53805  - context_resolve: Preparing initial endpoint to resolve endpoints
2026-04-11 06:50:02,728 - DEBUG - ydb.connection.53805  - 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)]
2026-04-11 06:50:02,739 - INFO - ya.test.53805  - pytest_runtest_call: Test call (class_name: test_auditlog.py, test_name: test_single_dml_query_logged[upsert])
2026-04-11 06:50:02,741 - DEBUG - ydb.table.SessionPool.53805  - subscribe: Active session queue is empty, subscribe waiter for a session
2026-04-11 06:50:02,742 - DEBUG - ydb.table.SessionPool.53805  - subscribe: Subscribe waiter <Future at 0x7c6b04393960 state=pending>
2026-04-11 06:50:02,742 - DEBUG - ydb.table.SessionPool.53805  - subscribe: Session pool is not large enough (active_count < size: 0 < 100). will create a new session.
2026-04-11 06:50:02,743 - DEBUG - ydb.table.SessionPool.53805  - _create: Created session <ydb.table.Session object at 0x7c6b04392ee0>
2026-04-11 06:50:02,744 - DEBUG - ydb.table.SessionPool.53805  - _prepare: Preparing session <ydb.table.Session object at 0x7c6b04392ee0>
2026-04-11 06:50:02,744 - ERROR - ydb.table.SessionPool.53805  - _on_session_create: Failed to create session. Put event to a delayed queue. Reason: Couldn't find valid connection
2026-04-11 06:50:02,751 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.53805  - context_resolve: Resolving endpoints for database /Root/ydb/tests/functional/audit/test_auditlog.py
2026-04-11 06:50:02,752 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(ListEndpoints, 9362ef98-2ee4-4758-8cd6-fd5bd9b05915, localhost:24819): creating call state
2026-04-11 06:50:02,753 - DEBUG - ydb.connection.53805  - _log_request: RpcState(ListEndpoints, 9362ef98-2ee4-4758-8cd6-fd5bd9b05915, localhost:24819): request = { database: "/Root/ydb/tests/functional/audit/test_auditlog.py" }
2026-04-11 06:50:02,763 - DEBUG - ydb.connection.53805  - _log_response: RpcState(ListEndpoints, 9362ef98-2ee4-4758-8cd6-fd5bd9b05915, localhost:24819): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tt6w5as7qa.auto.internal" port: 8832 service: "nbs" 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" node_id: 50000 } self_location: "1" } } } }
2026-04-11 06:50:02,764 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.53805  - _add_debug_details: Resolved endpoints for database /Root/ydb/tests/functional/audit/test_auditlog.py: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tt6w5as7qa.auto.internal:8832, location , ssl: False>]>
2026-04-11 06:50:02,765 - DEBUG - ydb.connection.53805  - 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)]
2026-04-11 06:50:02,783 - DEBUG - ydb.connection.53805  - close: Closing channel for endpoint localhost:24819
2026-04-11 06:50:02,783 - DEBUG - ydb.table.SessionPool.53805  - _prepare: Preparing session <ydb.table.Session object at 0x7c6b04392ee0>
2026-04-11 06:50:02,785 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(CreateSession, fbf6008e-8867-4a37-baae-613eaf2202d9, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:02,786 - DEBUG - ydb.connection.53805  - _log_request: RpcState(CreateSession, fbf6008e-8867-4a37-baae-613eaf2202d9, ghrun-tt6w5as7qa.auto.internal:8832): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-04-11 06:50:02,796 - DEBUG - ydb.connection.53805  - _on_response_callback: RpcState(CreateSession, fbf6008e-8867-4a37-baae-613eaf2202d9, ghrun-tt6w5as7qa.auto.internal:8832): on response callback started
2026-04-11 06:50:02,798 - DEBUG - ydb.connection.53805  - _log_response: RpcState(CreateSession, fbf6008e-8867-4a37-baae-613eaf2202d9, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=50000&id=ZTU4OTZkNGUtODE0MWE0ZTktMTIxMzNhOWEtZDdlOTZkMGE%3D" } } } }
2026-04-11 06:50:02,799 - DEBUG - ydb.table.SessionPool.53805  - put: Put on session <ydb.table.Session object at 0x7c6b04392ee0>
2026-04-11 06:50:02,799 - DEBUG - ydb.table.SessionPool.53805  - put: Replying to waiter with a session <ydb.table.Session object at 0x7c6b04392ee0>
2026-04-11 06:50:02,800 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(ExecuteDataQuery, 33359cad-d016-4c09-ad27-8e5aba8ca7ee, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:02,801 - DEBUG - ydb.connection.53805  - _on_response_callback: RpcState(CreateSession, fbf6008e-8867-4a37-baae-613eaf2202d9, ghrun-tt6w5as7qa.auto.internal:8832): on response callback success
2026-04-11 06:50:02,803 - DEBUG - ydb.connection.53805  - _log_request: RpcState(ExecuteDataQuery, 33359cad-d016-4c09-ad27-8e5aba8ca7ee, ghrun-tt6w5as7qa.auto.internal:8832): request = { session_id: "ydb://session/3?node_id=50000&id=ZTU4OTZkNGUtODE0MWE0ZTktMTIxMzNhOWEtZDdlOTZkMGE%3D" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "upsert into `/Root/ydb/tests/functional/audit/test_auditlog.py/test-table` (id, value) values (4, 4), (5, 5)" } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2026-04-11 06:50:02,941 - DEBUG - ydb.connection.53805  - _log_response: RpcState(ExecuteDataQuery, 33359cad-d016-4c09-ad27-8e5aba8ca7ee, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2026-04-11 06:50:02,943 - DEBUG - ydb.table.SessionPool.53805  - put: Put on session <ydb.table.Session object at 0x7c6b04392ee0>
2026-04-11 06:50:03,046 - INFO - ya.test.53805  - pytest_runtest_teardown: Test teardown
2026-04-11 06:50:03,047 - DEBUG - ydb.table.SessionPool.53805  - stop: Requested session pool stop.
2026-04-11 06:50:03,047 - DEBUG - ydb.table.SessionPool.53805  - stop: Session pool is under stop, cancelling all in flight waiters.
2026-04-11 06:50:03,048 - DEBUG - ydb.table.SessionPool.53805  - _terminate_event: Terminated session pool.
2026-04-11 06:50:03,048 - DEBUG - ydb.table.SessionPool.53805  - stop: Destroying sessions in active queue
2026-04-11 06:50:03,049 - DEBUG - ydb.table.SessionPool.53805  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7c6b04392ee0>, reason: session-pool-terminated
2026-04-11 06:50:03,050 - DEBUG - ydb.table.SessionPool.53805  - _destroy: Session <ydb.table.Session object at 0x7c6b04392ee0> is no longer active. Current active count 0.
2026-04-11 06:50:03,051 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(DeleteSession, 4b77b8bf-07b8-40aa-87b2-89b276a8663a, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:03,052 - DEBUG - ydb.connection.53805  - _log_request: RpcState(DeleteSession, 4b77b8bf-07b8-40aa-87b2-89b276a8663a, ghrun-tt6w5as7qa.auto.internal:8832): request = { session_id: "ydb://session/3?node_id=50000&id=ZTU4OTZkNGUtODE0MWE0ZTktMTIxMzNhOWEtZDdlOTZkMGE%3D" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-04-11 06:50:03,055 - DEBUG - ydb.table.SessionPool.53805  - _destroy: Sent delete on session <ydb.table.Session object at 0x7c6b04392ee0>
2026-04-11 06:50:03,055 - DEBUG - ydb.table.SessionPool.53805  - stop: Destroyed active sessions
2026-04-11 06:50:03,056 - DEBUG - ydb.topic.53805  - close: Close topic client
2026-04-11 06:50:03,056 - DEBUG - ydb.topic.53805  - close: Topic client was closed
2026-04-11 06:50:03,057 - DEBUG - ydb.connection.53805  - close: Closing channel for endpoint localhost:24819
2026-04-11 06:50:03,058 - DEBUG - ydb.connection.53805  - _on_response_callback: RpcState(DeleteSession, 4b77b8bf-07b8-40aa-87b2-89b276a8663a, ghrun-tt6w5as7qa.auto.internal:8832): on response callback started
2026-04-11 06:50:03,059 - DEBUG - ydb.connection.53805  - _log_response: RpcState(DeleteSession, 4b77b8bf-07b8-40aa-87b2-89b276a8663a, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS } }
2026-04-11 06:50:03,060 - DEBUG - ydb.connection.53805  - _on_response_callback: RpcState(DeleteSession, 4b77b8bf-07b8-40aa-87b2-89b276a8663a, ghrun-tt6w5as7qa.auto.internal:8832): on response callback success
2026-04-11 06:50:03,064 - DEBUG - ydb.connection.53805  - close: Closing channel for endpoint ghrun-tt6w5as7qa.auto.internal:8832
2026-04-11 06:50:03,066 - INFO - ydb.pool.Discovery.53805  - run: Successfully terminated discovery process
2026-04-11 06:50:03,068 - DEBUG - ydb.table.SessionPool.53805  - stop: Requested session pool stop.
2026-04-11 06:50:03,068 - DEBUG - ydb.table.SessionPool.53805  - stop: Session pool is under stop, cancelling all in flight waiters.
2026-04-11 06:50:03,069 - DEBUG - ydb.table.SessionPool.53805  - stop: Destroying sessions in active queue
2026-04-11 06:50:03,069 - DEBUG - ydb.table.SessionPool.53805  - _destroy: Requested session destroy: <ydb.table.Session object at 0x7c7b03fd5760>, reason: session-pool-terminated
2026-04-11 06:50:03,069 - DEBUG - ydb.table.SessionPool.53805  - _destroy: Session <ydb.table.Session object at 0x7c7b03fd5760> is no longer active. Current active count 0.
2026-04-11 06:50:03,070 - DEBUG - ydb.connection.53805  - _prepare_call: RpcState(DeleteSession, e4be2487-5a61-40f2-be77-764e397faaa3, ghrun-tt6w5as7qa.auto.internal:8832): creating call state
2026-04-11 06:50:03,071 - DEBUG - ydb.connection.53805  - _log_request: RpcState(DeleteSession, e4be2487-5a61-40f2-be77-764e397faaa3, ghrun-tt6w5as7qa.auto.internal:8832): request = { session_id: "ydb://session/3?node_id=50000&id=YzRmYTViN2ItNjcwMDMzOGItYTBmMmJlZGMtNmVhOGIyZGE%3D" operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2026-04-11 06:50:03,072 - DEBUG - ydb.table.SessionPool.53805  - _terminate_event: Terminated session pool.
2026-04-11 06:50:03,075 - DEBUG - ydb.table.SessionPool.53805  - _destroy: Sent delete on session <ydb.table.Session object at 0x7c7b03fd5760>
2026-04-11 06:50:03,075 - DEBUG - ydb.table.SessionPool.53805  - stop: Destroyed active sessions
2026-04-11 06:50:03,076 - DEBUG - ydb.topic.53805  - close: Close topic client
2026-04-11 06:50:03,076 - DEBUG - ydb.topic.53805  - close: Topic client was closed
2026-04-11 06:50:03,077 - DEBUG - ydb.connection.53805  - close: Closing channel for endpoint localhost:24819
2026-04-11 06:50:03,078 - DEBUG - ydb.connection.53805  - close: Closing channel for endpoint ghrun-tt6w5as7qa.auto.internal:8832
2026-04-11 06:50:03,078 - INFO - ydb.pool.Discovery.53805  - run: Successfully terminated discovery process
2026-04-11 06:50:03,079 - DEBUG - ydb.connection.53805  - _on_response_callback: RpcState(DeleteSession, e4be2487-5a61-40f2-be77-764e397faaa3, ghrun-tt6w5as7qa.auto.internal:8832): on response callback started
2026-04-11 06:50:03,081 - DEBUG - ydb.tests.library.fixtures.53805  - ydb_database_ctx: destroy database /Root/ydb/tests/functional/audit/test_auditlog.py: remove path and dismantle internals
2026-04-11 06:50:03,082 - DEBUG - ydb.connection.53805  - _log_response: RpcState(DeleteSession, e4be2487-5a61-40f2-be77-764e397faaa3, ghrun-tt6w5as7qa.auto.internal:8832): response = { operation { ready: true status: SUCCESS } }
2026-04-11 06:50:03,082 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - remove_database: /Root/ydb/tests/functional/audit/test_auditlog.py
2026-04-11 06:50:03,083 - DEBUG - ydb.connection.53805  - _on_response_callback: RpcState(DeleteSession, e4be2487-5a61-40f2-be77-764e397faaa3, ghrun-tt6w5as7qa.auto.internal:8832): on response callback success
2026-04-11 06:50:03,084 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_send_op: /Root/ydb/tests/functional/audit/test_auditlog.py: send console operation, token None
2026-04-11 06:50:03,109 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_send_op: /Root/ydb/tests/functional/audit/test_auditlog.py: response from console: RemoveTenantResponse {
  Response {
    operation {
      id: "ydb://cmsrequest/5?tenant=/Root/ydb/tests/functional/audit/test_auditlog.py&cmstid=72057594037936131&txid=1775890203100727&action=2"
    }
  }
}
Status {
  Code: STATUS_CODE_UNSPECIFIED
}

2026-04-11 06:50:03,111 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_wait_op: /Root/ydb/tests/functional/audit/test_auditlog.py: wait console operation done
2026-04-11 06:50:03,629 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_wait_op: /Root/ydb/tests/functional/audit/test_auditlog.py: console operation done
2026-04-11 06:50:03,629 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_wait_tenant_gone: /Root/ydb/tests/functional/audit/test_auditlog.py: wait tenant gone
2026-04-11 06:50:03,647 - DEBUG - ydb.tests.library.harness.kikimr_cluster_interface.53805  - _remove_database_wait_tenant_gone: /Root/ydb/tests/functional/audit/test_auditlog.py: tenant gone
2026-04-11 06:50:03,647 - DEBUG - ydb.tests.library.fixtures.53805  - ydb_database_ctx: destroy database /Root/ydb/tests/functional/audit/test_auditlog.py: stop nodes
2026-04-11 06:50:10,652 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - stop: Stopped node localhost:8832/1
2026-04-11 06:50:10,653 - DEBUG - ydb.tests.library.fixtures.53805  - ydb_database_ctx: destroy database /Root/ydb/tests/functional/audit/test_auditlog.py: database down
2026-04-11 06:50:10,654 - INFO - ydb.tests.library.fixtures.53805  - ydb_cluster: destroy ydb_cluster for __tests__.test_auditlog
2026-04-11 06:50:17,656 - INFO - ydb.tests.library.harness.kikimr_runner.53805  - stop: Stopped node localhost:24819/1
2026-04-11 06:50:17,675 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61215
2026-04-11 06:50:17,676 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6661
2026-04-11 06:50:17,676 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4084
2026-04-11 06:50:17,676 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28378
2026-04-11 06:50:17,676 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8832
2026-04-11 06:50:17,677 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10250
2026-04-11 06:50:17,677 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22750
2026-04-11 06:50:17,677 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63190
2026-04-11 06:50:17,678 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24819
2026-04-11 06:50:17,678 - DEBUG - library.python.filelock.53805  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23795
