2025-11-11 14:34:09,686 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 14:34:09,686 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[180]
2025-11-11 14:34:09,687 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 14:34:09,687 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 14:34:09,688 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[180])
2025-11-11 14:34:09,689 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb
2025-11-11 14:34:09,689 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb -e grpc://localhost:9444 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-11 14:34:09,690 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-11-11 14:34:09,691 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-11-11 14:34:09,691 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9444', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-11 14:34:09,692 - DEBUG - ya.test - execute: Command pid: 4168150
2025-11-11 14:34:10,141 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) rc: 0
2025-11-11 14:34:10,141 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) elapsed time (sec): 0.4478120803833008
2025-11-11 14:34:10,141 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) idrss: 0
2025-11-11 14:34:10,141 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) inblock: 0
2025-11-11 14:34:10,142 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) isrss: 0
2025-11-11 14:34:10,142 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) ixrss: 0
2025-11-11 14:34:10,143 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) majflt: 0
2025-11-11 14:34:10,145 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) maxrss: 813404
2025-11-11 14:34:10,145 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) minflt: 3538
2025-11-11 14:34:10,145 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) msgrcv: 0
2025-11-11 14:34:10,146 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) msgsnd: 0
2025-11-11 14:34:10,146 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) nivcsw: 23
2025-11-11 14:34:10,149 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) nsignals: 0
2025-11-11 14:34:10,149 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) nswap: 0
2025-11-11 14:34:10,149 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) nvcsw: 177
2025-11-11 14:34:10,149 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) oublock: 8
2025-11-11 14:34:10,149 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) stime: 0.024326999999999998
2025-11-11 14:34:10,150 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) utime: 0.051675
2025-11-11 14:34:10,150 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) wtime: 0.449
2025-11-11 14:34:10,150 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) output:
Init tables ...
Init tables ...Ok

2025-11-11 14:34:10,150 - DEBUG - ya.test - _save_outputs: Command (pid 4168150) errors:

2025-11-11 14:34:10,150 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=
2025-11-11 14:34:10,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c998365a-b297-49c4-8070-8159dd148555, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:10,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c998365a-b297-49c4-8070-8159dd148555, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "ALTER OBJECT `//Root/log` (TYPE TABLE) SET (ACTION=UPSERT_OPTIONS, `SCAN_READER_POLICY_NAME`=`SIMPLE`) " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:10,153 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c998365a-b297-49c4-8070-8159dd148555, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:10,196 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=
2025-11-11 14:34:10,196 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 14:34:10,196 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb -e grpc://localhost:9444 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-11 14:34:10,198 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-11-11 14:34:10,198 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-11-11 14:34:10,199 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9444', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-11 14:34:10,202 - DEBUG - ya.test - execute: Command pid: 4168764
2025-11-11 14:34:14,161 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 14:34:14,162 - DEBUG - ya.test - _save_outputs: Command (pid 4168764) rc: 0
2025-11-11 14:34:14,162 - DEBUG - ya.test - _save_outputs: Command (pid 4168764) elapsed time (sec): 3.958388328552246
2025-11-11 14:34:14,162 - DEBUG - ya.test - _save_outputs: Command (pid 4168764) wtime: 3.963
2025-11-11 14:34:14,162 - DEBUG - ya.test - _save_outputs: Command (pid 4168764) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	     10	10	0	0	711	919	919	919
2	     10	10	0	0	879	911	911	911
3	     20	20	0	0	903	947	963	963

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     40	13.3333	0	0	891	947	963	963

2025-11-11 14:34:14,163 - DEBUG - ya.test - _save_outputs: Command (pid 4168764) errors:

2025-11-11 14:34:14,163 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:14,164 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e946499-e9b0-487b-a4a1-973bfd0ccb4e, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,165 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e946499-e9b0-487b-a4a1-973bfd0ccb4e, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,165 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e946499-e9b0-487b-a4a1-973bfd0ccb4e, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,517 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:14,518 - INFO - root - test_log_deviation: Count rows after insert 20000 before wait
2025-11-11 14:34:14,518 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:14,519 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 84f99273-51d6-401b-9271-c1f5370ec5df, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,519 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 84f99273-51d6-401b-9271-c1f5370ec5df, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,520 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 84f99273-51d6-401b-9271-c1f5370ec5df, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,638 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:14,638 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==
2025-11-11 14:34:14,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a4d75be1-e3ab-42bc-ac6d-970a4dc7e744, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,639 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a4d75be1-e3ab-42bc-ac6d-970a4dc7e744, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,640 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a4d75be1-e3ab-42bc-ac6d-970a4dc7e744, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,721 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==
2025-11-11 14:34:14,722 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 14:34:14,722 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-11 14:34:14,723 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-11 14:34:14,723 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb -e grpc://localhost:9444 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-11 14:34:14,724 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:14,724 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb -e grpc://localhost:9444 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-11 14:34:14,725 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb -e grpc://localhost:9444 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-11 14:34:14,727 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.7
2025-11-11 14:34:14,727 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c1786682-4211-4dfa-95b8-465cfcb49306, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,727 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:14,732 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.8
2025-11-11 14:34:14,733 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c1786682-4211-4dfa-95b8-465cfcb49306, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,734 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 06f1450c-ab14-4b9a-bfff-ffeb0216b941, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,736 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.9
2025-11-11 14:34:14,737 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-11-11 14:34:14,738 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=
2025-11-11 14:34:14,738 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c1786682-4211-4dfa-95b8-465cfcb49306, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,740 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.8
2025-11-11 14:34:14,740 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 06f1450c-ab14-4b9a-bfff-ffeb0216b941, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,742 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9444', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-11 14:34:14,743 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9bc6301b-51b7-4cbc-8b83-09370e563bd8, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,743 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=
2025-11-11 14:34:14,745 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9444', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-11 14:34:14,747 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 06f1450c-ab14-4b9a-bfff-ffeb0216b941, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,748 - DEBUG - ya.test - execute: Command pid: 4173733
2025-11-11 14:34:14,749 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9bc6301b-51b7-4cbc-8b83-09370e563bd8, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,755 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9bc6301b-51b7-4cbc-8b83-09370e563bd8, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,750 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=
2025-11-11 14:34:14,756 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08cbb036-6f38-4fd1-9267-9ad40e9e4aa1, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,753 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.9
2025-11-11 14:34:14,750 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f1c2b971-b6c3-45e2-8be0-611f79658346, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,751 - DEBUG - ya.test - execute: Command pid: 4173734
2025-11-11 14:34:14,756 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=
2025-11-11 14:34:14,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08cbb036-6f38-4fd1-9267-9ad40e9e4aa1, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f1c2b971-b6c3-45e2-8be0-611f79658346, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,759 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5481020b-c217-4955-9e49-ff54a3495cac, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,759 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9444', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-11 14:34:14,759 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=
2025-11-11 14:34:14,761 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5481020b-c217-4955-9e49-ff54a3495cac, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,761 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08cbb036-6f38-4fd1-9267-9ad40e9e4aa1, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,770 - DEBUG - ya.test - execute: Command pid: 4173735
2025-11-11 14:34:14,770 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f1c2b971-b6c3-45e2-8be0-611f79658346, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,771 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 433c4686-8f0f-47f9-a8b3-601a3df4e9f2, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,771 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:14,775 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3594aa53-786a-4539-8d6e-e5dc7ce3e24b, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,775 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 433c4686-8f0f-47f9-a8b3-601a3df4e9f2, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,778 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 433c4686-8f0f-47f9-a8b3-601a3df4e9f2, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,773 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5481020b-c217-4955-9e49-ff54a3495cac, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,777 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:14,776 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3594aa53-786a-4539-8d6e-e5dc7ce3e24b, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,780 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d823f17-7a8d-4995-822c-8f7b8dfeaf9d, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,780 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==
2025-11-11 14:34:14,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3594aa53-786a-4539-8d6e-e5dc7ce3e24b, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,782 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d823f17-7a8d-4995-822c-8f7b8dfeaf9d, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,783 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 243ed529-7b81-42a0-8c6b-31f900a069cb, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:14,784 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d823f17-7a8d-4995-822c-8f7b8dfeaf9d, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:14,785 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 243ed529-7b81-42a0-8c6b-31f900a069cb, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:14,786 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 243ed529-7b81-42a0-8c6b-31f900a069cb, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:19,623 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 14:34:19,624 - DEBUG - ya.test - _save_outputs: Command (pid 4173735) rc: 0
2025-11-11 14:34:19,624 - DEBUG - ya.test - _save_outputs: Command (pid 4173735) elapsed time (sec): 4.850592613220215
2025-11-11 14:34:19,624 - DEBUG - ya.test - _save_outputs: Command (pid 4173735) wtime: 4.862
2025-11-11 14:34:19,625 - DEBUG - ya.test - _save_outputs: Command (pid 4173735) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	18	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	      0	0	0	28	0	0	0	0

2025-11-11 14:34:19,625 - DEBUG - ya.test - _save_outputs: Command (pid 4173735) errors:

2025-11-11 14:34:19,705 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 14:34:19,706 - DEBUG - ya.test - _save_outputs: Command (pid 4173734) rc: 0
2025-11-11 14:34:19,706 - DEBUG - ya.test - _save_outputs: Command (pid 4173734) elapsed time (sec): 4.947467803955078
2025-11-11 14:34:19,706 - DEBUG - ya.test - _save_outputs: Command (pid 4173734) wtime: 4.955
2025-11-11 14:34:19,706 - DEBUG - ya.test - _save_outputs: Command (pid 4173734) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	19	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	      0	0	0	29	0	0	0	0

2025-11-11 14:34:19,707 - DEBUG - ya.test - _save_outputs: Command (pid 4173734) errors:

2025-11-11 14:34:21,225 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=
2025-11-11 14:34:21,225 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=
2025-11-11 14:34:21,226 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89f45ec7-6732-4b3e-980f-a4ac090ae740, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89f45ec7-6732-4b3e-980f-a4ac090ae740, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,227 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89f45ec7-6732-4b3e-980f-a4ac090ae740, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,232 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:21,232 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:21,233 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 897898eb-372e-4428-bb60-c7523bc72072, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,233 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 897898eb-372e-4428-bb60-c7523bc72072, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,234 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 897898eb-372e-4428-bb60-c7523bc72072, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,240 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==
2025-11-11 14:34:21,241 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==
2025-11-11 14:34:21,241 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3d2509f-338c-4645-b2bb-1a2acddf1158, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,242 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3d2509f-338c-4645-b2bb-1a2acddf1158, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,243 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3d2509f-338c-4645-b2bb-1a2acddf1158, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,243 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=
2025-11-11 14:34:21,244 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=
2025-11-11 14:34:21,244 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e09b6f8e-f085-460a-9916-92e1dd886bf5, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,245 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e09b6f8e-f085-460a-9916-92e1dd886bf5, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,246 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e09b6f8e-f085-460a-9916-92e1dd886bf5, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,252 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:21,253 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:21,253 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 622b0660-ab83-459d-bf9d-748b0ba5e6b5, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,254 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 622b0660-ab83-459d-bf9d-748b0ba5e6b5, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,254 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 622b0660-ab83-459d-bf9d-748b0ba5e6b5, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,257 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:21,257 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:21,258 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d2bf6dc-aac0-40e4-80a6-ec50aee056c0, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d2bf6dc-aac0-40e4-80a6-ec50aee056c0, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d2bf6dc-aac0-40e4-80a6-ec50aee056c0, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,262 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=
2025-11-11 14:34:21,263 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=
2025-11-11 14:34:21,263 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76963df0-4a44-492c-8d27-abd48fcf803e, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,264 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76963df0-4a44-492c-8d27-abd48fcf803e, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,265 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76963df0-4a44-492c-8d27-abd48fcf803e, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,275 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=
2025-11-11 14:34:21,277 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=
2025-11-11 14:34:21,276 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=
2025-11-11 14:34:21,278 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=
2025-11-11 14:34:21,279 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ff97579a-3955-487f-a863-091860d2c5ef, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,279 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ff97579a-3955-487f-a863-091860d2c5ef, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,280 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ff97579a-3955-487f-a863-091860d2c5ef, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,277 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fde5d42a-0e17-42d2-a0b3-e17a593f79bc, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,282 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fde5d42a-0e17-42d2-a0b3-e17a593f79bc, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,283 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fde5d42a-0e17-42d2-a0b3-e17a593f79bc, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:21,332 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:21,332 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:21,333 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9474a884-c3c1-480a-bf0f-594c7aeb908f, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:21,333 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9474a884-c3c1-480a-bf0f-594c7aeb908f, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:21,334 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9474a884-c3c1-480a-bf0f-594c7aeb908f, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:22,457 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:22,460 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:22,461 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e59c8cd7-3d49-421e-affb-a0dcae3b03a2, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:22,462 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e59c8cd7-3d49-421e-affb-a0dcae3b03a2, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:22,468 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e59c8cd7-3d49-421e-affb-a0dcae3b03a2, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:23,174 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:23,176 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:23,182 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0cc45d83-5340-49af-b8c1-2635e25c16f6, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:23,186 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0cc45d83-5340-49af-b8c1-2635e25c16f6, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:23,195 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0cc45d83-5340-49af-b8c1-2635e25c16f6, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:23,353 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:23,559 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:23,563 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:23,564 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d3c46f86-9b76-4529-97ad-ed78a2c94176, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:23,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d3c46f86-9b76-4529-97ad-ed78a2c94176, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:23,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d3c46f86-9b76-4529-97ad-ed78a2c94176, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:23,763 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 14:34:23,779 - DEBUG - ya.test - _save_outputs: Command (pid 4173733) rc: 0
2025-11-11 14:34:23,779 - DEBUG - ya.test - _save_outputs: Command (pid 4173733) elapsed time (sec): 9.011177062988281
2025-11-11 14:34:23,779 - DEBUG - ya.test - _save_outputs: Command (pid 4173733) wtime: 9.018
2025-11-11 14:34:23,780 - DEBUG - ya.test - _save_outputs: Command (pid 4173733) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	0	0	0	0	0
3	     10	10	0	0	8575	8767	8767	8767

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     10	3.33333	0	0	8575	8767	8767	8767

2025-11-11 14:34:23,780 - DEBUG - ya.test - _save_outputs: Command (pid 4173733) errors:

2025-11-11 14:34:23,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:23,786 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:23,788 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 422ec883-51a8-4268-b542-544c4379a5d8, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:23,796 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 422ec883-51a8-4268-b542-544c4379a5d8, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:23,797 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 422ec883-51a8-4268-b542-544c4379a5d8, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:23,857 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:23,863 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:23,868 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 108dcea0-cf23-420d-b42e-ec309e0b2105, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:23,875 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 108dcea0-cf23-420d-b42e-ec309e0b2105, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:23,879 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 108dcea0-cf23-420d-b42e-ec309e0b2105, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:23,907 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=
2025-11-11 14:34:23,920 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:23,923 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 802f676f-b480-441e-a0ea-f1442cf850fd, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:23,929 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 802f676f-b480-441e-a0ea-f1442cf850fd, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:23,933 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 802f676f-b480-441e-a0ea-f1442cf850fd, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:24,104 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:24,142 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:24,166 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=
2025-11-11 14:34:24,185 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b949aade-b380-4d54-9d5d-f8405a705d6a, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:24,188 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b949aade-b380-4d54-9d5d-f8405a705d6a, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:24,190 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b949aade-b380-4d54-9d5d-f8405a705d6a, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:24,200 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=
2025-11-11 14:34:24,201 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:24,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:24,242 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:24,244 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 41f6a9a8-3f95-456c-8310-f39424e70331, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:24,244 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:24,248 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=
2025-11-11 14:34:24,249 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 41f6a9a8-3f95-456c-8310-f39424e70331, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:24,250 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 356a51ab-f8d0-4d7f-8cff-a1915e3641d0, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:24,250 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=
2025-11-11 14:34:24,267 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 356a51ab-f8d0-4d7f-8cff-a1915e3641d0, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:24,269 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 41f6a9a8-3f95-456c-8310-f39424e70331, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:24,271 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c7667ca-53ee-44d3-ae7f-88c0305bcc0e, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:24,271 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:24,280 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c7667ca-53ee-44d3-ae7f-88c0305bcc0e, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:24,280 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 356a51ab-f8d0-4d7f-8cff-a1915e3641d0, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:24,282 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 99a5b5ac-ef45-46d5-81b6-b8426dbbde3c, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:24,284 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c7667ca-53ee-44d3-ae7f-88c0305bcc0e, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:24,300 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 99a5b5ac-ef45-46d5-81b6-b8426dbbde3c, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:24,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 99a5b5ac-ef45-46d5-81b6-b8426dbbde3c, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:24,307 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==
2025-11-11 14:34:24,312 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:24,324 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2fe3a0ef-1d10-4c47-9ba2-bb3ec110a9c5, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:24,324 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2fe3a0ef-1d10-4c47-9ba2-bb3ec110a9c5, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:24,330 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2fe3a0ef-1d10-4c47-9ba2-bb3ec110a9c5, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:24,510 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=
2025-11-11 14:34:24,567 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=
2025-11-11 14:34:24,568 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e78b989d-31bc-4e69-b672-69556c0547c6, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:24,576 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e78b989d-31bc-4e69-b672-69556c0547c6, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:24,583 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e78b989d-31bc-4e69-b672-69556c0547c6, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:24,645 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=
2025-11-11 14:34:24,691 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==
2025-11-11 14:34:24,692 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b51ab714-2a1a-4897-a11e-5c7ff3577f34, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:24,692 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b51ab714-2a1a-4897-a11e-5c7ff3577f34, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:24,693 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b51ab714-2a1a-4897-a11e-5c7ff3577f34, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:25,022 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=
2025-11-11 14:34:25,022 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=
2025-11-11 14:34:25,023 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a7a168a6-0ea3-4d0c-ae37-4c9adba9c6aa, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:25,023 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a7a168a6-0ea3-4d0c-ae37-4c9adba9c6aa, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:25,026 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=
2025-11-11 14:34:25,027 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a7a168a6-0ea3-4d0c-ae37-4c9adba9c6aa, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:25,032 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:25,033 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:25,034 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:25,037 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5
2025-11-11 14:34:25,038 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=
2025-11-11 14:34:25,040 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 80ae0466-a7a8-4323-8925-c1d248d59036, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:25,041 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 80ae0466-a7a8-4323-8925-c1d248d59036, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:25,042 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 80ae0466-a7a8-4323-8925-c1d248d59036, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:25,040 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=
2025-11-11 14:34:25,042 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb607ece-754e-46f7-9b3e-787e5cf51ae8, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:25,043 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb607ece-754e-46f7-9b3e-787e5cf51ae8, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:25,044 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=
2025-11-11 14:34:25,044 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c67dbd1b-c8eb-49d5-a697-7d4fe7adb5e0, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:25,044 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb607ece-754e-46f7-9b3e-787e5cf51ae8, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:25,045 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c67dbd1b-c8eb-49d5-a697-7d4fe7adb5e0, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:25,046 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c67dbd1b-c8eb-49d5-a697-7d4fe7adb5e0, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:25,229 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=
2025-11-11 14:34:25,230 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:25,230 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==
2025-11-11 14:34:25,231 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 66440ae9-167a-4692-be2b-0690b504ec13, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:25,231 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:25,232 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 66440ae9-167a-4692-be2b-0690b504ec13, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:25,233 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1dcdcbc2-0ba5-466f-a62a-5736a9848199, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:25,234 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1dcdcbc2-0ba5-466f-a62a-5736a9848199, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:25,234 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 66440ae9-167a-4692-be2b-0690b504ec13, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:25,235 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1dcdcbc2-0ba5-466f-a62a-5736a9848199, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:25,613 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=
2025-11-11 14:34:25,742 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=
2025-11-11 14:34:25,743 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=
2025-11-11 14:34:25,748 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=
2025-11-11 14:34:25,784 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==
2025-11-11 14:34:25,785 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw
2025-11-11 14:34:25,786 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:25,787 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68e0d7ec-d897-438b-bbf2-90dc944792d4, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:25,787 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68e0d7ec-d897-438b-bbf2-90dc944792d4, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-11 14:34:25,788 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68e0d7ec-d897-438b-bbf2-90dc944792d4, ghrun-3ynj4chmly.auto.internal:9444): response = { <_MultiThreadedRendezvous object> }
2025-11-11 14:34:25,880 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=
2025-11-11 14:34:25,880 - INFO - root - test_log_deviation: check insert: 25000 20000
2025-11-11 14:34:25,881 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.10
2025-11-11 14:34:25,882 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.10
2025-11-11 14:34:25,882 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/leud/001f07/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9444', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-11 14:34:25,883 - DEBUG - ya.test - execute: Command pid: 4186526
2025-11-11 14:34:26,065 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) rc: 0
2025-11-11 14:34:26,066 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) elapsed time (sec): 0.18177008628845215
2025-11-11 14:34:26,066 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) idrss: 0
2025-11-11 14:34:26,066 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) inblock: 0
2025-11-11 14:34:26,066 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) isrss: 0
2025-11-11 14:34:26,067 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) ixrss: 0
2025-11-11 14:34:26,067 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) majflt: 0
2025-11-11 14:34:26,067 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) maxrss: 813404
2025-11-11 14:34:26,067 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) minflt: 3529
2025-11-11 14:34:26,067 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) msgrcv: 0
2025-11-11 14:34:26,067 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) msgsnd: 0
2025-11-11 14:34:26,067 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) nivcsw: 19
2025-11-11 14:34:26,067 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) nsignals: 0
2025-11-11 14:34:26,068 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) nswap: 0
2025-11-11 14:34:26,068 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) nvcsw: 167
2025-11-11 14:34:26,068 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) oublock: 8
2025-11-11 14:34:26,068 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) stime: 0.030579
2025-11-11 14:34:26,068 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) utime: 0.042401
2025-11-11 14:34:26,068 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) wtime: 0.183
2025-11-11 14:34:26,068 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) output:
Remove path log...
Remove path log...Ok

2025-11-11 14:34:26,068 - DEBUG - ya.test - _save_outputs: Command (pid 4186526) errors:

2025-11-11 14:34:26,070 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-11 14:34:26,071 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 46ff35dc-9491-4521-9460-1458543e5c66, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,071 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 46ff35dc-9491-4521-9460-1458543e5c66, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=MWViMTYxZTEtY2M3ZmU5ZDItNWNkZDY4Mi02Nzk1NzE5" }
2025-11-11 14:34:26,076 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 46ff35dc-9491-4521-9460-1458543e5c66, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,077 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d62d0f2c-b145-400a-98ee-845dc3ad098c, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,077 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d62d0f2c-b145-400a-98ee-845dc3ad098c, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=ZTQzODc0YzYtYWJhMDAzNzctZmE5ODU1ZGQtYTU4Mjg2ZGQ=" }
2025-11-11 14:34:26,079 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d62d0f2c-b145-400a-98ee-845dc3ad098c, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,079 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 194fa0bd-15f2-41e1-8208-a3345957bc06, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,080 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 39828402-ce4c-4d1b-9267-8475b9c2aa11, ghrun-3ynj4chmly.auto.internal:9444): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 14:34:26,081 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 194fa0bd-15f2-41e1-8208-a3345957bc06, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTRhZTQ5ZTMtZjM4OGM1MS00OThlODRjOS0zNzc3NDk2NA==" }
2025-11-11 14:34:26,083 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 194fa0bd-15f2-41e1-8208-a3345957bc06, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,084 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c5409514-0d29-40f5-bbd1-df6e6424caab, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,084 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c5409514-0d29-40f5-bbd1-df6e6424caab, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTlhY2Y5NjMtYjdkNGJmZGMtM2I4OTkwNzAtYzhmMzBiMTM=" }
2025-11-11 14:34:26,085 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c5409514-0d29-40f5-bbd1-df6e6424caab, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,086 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ab1ba30f-bf80-4846-bc25-2d73ed11bfab, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,086 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ab1ba30f-bf80-4846-bc25-2d73ed11bfab, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmJiMTkwMmMtM2ZkMDZiYjUtM2ZlZDcyNjItOTU0MzljOWI=" }
2025-11-11 14:34:26,087 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 54fda926-9f3c-4338-baba-fe8167eed6bd, ghrun-3ynj4chmly.auto.internal:9444): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 14:34:26,089 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ab1ba30f-bf80-4846-bc25-2d73ed11bfab, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,090 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, f1da4f8f-f36e-4bf1-a600-dd2ecaaf81e7, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,090 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, f1da4f8f-f36e-4bf1-a600-dd2ecaaf81e7, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NTE5MWVhM2ItNmYxNGQwZjEtZGRhMGY2MGMtZGY1ZTMzYjM=" }
2025-11-11 14:34:26,092 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, f1da4f8f-f36e-4bf1-a600-dd2ecaaf81e7, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,093 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 9950d155-c9a1-4aa6-9599-0faa053e0750, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,093 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 7b6bf9de-45b9-4bd4-840a-fe8ec01d6a99, ghrun-3ynj4chmly.auto.internal:9444): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 14:34:26,093 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 9950d155-c9a1-4aa6-9599-0faa053e0750, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=NGJkYTJjZGQtNjNkODExNzQtNThjZDhiNGMtYTE4YzI3YWQ=" }
2025-11-11 14:34:26,096 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 9950d155-c9a1-4aa6-9599-0faa053e0750, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,097 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 27fc843c-6154-40db-9ccf-85d224401e79, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,098 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 27fc843c-6154-40db-9ccf-85d224401e79, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YTY3NzI3OGEtYThiODk3NDAtYWRmNjg2ZjktZmJkZjY3OA==" }
2025-11-11 14:34:26,099 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, fd84937e-4d16-4c99-a753-0d734e57c065, ghrun-3ynj4chmly.auto.internal:9444): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 14:34:26,100 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 27fc843c-6154-40db-9ccf-85d224401e79, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,101 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 3af2bb8d-7665-48b0-bde5-08229f1e3405, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,101 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, d09db5bd-7b31-4582-be0f-ac8263c4b5d3, ghrun-3ynj4chmly.auto.internal:9444): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 14:34:26,102 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 3af2bb8d-7665-48b0-bde5-08229f1e3405, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=YmI5MDQ4NzktYmE4NmFkMy05ODk1ZWU1LWY3MzlhNzkw" }
2025-11-11 14:34:26,108 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 3af2bb8d-7665-48b0-bde5-08229f1e3405, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,109 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 9793e9d3-d4b7-4103-8bb2-74891b330ea5, ghrun-3ynj4chmly.auto.internal:9444): creating call state
2025-11-11 14:34:26,109 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 9517e37d-9b94-4fab-a102-3c9d231434d8, ghrun-3ynj4chmly.auto.internal:9444): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 14:34:26,110 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 9793e9d3-d4b7-4103-8bb2-74891b330ea5, ghrun-3ynj4chmly.auto.internal:9444): request = { session_id: "ydb://session/3?node_id=1&id=OTE2MjQ4MmUtODM2MDMyMmUtZDUyZjZlYmEtNGQ4MmQ5ZDg=" }
2025-11-11 14:34:26,111 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 9793e9d3-d4b7-4103-8bb2-74891b330ea5, ghrun-3ynj4chmly.auto.internal:9444): response = { status: SUCCESS }
2025-11-11 14:34:26,112 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-11 14:34:26,112 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, fc938efe-3619-4d89-98a6-05af673aced7, ghrun-3ynj4chmly.auto.internal:9444): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 14:34:26,113 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 14:34:26,114 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 14:34:26,114 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:9444
2025-11-11 14:34:26,115 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:9444
2025-11-11 14:34:26,116 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 14:34:29,122 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:9444/1
2025-11-11 14:34:29,123 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26617
2025-11-11 14:34:29,124 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12485
2025-11-11 14:34:29,124 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11250
2025-11-11 14:34:29,124 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9444
2025-11-11 14:34:29,124 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25744
