2025-11-11 13:04:07,433 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 13:04:07,433 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[180]
2025-11-11 13:04:07,433 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 13:04:07,433 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 13:04:07,436 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[180])
2025-11-11 13:04:07,437 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb
2025-11-11 13:04:07,438 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb -e grpc://localhost:24475 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-11 13:04:07,439 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-11-11 13:04:07,439 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-11-11 13:04:07,440 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:24475', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-11 13:04:07,443 - DEBUG - ya.test - execute: Command pid: 2714874
2025-11-11 13:04:07,969 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) rc: 0
2025-11-11 13:04:07,969 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) elapsed time (sec): 0.5171139240264893
2025-11-11 13:04:07,970 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) idrss: 0
2025-11-11 13:04:07,970 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) inblock: 0
2025-11-11 13:04:07,970 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) isrss: 0
2025-11-11 13:04:07,978 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) ixrss: 0
2025-11-11 13:04:07,978 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) majflt: 0
2025-11-11 13:04:07,978 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) maxrss: 467720
2025-11-11 13:04:07,983 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) minflt: 3541
2025-11-11 13:04:07,984 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) msgrcv: 0
2025-11-11 13:04:07,984 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) msgsnd: 0
2025-11-11 13:04:07,984 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) nivcsw: 33
2025-11-11 13:04:07,984 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) nsignals: 0
2025-11-11 13:04:07,984 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) nswap: 0
2025-11-11 13:04:07,984 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) nvcsw: 205
2025-11-11 13:04:07,985 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) oublock: 8
2025-11-11 13:04:07,988 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) stime: 0.025327
2025-11-11 13:04:07,989 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) utime: 0.054875
2025-11-11 13:04:07,989 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) wtime: 0.52
2025-11-11 13:04:07,995 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) output:
Init tables ...
Init tables ...Ok

2025-11-11 13:04:07,996 - DEBUG - ya.test - _save_outputs: Command (pid 2714874) errors:

2025-11-11 13:04:07,996 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:07,997 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, faad1052-6afb-462d-b377-7e5946c39895, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:07,997 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, faad1052-6afb-462d-b377-7e5946c39895, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=" 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 13:04:08,011 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, faad1052-6afb-462d-b377-7e5946c39895, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:08,045 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:08,053 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 13:04:08,053 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb -e grpc://localhost:24475 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-11 13:04:08,054 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-11-11 13:04:08,055 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-11-11 13:04:08,057 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:24475', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-11 13:04:08,059 - DEBUG - ya.test - execute: Command pid: 2715470
2025-11-11 13:04:12,088 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 13:04:12,101 - DEBUG - ya.test - _save_outputs: Command (pid 2715470) rc: 0
2025-11-11 13:04:12,102 - DEBUG - ya.test - _save_outputs: Command (pid 2715470) elapsed time (sec): 4.028989791870117
2025-11-11 13:04:12,102 - DEBUG - ya.test - _save_outputs: Command (pid 2715470) wtime: 4.031
2025-11-11 13:04:12,102 - DEBUG - ya.test - _save_outputs: Command (pid 2715470) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      7	7	0	0	787	975	975	975
2	     10	10	0	0	987	1111	1111	1111
3	     19	19	0	0	967	991	1011	1011

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     36	12	0	0	971	1031	1111	1111

2025-11-11 13:04:12,103 - DEBUG - ya.test - _save_outputs: Command (pid 2715470) errors:

2025-11-11 13:04:12,103 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=
2025-11-11 13:04:12,104 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7dcb8d82-06fd-4ce9-b85d-5c59ce5588b6, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,116 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7dcb8d82-06fd-4ce9-b85d-5c59ce5588b6, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=" 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 13:04:12,117 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7dcb8d82-06fd-4ce9-b85d-5c59ce5588b6, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,476 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=
2025-11-11 13:04:12,477 - INFO - root - test_log_deviation: Count rows after insert 18000 before wait
2025-11-11 13:04:12,497 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:12,501 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 51783d77-4a70-4bf0-8d3c-5942329e9928, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,502 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 51783d77-4a70-4bf0-8d3c-5942329e9928, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy" 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 13:04:12,503 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 51783d77-4a70-4bf0-8d3c-5942329e9928, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,663 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:12,663 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=
2025-11-11 13:04:12,664 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8aa446b3-0f87-4204-81b0-abff35d94000, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,665 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8aa446b3-0f87-4204-81b0-abff35d94000, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=" 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 13:04:12,666 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8aa446b3-0f87-4204-81b0-abff35d94000, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,830 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=
2025-11-11 13:04:12,831 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 13:04:12,832 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-11 13:04:12,833 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-11 13:04:12,833 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:12,835 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb -e grpc://localhost:24475 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-11 13:04:12,835 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb -e grpc://localhost:24475 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-11 13:04:12,837 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb -e grpc://localhost:24475 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-11 13:04:12,838 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d593158-8717-4e18-b864-9f47040d80d9, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,838 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==
2025-11-11 13:04:12,840 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.7
2025-11-11 13:04:12,848 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d593158-8717-4e18-b864-9f47040d80d9, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,850 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3cc3a93-a1e3-4bae-9026-eb773b0ec851, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,850 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=
2025-11-11 13:04:12,854 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3cc3a93-a1e3-4bae-9026-eb773b0ec851, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,852 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d593158-8717-4e18-b864-9f47040d80d9, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,852 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.8
2025-11-11 13:04:12,854 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.9
2025-11-11 13:04:12,855 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8e60891a-a2b6-419b-9c14-fb8c6d4c87c8, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,865 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8e60891a-a2b6-419b-9c14-fb8c6d4c87c8, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,866 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8e60891a-a2b6-419b-9c14-fb8c6d4c87c8, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,856 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3cc3a93-a1e3-4bae-9026-eb773b0ec851, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,871 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.9
2025-11-11 13:04:12,855 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-11-11 13:04:12,855 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=
2025-11-11 13:04:12,869 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.8
2025-11-11 13:04:12,873 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:24475', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-11 13:04:12,874 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6af0797e-ebe3-4816-ad93-a7dabb9bcfa4, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,880 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6af0797e-ebe3-4816-ad93-a7dabb9bcfa4, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,875 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:12,875 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:24475', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-11 13:04:12,879 - DEBUG - ya.test - execute: Command pid: 2717809
2025-11-11 13:04:12,876 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:24475', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-11 13:04:12,883 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67c484f3-ecce-4228-90b7-313b068f51e0, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,890 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67c484f3-ecce-4228-90b7-313b068f51e0, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,883 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:12,885 - DEBUG - ya.test - execute: Command pid: 2717821
2025-11-11 13:04:12,887 - DEBUG - ya.test - execute: Command pid: 2717822
2025-11-11 13:04:12,881 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6af0797e-ebe3-4816-ad93-a7dabb9bcfa4, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,892 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67c484f3-ecce-4228-90b7-313b068f51e0, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,892 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1c014175-70d3-4b70-b098-689d8d34931f, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,893 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:12,907 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1c014175-70d3-4b70-b098-689d8d34931f, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,908 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5bf10bb8-967f-4e16-9104-f73f1518cafc, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,909 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=
2025-11-11 13:04:12,912 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1c014175-70d3-4b70-b098-689d8d34931f, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,916 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5bf10bb8-967f-4e16-9104-f73f1518cafc, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a147b5c4-86f3-4c54-b517-c5f26848dcf4, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,917 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:12,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e7abb4db-ca29-4997-b982-aca0659c2f27, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,921 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e7abb4db-ca29-4997-b982-aca0659c2f27, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,921 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e7abb4db-ca29-4997-b982-aca0659c2f27, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,920 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a147b5c4-86f3-4c54-b517-c5f26848dcf4, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,924 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a147b5c4-86f3-4c54-b517-c5f26848dcf4, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,922 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=
2025-11-11 13:04:12,925 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 42745717-099d-4390-90cb-2ade6ec3e123, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:12,926 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 42745717-099d-4390-90cb-2ade6ec3e123, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:12,927 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 42745717-099d-4390-90cb-2ade6ec3e123, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:12,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5bf10bb8-967f-4e16-9104-f73f1518cafc, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:17,943 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 13:04:17,944 - DEBUG - ya.test - _save_outputs: Command (pid 2717822) rc: 0
2025-11-11 13:04:17,944 - DEBUG - ya.test - _save_outputs: Command (pid 2717822) elapsed time (sec): 5.043929576873779
2025-11-11 13:04:17,945 - DEBUG - ya.test - _save_outputs: Command (pid 2717822) wtime: 5.058
2025-11-11 13:04:17,945 - DEBUG - ya.test - _save_outputs: Command (pid 2717822) 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	12	0	0	0	0

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

2025-11-11 13:04:17,947 - DEBUG - ya.test - _save_outputs: Command (pid 2717822) errors:

2025-11-11 13:04:17,960 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 13:04:17,960 - DEBUG - ya.test - _save_outputs: Command (pid 2717809) rc: 0
2025-11-11 13:04:17,960 - DEBUG - ya.test - _save_outputs: Command (pid 2717809) elapsed time (sec): 5.073992729187012
2025-11-11 13:04:17,960 - DEBUG - ya.test - _save_outputs: Command (pid 2717809) wtime: 5.082
2025-11-11 13:04:17,960 - DEBUG - ya.test - _save_outputs: Command (pid 2717809) 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	13	0	0	0	0

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

2025-11-11 13:04:17,961 - DEBUG - ya.test - _save_outputs: Command (pid 2717809) errors:

2025-11-11 13:04:20,864 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:20,864 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:20,865 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec2c29bd-d699-4f10-b8dc-d3528062fcb5, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:20,866 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec2c29bd-d699-4f10-b8dc-d3528062fcb5, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=" 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 13:04:20,873 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:20,879 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:20,879 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==
2025-11-11 13:04:20,884 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec2c29bd-d699-4f10-b8dc-d3528062fcb5, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:20,894 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=
2025-11-11 13:04:20,895 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:20,898 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=
2025-11-11 13:04:20,901 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:20,909 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:20,904 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:20,910 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=
2025-11-11 13:04:20,912 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d54cfe51-1938-4a63-9eb2-99c2b395a118, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:20,900 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36554f89-c638-4074-92f9-43c10d599a41, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:20,912 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=
2025-11-11 13:04:20,912 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==
2025-11-11 13:04:20,961 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d54cfe51-1938-4a63-9eb2-99c2b395a118, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy" 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 13:04:21,001 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36554f89-c638-4074-92f9-43c10d599a41, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=" 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 13:04:21,016 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c79d74a9-219c-4af1-bdb8-bca72c593e86, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:21,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c79d74a9-219c-4af1-bdb8-bca72c593e86, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==" 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 13:04:21,020 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d54cfe51-1938-4a63-9eb2-99c2b395a118, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:21,066 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36554f89-c638-4074-92f9-43c10d599a41, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:21,017 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=
2025-11-11 13:04:21,078 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c79d74a9-219c-4af1-bdb8-bca72c593e86, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:21,122 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 829e75a9-069d-4feb-a262-fc3863d76337, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:21,123 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 829e75a9-069d-4feb-a262-fc3863d76337, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=" 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 13:04:21,127 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=
2025-11-11 13:04:21,207 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 829e75a9-069d-4feb-a262-fc3863d76337, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:21,236 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 87ba4821-a1f4-49bb-b5c3-07a836d7af5d, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:21,250 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 87ba4821-a1f4-49bb-b5c3-07a836d7af5d, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=" 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 13:04:21,256 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 87ba4821-a1f4-49bb-b5c3-07a836d7af5d, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:21,237 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:21,284 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6bc62c8-c2a9-4c64-a6da-c3541b4037a7, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:21,284 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6bc62c8-c2a9-4c64-a6da-c3541b4037a7, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=" 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 13:04:21,286 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:21,287 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd9dcb4d-86fc-4ab7-b931-12db11daa8ed, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:21,287 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd9dcb4d-86fc-4ab7-b931-12db11daa8ed, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:21,288 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=
2025-11-11 13:04:21,288 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b302afb-c168-48a0-8568-3c89bf2fc7ef, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:21,289 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b302afb-c168-48a0-8568-3c89bf2fc7ef, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=" 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 13:04:21,290 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=
2025-11-11 13:04:21,290 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 988b37bb-0e53-48b8-8f24-0e73d1002b17, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:21,291 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 988b37bb-0e53-48b8-8f24-0e73d1002b17, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=" 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 13:04:21,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd9dcb4d-86fc-4ab7-b931-12db11daa8ed, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:21,290 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6bc62c8-c2a9-4c64-a6da-c3541b4037a7, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:21,295 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b302afb-c168-48a0-8568-3c89bf2fc7ef, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:21,296 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 988b37bb-0e53-48b8-8f24-0e73d1002b17, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:22,865 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:22,872 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:22,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca73b181-a5e1-49b9-b737-c67cf74fe539, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:22,876 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca73b181-a5e1-49b9-b737-c67cf74fe539, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=" 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 13:04:22,879 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca73b181-a5e1-49b9-b737-c67cf74fe539, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:22,982 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:22,989 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:22,994 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70cea953-6d7c-40dd-acd7-0df183aa55a7, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:22,999 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70cea953-6d7c-40dd-acd7-0df183aa55a7, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy" 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 13:04:23,001 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70cea953-6d7c-40dd-acd7-0df183aa55a7, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:23,069 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:23,070 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:23,079 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2cf7c4e0-0fd2-4177-add3-2da47e693cea, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:23,080 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2cf7c4e0-0fd2-4177-add3-2da47e693cea, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:23,087 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2cf7c4e0-0fd2-4177-add3-2da47e693cea, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:23,154 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:23,163 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:23,165 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6769a79f-18d6-4bb0-a76b-290b251d57d4, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:23,166 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6769a79f-18d6-4bb0-a76b-290b251d57d4, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=" 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 13:04:23,169 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6769a79f-18d6-4bb0-a76b-290b251d57d4, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:23,366 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:23,371 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:23,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a19cd436-769d-4c46-9bb8-f9c6140c2d9b, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:23,412 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a19cd436-769d-4c46-9bb8-f9c6140c2d9b, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=" 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 13:04:23,413 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a19cd436-769d-4c46-9bb8-f9c6140c2d9b, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,303 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=
2025-11-11 13:04:24,347 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=
2025-11-11 13:04:24,355 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9f5b3ebd-d3bc-41d2-9fc0-2dd2cd3fff54, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,366 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9f5b3ebd-d3bc-41d2-9fc0-2dd2cd3fff54, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=" 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 13:04:24,375 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9f5b3ebd-d3bc-41d2-9fc0-2dd2cd3fff54, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,547 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=
2025-11-11 13:04:24,563 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=
2025-11-11 13:04:24,596 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c04c1fc0-32b6-4352-b8d7-b72322bae513, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,614 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c04c1fc0-32b6-4352-b8d7-b72322bae513, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=" 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 13:04:24,618 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:24,619 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c04c1fc0-32b6-4352-b8d7-b72322bae513, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,621 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:24,640 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a64206a-2b63-4996-9bce-0df738732104, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,622 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=
2025-11-11 13:04:24,641 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a64206a-2b63-4996-9bce-0df738732104, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8))" } stats_mode: STATS_MODE_NONE }
2025-11-11 13:04:24,672 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a64206a-2b63-4996-9bce-0df738732104, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,672 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=
2025-11-11 13:04:24,683 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:24,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:24,694 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:24,694 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==
2025-11-11 13:04:24,690 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a546c4be-da81-4a67-a89d-bbad10d7dabd, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,697 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:24,697 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:24,702 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9107218-70b0-4892-b970-fe0a5a07da47, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9107218-70b0-4892-b970-fe0a5a07da47, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=" 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 13:04:24,768 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9107218-70b0-4892-b970-fe0a5a07da47, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,751 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a546c4be-da81-4a67-a89d-bbad10d7dabd, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=" 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 13:04:24,776 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a546c4be-da81-4a67-a89d-bbad10d7dabd, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,771 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=
2025-11-11 13:04:24,750 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:24,870 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e368563b-f262-4f18-a6bb-19118cca1a45, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,871 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==
2025-11-11 13:04:24,886 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e368563b-f262-4f18-a6bb-19118cca1a45, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=" 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 13:04:24,892 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b2e8111-223c-44a5-a2b0-7321cf947238, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,893 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:24,921 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b2e8111-223c-44a5-a2b0-7321cf947238, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==" 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 13:04:24,925 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96f4a63b-e9fd-4f39-8b26-aaf2fabf5cb3, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,925 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:24,901 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e368563b-f262-4f18-a6bb-19118cca1a45, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,932 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b2e8111-223c-44a5-a2b0-7321cf947238, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,945 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96f4a63b-e9fd-4f39-8b26-aaf2fabf5cb3, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=" 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 13:04:24,953 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e903ec8-6d24-4065-9d34-d99fb6685dd9, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:24,954 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=
2025-11-11 13:04:24,976 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96f4a63b-e9fd-4f39-8b26-aaf2fabf5cb3, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:24,980 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e903ec8-6d24-4065-9d34-d99fb6685dd9, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy" 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 13:04:24,992 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9b48f6f-c62d-4565-800a-700a08746b43, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:25,000 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e903ec8-6d24-4065-9d34-d99fb6685dd9, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:25,023 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9b48f6f-c62d-4565-800a-700a08746b43, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=" 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 13:04:25,031 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9b48f6f-c62d-4565-800a-700a08746b43, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:25,776 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 13:04:25,777 - DEBUG - ya.test - _save_outputs: Command (pid 2717821) rc: 0
2025-11-11 13:04:25,777 - DEBUG - ya.test - _save_outputs: Command (pid 2717821) elapsed time (sec): 12.884008884429932
2025-11-11 13:04:25,777 - DEBUG - ya.test - _save_outputs: Command (pid 2717821) wtime: 12.892
2025-11-11 13:04:25,777 - DEBUG - ya.test - _save_outputs: Command (pid 2717821) 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	11519	12479	12479	12479

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

2025-11-11 13:04:25,777 - DEBUG - ya.test - _save_outputs: Command (pid 2717821) errors:

2025-11-11 13:04:25,988 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:26,087 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:26,100 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:26,107 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:26,107 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=
2025-11-11 13:04:26,108 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:26,109 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:26,110 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6ba3189-fee9-415a-bae8-3f4261194e52, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:26,111 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6ba3189-fee9-415a-bae8-3f4261194e52, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=" 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 13:04:26,112 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6ba3189-fee9-415a-bae8-3f4261194e52, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:26,135 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=
2025-11-11 13:04:26,136 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:26,136 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 25853e52-50d3-4b3a-8bf3-5cb999a10172, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:26,137 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 25853e52-50d3-4b3a-8bf3-5cb999a10172, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy" 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 13:04:26,138 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 25853e52-50d3-4b3a-8bf3-5cb999a10172, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:26,500 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=
2025-11-11 13:04:26,512 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:26,512 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b3a1b084-701d-4fe5-a2a3-0185ec752641, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:26,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b3a1b084-701d-4fe5-a2a3-0185ec752641, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=" 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 13:04:26,513 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b3a1b084-701d-4fe5-a2a3-0185ec752641, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:26,540 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==
2025-11-11 13:04:26,541 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:26,541 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ea1a9f86-d98f-4ae1-b26e-c47639644613, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:26,542 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ea1a9f86-d98f-4ae1-b26e-c47639644613, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=" 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 13:04:26,542 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ea1a9f86-d98f-4ae1-b26e-c47639644613, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:26,543 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=
2025-11-11 13:04:26,543 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=
2025-11-11 13:04:26,544 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6932380d-4eb4-4707-8986-7d823259c921, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:26,544 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6932380d-4eb4-4707-8986-7d823259c921, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=" 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 13:04:26,545 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6932380d-4eb4-4707-8986-7d823259c921, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:27,049 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy
2025-11-11 13:04:27,079 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=
2025-11-11 13:04:27,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=
2025-11-11 13:04:27,496 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=
2025-11-11 13:04:27,498 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=
2025-11-11 13:04:27,499 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:27,504 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b51c9035-6db0-4b12-b0bf-48a9bad129eb, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:27,504 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b51c9035-6db0-4b12-b0bf-48a9bad129eb, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=" 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 13:04:27,515 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b51c9035-6db0-4b12-b0bf-48a9bad129eb, ghrun-3ynj4chmly.auto.internal:24475): response = { <_MultiThreadedRendezvous object> }
2025-11-11 13:04:27,755 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=
2025-11-11 13:04:27,756 - INFO - root - test_log_deviation: check insert: 23000 18000
2025-11-11 13:04:27,764 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.10
2025-11-11 13:04:27,766 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.10
2025-11-11 13:04:27,767 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/bhk2/004b66/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:24475', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-11 13:04:27,808 - DEBUG - ya.test - execute: Command pid: 2726627
2025-11-11 13:04:28,003 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) rc: 0
2025-11-11 13:04:28,004 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) elapsed time (sec): 0.19402861595153809
2025-11-11 13:04:28,004 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) idrss: 0
2025-11-11 13:04:28,004 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) inblock: 0
2025-11-11 13:04:28,004 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) isrss: 0
2025-11-11 13:04:28,004 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) ixrss: 0
2025-11-11 13:04:28,004 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) majflt: 0
2025-11-11 13:04:28,011 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) maxrss: 467720
2025-11-11 13:04:28,012 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) minflt: 3532
2025-11-11 13:04:28,012 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) msgrcv: 0
2025-11-11 13:04:28,012 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) msgsnd: 0
2025-11-11 13:04:28,012 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) nivcsw: 33
2025-11-11 13:04:28,013 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) nsignals: 0
2025-11-11 13:04:28,013 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) nswap: 0
2025-11-11 13:04:28,013 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) nvcsw: 160
2025-11-11 13:04:28,013 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) oublock: 8
2025-11-11 13:04:28,013 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) stime: 0.016146999999999998
2025-11-11 13:04:28,013 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) utime: 0.06443299999999999
2025-11-11 13:04:28,014 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) wtime: 0.216
2025-11-11 13:04:28,014 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) output:
Remove path log...
Remove path log...Ok

2025-11-11 13:04:28,014 - DEBUG - ya.test - _save_outputs: Command (pid 2726627) errors:

2025-11-11 13:04:28,017 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-11 13:04:28,017 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, fb758bb3-fa22-4f9e-9d8c-07fa7d05dd36, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,018 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, fb758bb3-fa22-4f9e-9d8c-07fa7d05dd36, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjM0NTQ4MDUtNWRkYTY5YTYtYjYzNDQ3ZGEtYjM2OWQzYTk=" }
2025-11-11 13:04:28,047 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, fb758bb3-fa22-4f9e-9d8c-07fa7d05dd36, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,049 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, aa21e291-e570-4854-9d02-5c9fe096a383, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,050 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, aa21e291-e570-4854-9d02-5c9fe096a383, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=MTk5ZThiNjEtZTFjYzA5MDItNTg3OTBiYmUtZmMxNTY1Mzc=" }
2025-11-11 13:04:28,049 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, a19a19b8-f2cf-48c3-88f9-13ecccdaf591, ghrun-3ynj4chmly.auto.internal:24475): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 13:04:28,053 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, aa21e291-e570-4854-9d02-5c9fe096a383, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,054 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 0b4fb2f1-392d-4adb-9870-66d365cd453d, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,054 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 0b4fb2f1-392d-4adb-9870-66d365cd453d, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=YTk5YzEwZDEtZjJkMmU5YS01NmNjMGZiNi04NmZhZTA4Zg==" }
2025-11-11 13:04:28,055 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 420ce39c-2d07-4226-9de1-72220dcf8970, ghrun-3ynj4chmly.auto.internal:24475): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 13:04:28,056 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 0b4fb2f1-392d-4adb-9870-66d365cd453d, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,059 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, e38c713a-5ca6-422f-9598-4a688f87cc0e, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,059 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, e38c713a-5ca6-422f-9598-4a688f87cc0e, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ODNmZTdkZDAtMjg1MTAxYzQtOWE5M2E0MWUtZWVhYTg4YzA=" }
2025-11-11 13:04:28,062 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, e38c713a-5ca6-422f-9598-4a688f87cc0e, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,065 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c17931cd-14e6-4526-9fbd-b26e0f6c35ad, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,065 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c17931cd-14e6-4526-9fbd-b26e0f6c35ad, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZDEzYzY5LTUzMGRlODQwLTU5MzM4NzgxLWU3ODIxNTYy" }
2025-11-11 13:04:28,070 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c17931cd-14e6-4526-9fbd-b26e0f6c35ad, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,071 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ff3c03ad-a781-470d-8122-08a59e053af5, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,071 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ff3c03ad-a781-470d-8122-08a59e053af5, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NTA2OTkxYWQtYTU2YTU1MTktNzRmMWY2NTItNDMyMjYwNjI=" }
2025-11-11 13:04:28,072 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, b0d50d64-0017-4723-a5a3-d18019c431c1, ghrun-3ynj4chmly.auto.internal:24475): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-11 13:04:28,074 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ff3c03ad-a781-470d-8122-08a59e053af5, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,077 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c2461b72-9c1f-496a-a837-b7b91f9e02ca, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,078 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c2461b72-9c1f-496a-a837-b7b91f9e02ca, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=NjNlZjdlOWMtZTNjM2RlN2YtMjdmMWMxNjEtZTk1M2YyMGY=" }
2025-11-11 13:04:28,081 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c2461b72-9c1f-496a-a837-b7b91f9e02ca, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,081 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b1afd2cf-1ffe-4f87-8567-127020d29182, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,082 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b1afd2cf-1ffe-4f87-8567-127020d29182, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OWMwNjk3ZTgtYTg1ZjY5OWYtMzYzN2M2ODctZGQzODk4ZTk=" }
2025-11-11 13:04:28,084 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b1afd2cf-1ffe-4f87-8567-127020d29182, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,085 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, e9b7a8a1-414d-470c-8e0d-cb9e0815d6ec, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,085 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, e9b7a8a1-414d-470c-8e0d-cb9e0815d6ec, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=ZmJmZjQ1MDYtZTE3NGE2MjItMmYwMzhkYWEtYzk0MjA3YTU=" }
2025-11-11 13:04:28,087 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, e9b7a8a1-414d-470c-8e0d-cb9e0815d6ec, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,088 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1746028d-3bf2-4feb-8867-c46c7bf830f6, ghrun-3ynj4chmly.auto.internal:24475): creating call state
2025-11-11 13:04:28,089 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1746028d-3bf2-4feb-8867-c46c7bf830f6, ghrun-3ynj4chmly.auto.internal:24475): request = { session_id: "ydb://session/3?node_id=1&id=OGEyMjY3YjgtZmQ4YjljYTYtMzAxZjBjOTAtYmZjMTk0Yzg=" }
2025-11-11 13:04:28,090 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1746028d-3bf2-4feb-8867-c46c7bf830f6, ghrun-3ynj4chmly.auto.internal:24475): response = { status: SUCCESS }
2025-11-11 13:04:28,095 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-11 13:04:28,096 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 13:04:28,099 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 13:04:28,099 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:24475
2025-11-11 13:04:28,099 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:24475
2025-11-11 13:04:28,101 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 13:04:31,103 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:24475/1
2025-11-11 13:04:31,104 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20949
2025-11-11 13:04:31,109 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22495
2025-11-11 13:04:31,111 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/23913
2025-11-11 13:04:31,114 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24475
2025-11-11 13:04:31,114 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12100
