2025-11-10 10:01:13,913 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 10:01:13,914 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[180]
2025-11-10 10:01:13,914 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 10:01:13,914 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-10 10:01:13,916 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[180])
2025-11-10 10:01:13,916 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb
2025-11-10 10:01:13,916 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb -e grpc://localhost:1775 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-10 10:01:13,917 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-11-10 10:01:13,918 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-11-10 10:01:13,918 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:1775', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-10 10:01:13,919 - DEBUG - ya.test - execute: Command pid: 1877934
2025-11-10 10:01:14,428 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) rc: 0
2025-11-10 10:01:14,428 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) elapsed time (sec): 0.5081374645233154
2025-11-10 10:01:14,429 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) idrss: 0
2025-11-10 10:01:14,429 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) inblock: 0
2025-11-10 10:01:14,429 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) isrss: 0
2025-11-10 10:01:14,429 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) ixrss: 0
2025-11-10 10:01:14,429 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) majflt: 0
2025-11-10 10:01:14,430 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) maxrss: 1216120
2025-11-10 10:01:14,430 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) minflt: 3533
2025-11-10 10:01:14,430 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) msgrcv: 0
2025-11-10 10:01:14,430 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) msgsnd: 0
2025-11-10 10:01:14,430 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) nivcsw: 25
2025-11-10 10:01:14,430 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) nsignals: 0
2025-11-10 10:01:14,431 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) nswap: 0
2025-11-10 10:01:14,431 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) nvcsw: 158
2025-11-10 10:01:14,431 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) oublock: 8
2025-11-10 10:01:14,431 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) stime: 0.022182999999999998
2025-11-10 10:01:14,432 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) utime: 0.07024799999999999
2025-11-10 10:01:14,432 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) wtime: 0.509
2025-11-10 10:01:14,432 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) output:
Init tables ...
Init tables ...Ok

2025-11-10 10:01:14,432 - DEBUG - ya.test - _save_outputs: Command (pid 1877934) errors:

2025-11-10 10:01:14,433 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=
2025-11-10 10:01:14,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d03d6eab-9245-43c9-9e33-d3b6f830bdc5, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:14,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d03d6eab-9245-43c9-9e33-d3b6f830bdc5, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=" 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-10 10:01:14,434 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d03d6eab-9245-43c9-9e33-d3b6f830bdc5, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:14,487 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=
2025-11-10 10:01:14,487 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-10 10:01:14,488 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb -e grpc://localhost:1775 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-10 10:01:14,489 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-11-10 10:01:14,490 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-11-10 10:01:14,490 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:1775', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-10 10:01:14,497 - DEBUG - ya.test - execute: Command pid: 1878583
2025-11-10 10:01:18,257 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 10:01:18,257 - DEBUG - ya.test - _save_outputs: Command (pid 1878583) rc: 0
2025-11-10 10:01:18,258 - DEBUG - ya.test - _save_outputs: Command (pid 1878583) elapsed time (sec): 3.7599267959594727
2025-11-10 10:01:18,258 - DEBUG - ya.test - _save_outputs: Command (pid 1878583) wtime: 3.767
2025-11-10 10:01:18,258 - DEBUG - ya.test - _save_outputs: Command (pid 1878583) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      9	9	0	0	763	863	863	863
2	     11	11	0	0	847	899	931	931
3	     21	21	0	0	799	899	907	907

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     41	13.6667	0	0	803	899	931	931

2025-11-10 10:01:18,258 - DEBUG - ya.test - _save_outputs: Command (pid 1878583) errors:

2025-11-10 10:01:18,259 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:18,259 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08de9f0a-c7f5-4e8b-947d-d8ee345bc099, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:18,260 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08de9f0a-c7f5-4e8b-947d-d8ee345bc099, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=" 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-10 10:01:18,261 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08de9f0a-c7f5-4e8b-947d-d8ee345bc099, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:18,680 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:18,680 - INFO - root - test_log_deviation: Count rows after insert 20500 before wait
2025-11-10 10:01:18,680 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=
2025-11-10 10:01:18,681 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6cad22c7-0fbd-48bb-9207-4a021f351b8f, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:18,681 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6cad22c7-0fbd-48bb-9207-4a021f351b8f, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=" 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-10 10:01:18,687 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6cad22c7-0fbd-48bb-9207-4a021f351b8f, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:18,812 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=
2025-11-10 10:01:18,812 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=
2025-11-10 10:01:18,813 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ad45197f-aca0-4313-aa77-5f0c21c9499b, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:18,813 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ad45197f-aca0-4313-aa77-5f0c21c9499b, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=" 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-10 10:01:18,814 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ad45197f-aca0-4313-aa77-5f0c21c9499b, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:18,957 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=
2025-11-10 10:01:18,962 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-10 10:01:18,963 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-10 10:01:18,963 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-10 10:01:18,964 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb -e grpc://localhost:1775 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-10 10:01:18,964 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==
2025-11-10 10:01:18,965 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb -e grpc://localhost:1775 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-10 10:01:18,966 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb -e grpc://localhost:1775 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-10 10:01:18,969 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f11e3731-7e8b-440f-a333-c25a8e479be2, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:18,978 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f11e3731-7e8b-440f-a333-c25a8e479be2, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:18,969 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:18,985 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.7
2025-11-10 10:01:18,999 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09b2c66a-cb26-4146-b0bf-72ee032c1f8f, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,002 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09b2c66a-cb26-4146-b0bf-72ee032c1f8f, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:18,991 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.8
2025-11-10 10:01:19,003 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09b2c66a-cb26-4146-b0bf-72ee032c1f8f, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,001 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-11-10 10:01:19,000 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:18,987 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f11e3731-7e8b-440f-a333-c25a8e479be2, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,006 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:1775', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-10 10:01:19,005 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.8
2025-11-10 10:01:19,008 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:1775', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-10 10:01:18,992 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.9
2025-11-10 10:01:19,017 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.9
2025-11-10 10:01:19,006 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=
2025-11-10 10:01:19,018 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:1775', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-10 10:01:19,011 - DEBUG - ya.test - execute: Command pid: 1881193
2025-11-10 10:01:19,019 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2cefe17d-37f6-4414-8560-6ab6f50dfef4, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,019 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=
2025-11-10 10:01:19,005 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 51517964-2b3b-4ad6-98a8-4ad9fe876523, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,022 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2cefe17d-37f6-4414-8560-6ab6f50dfef4, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:19,024 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 51517964-2b3b-4ad6-98a8-4ad9fe876523, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:19,023 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97165f7f-f646-4b18-a4de-7d815daa9e45, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,021 - DEBUG - ya.test - execute: Command pid: 1881194
2025-11-10 10:01:19,026 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2cefe17d-37f6-4414-8560-6ab6f50dfef4, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,024 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:19,026 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97165f7f-f646-4b18-a4de-7d815daa9e45, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:19,029 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=
2025-11-10 10:01:19,030 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 467b594f-6a69-4d82-a167-14e57b5f7205, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,029 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6f8e5af-e456-41d3-b8e3-29de148e3102, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 467b594f-6a69-4d82-a167-14e57b5f7205, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:19,033 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 467b594f-6a69-4d82-a167-14e57b5f7205, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,028 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 51517964-2b3b-4ad6-98a8-4ad9fe876523, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,007 - DEBUG - ya.test - execute: Command pid: 1881192
2025-11-10 10:01:19,031 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:19,046 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be7a86d9-a2d4-4405-ad59-4ca6bdb5d893, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,049 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be7a86d9-a2d4-4405-ad59-4ca6bdb5d893, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:19,049 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be7a86d9-a2d4-4405-ad59-4ca6bdb5d893, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,032 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6f8e5af-e456-41d3-b8e3-29de148e3102, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:19,051 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6f8e5af-e456-41d3-b8e3-29de148e3102, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,031 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97165f7f-f646-4b18-a4de-7d815daa9e45, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,047 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=
2025-11-10 10:01:19,053 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a19cfc9-8b9b-4726-b920-e20693006f31, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,054 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a19cfc9-8b9b-4726-b920-e20693006f31, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:19,055 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a19cfc9-8b9b-4726-b920-e20693006f31, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:19,056 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=
2025-11-10 10:01:19,057 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9a9b256-a088-41be-99f4-156bb0e7d7ca, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:19,058 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9a9b256-a088-41be-99f4-156bb0e7d7ca, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:19,059 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9a9b256-a088-41be-99f4-156bb0e7d7ca, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:22,607 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 10:01:22,608 - DEBUG - ya.test - _save_outputs: Command (pid 1881192) rc: 0
2025-11-10 10:01:22,608 - DEBUG - ya.test - _save_outputs: Command (pid 1881192) elapsed time (sec): 3.5625226497650146
2025-11-10 10:01:22,608 - DEBUG - ya.test - _save_outputs: Command (pid 1881192) wtime: 3.601
2025-11-10 10:01:22,608 - DEBUG - ya.test - _save_outputs: Command (pid 1881192) 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	10	0	0	0	0

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

2025-11-10 10:01:22,609 - DEBUG - ya.test - _save_outputs: Command (pid 1881192) errors:

2025-11-10 10:01:22,681 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 10:01:22,681 - DEBUG - ya.test - _save_outputs: Command (pid 1881193) rc: 0
2025-11-10 10:01:22,682 - DEBUG - ya.test - _save_outputs: Command (pid 1881193) elapsed time (sec): 3.6593782901763916
2025-11-10 10:01:22,684 - DEBUG - ya.test - _save_outputs: Command (pid 1881193) wtime: 3.673
2025-11-10 10:01:22,684 - DEBUG - ya.test - _save_outputs: Command (pid 1881193) 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	10	0	0	0	0

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

2025-11-10 10:01:22,685 - DEBUG - ya.test - _save_outputs: Command (pid 1881193) errors:

2025-11-10 10:01:25,501 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=
2025-11-10 10:01:25,501 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=
2025-11-10 10:01:25,502 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa811e7d-d2d8-4a3f-a6d9-122a5497394c, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,502 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa811e7d-d2d8-4a3f-a6d9-122a5497394c, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=" 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-10 10:01:25,503 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa811e7d-d2d8-4a3f-a6d9-122a5497394c, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,509 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==
2025-11-10 10:01:25,510 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==
2025-11-10 10:01:25,510 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d8e844e-bf34-48fc-996b-24f5848fdce9, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,512 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d8e844e-bf34-48fc-996b-24f5848fdce9, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==" 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-10 10:01:25,517 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d8e844e-bf34-48fc-996b-24f5848fdce9, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,521 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=
2025-11-10 10:01:25,522 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=
2025-11-10 10:01:25,522 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 543f1ea8-abda-41f3-9c6e-a190811d00af, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,523 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 543f1ea8-abda-41f3-9c6e-a190811d00af, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=" 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-10 10:01:25,534 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 543f1ea8-abda-41f3-9c6e-a190811d00af, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,524 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=
2025-11-10 10:01:25,535 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=
2025-11-10 10:01:25,535 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 78ea557e-0d1f-4f57-afd5-735fa6217604, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,536 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 78ea557e-0d1f-4f57-afd5-735fa6217604, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=" 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-10 10:01:25,537 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 78ea557e-0d1f-4f57-afd5-735fa6217604, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,528 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=
2025-11-10 10:01:25,533 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:25,538 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=
2025-11-10 10:01:25,539 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c689a585-cb43-4e92-88f9-87c0bdcebc52, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c689a585-cb43-4e92-88f9-87c0bdcebc52, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:25,540 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:25,540 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 35fb6c86-0906-483b-8dac-a4871a84f601, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,544 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 35fb6c86-0906-483b-8dac-a4871a84f601, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk" 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-10 10:01:25,545 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c689a585-cb43-4e92-88f9-87c0bdcebc52, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,546 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=
2025-11-10 10:01:25,546 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=
2025-11-10 10:01:25,547 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b3a9d5bb-a2e8-483b-9423-12acfee38352, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,547 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b3a9d5bb-a2e8-483b-9423-12acfee38352, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:25,549 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 35fb6c86-0906-483b-8dac-a4871a84f601, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,549 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b3a9d5bb-a2e8-483b-9423-12acfee38352, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,553 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:25,553 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:25,553 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:25,554 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:25,555 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9c330019-384e-4552-b87d-bdc52f9c1594, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,555 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:25,556 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9c330019-384e-4552-b87d-bdc52f9c1594, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:25,557 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2198c8f1-2269-42af-89c3-f725a65d1e4d, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,558 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2198c8f1-2269-42af-89c3-f725a65d1e4d, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:25,558 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:25,559 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9c330019-384e-4552-b87d-bdc52f9c1594, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,559 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2198c8f1-2269-42af-89c3-f725a65d1e4d, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:25,561 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f6042bb-91b5-4d75-a7a1-e3904e926dd9, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:25,566 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f6042bb-91b5-4d75-a7a1-e3904e926dd9, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=" 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-10 10:01:25,567 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f6042bb-91b5-4d75-a7a1-e3904e926dd9, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:27,453 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:27,454 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:27,454 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 84cb4140-30f6-4abd-87d5-52a6f7a98058, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:27,455 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 84cb4140-30f6-4abd-87d5-52a6f7a98058, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:27,456 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 84cb4140-30f6-4abd-87d5-52a6f7a98058, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:27,533 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:27,545 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:27,546 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 164cba9c-c63a-4946-9a2d-7cddc504bc13, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:27,546 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 164cba9c-c63a-4946-9a2d-7cddc504bc13, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:27,547 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 164cba9c-c63a-4946-9a2d-7cddc504bc13, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:28,355 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:28,356 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:28,358 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e94ee170-7bd4-4118-b18f-6bee17452f55, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:28,358 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e94ee170-7bd4-4118-b18f-6bee17452f55, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk" 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-10 10:01:28,386 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e94ee170-7bd4-4118-b18f-6bee17452f55, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:28,759 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:28,759 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:28,760 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c911e596-d397-48b9-9ecb-c0921449d1f7, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:28,760 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c911e596-d397-48b9-9ecb-c0921449d1f7, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:28,761 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:28,779 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:28,779 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b838ef5-9ccf-467a-9018-faed0bdc89cc, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:28,780 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b838ef5-9ccf-467a-9018-faed0bdc89cc, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:28,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c911e596-d397-48b9-9ecb-c0921449d1f7, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:28,785 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b838ef5-9ccf-467a-9018-faed0bdc89cc, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:29,133 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:29,134 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:29,134 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 77801a87-06ec-465c-9966-46951d818c2a, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:29,135 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 77801a87-06ec-465c-9966-46951d818c2a, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk" 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-10 10:01:29,150 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 77801a87-06ec-465c-9966-46951d818c2a, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:29,524 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:29,548 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:29,892 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:30,677 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==
2025-11-10 10:01:30,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:30,708 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3cf7127a-a7e5-4942-8c0f-9544cac02d17, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:30,712 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3cf7127a-a7e5-4942-8c0f-9544cac02d17, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=" 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-10 10:01:30,720 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3cf7127a-a7e5-4942-8c0f-9544cac02d17, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:30,749 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 10:01:30,750 - DEBUG - ya.test - _save_outputs: Command (pid 1881194) rc: 0
2025-11-10 10:01:30,750 - DEBUG - ya.test - _save_outputs: Command (pid 1881194) elapsed time (sec): 11.7221040725708
2025-11-10 10:01:30,750 - DEBUG - ya.test - _save_outputs: Command (pid 1881194) wtime: 11.729
2025-11-10 10:01:30,750 - DEBUG - ya.test - _save_outputs: Command (pid 1881194) 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	11263	11391	11391	11391

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

2025-11-10 10:01:30,751 - DEBUG - ya.test - _save_outputs: Command (pid 1881194) errors:

2025-11-10 10:01:31,038 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:31,053 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:31,054 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 183c5df7-4bfa-40e9-861f-d8d5e628f78b, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:31,054 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 183c5df7-4bfa-40e9-861f-d8d5e628f78b, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=" 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-10 10:01:31,081 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 183c5df7-4bfa-40e9-861f-d8d5e628f78b, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:31,094 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:31,102 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:31,105 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c4b7dc04-218c-4e92-8d22-47584117a286, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:31,109 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c4b7dc04-218c-4e92-8d22-47584117a286, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk" 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-10 10:01:31,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c4b7dc04-218c-4e92-8d22-47584117a286, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:31,466 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=
2025-11-10 10:01:31,520 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==
2025-11-10 10:01:31,525 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef5950e7-2544-4506-8e48-3d5a8a9ab4c9, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:31,526 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef5950e7-2544-4506-8e48-3d5a8a9ab4c9, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==" 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-10 10:01:31,527 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef5950e7-2544-4506-8e48-3d5a8a9ab4c9, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:31,540 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:31,553 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:31,553 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:31,554 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c40d60ee-011e-4b96-ab6d-a28e0b7067c2, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:31,555 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c40d60ee-011e-4b96-ab6d-a28e0b7067c2, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=" 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-10 10:01:31,555 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c40d60ee-011e-4b96-ab6d-a28e0b7067c2, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:31,785 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=
2025-11-10 10:01:31,843 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:31,847 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d4f0ef4-d093-40ab-8d4a-bf2c01c259c8, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:31,868 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d4f0ef4-d093-40ab-8d4a-bf2c01c259c8, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:31,848 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=
2025-11-10 10:01:31,945 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=
2025-11-10 10:01:31,945 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8016b85d-42c4-4fc7-8be3-33149f89e67b, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:31,946 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8016b85d-42c4-4fc7-8be3-33149f89e67b, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=" 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-10 10:01:31,957 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d4f0ef4-d093-40ab-8d4a-bf2c01c259c8, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:31,958 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8016b85d-42c4-4fc7-8be3-33149f89e67b, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:31,973 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:31,992 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==
2025-11-10 10:01:31,993 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:31,994 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2011ec4e-54a0-4180-baf8-72e619e07e10, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:31,994 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2011ec4e-54a0-4180-baf8-72e619e07e10, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk" 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-10 10:01:31,995 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2011ec4e-54a0-4180-baf8-72e619e07e10, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:31,995 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=
2025-11-10 10:01:32,069 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:32,069 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 31cc1f38-a5b8-4bae-9e0b-2c67a0d100ce, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:32,070 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 31cc1f38-a5b8-4bae-9e0b-2c67a0d100ce, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:32,078 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 31cc1f38-a5b8-4bae-9e0b-2c67a0d100ce, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:32,091 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=
2025-11-10 10:01:32,167 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=
2025-11-10 10:01:32,168 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 776e4cac-3a1f-42eb-91da-601d035e54b5, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:32,169 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 776e4cac-3a1f-42eb-91da-601d035e54b5, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=" 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-10 10:01:32,170 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 776e4cac-3a1f-42eb-91da-601d035e54b5, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:32,889 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=
2025-11-10 10:01:32,890 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=
2025-11-10 10:01:32,890 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5f503f22-13d9-4246-9ae0-5be8e5abc5cd, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:32,891 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5f503f22-13d9-4246-9ae0-5be8e5abc5cd, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:32,892 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5f503f22-13d9-4246-9ae0-5be8e5abc5cd, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:32,905 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=
2025-11-10 10:01:32,906 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:32,906 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b56fb0d7-af9f-4ed1-97db-64f6ae5180c8, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:32,907 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b56fb0d7-af9f-4ed1-97db-64f6ae5180c8, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=" 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-10 10:01:32,908 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b56fb0d7-af9f-4ed1-97db-64f6ae5180c8, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:32,914 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=
2025-11-10 10:01:32,914 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==
2025-11-10 10:01:32,915 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7cb5df4b-bc4f-43e4-934c-01c2677f9dae, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:32,915 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7cb5df4b-bc4f-43e4-934c-01c2677f9dae, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==" 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-10 10:01:32,916 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7cb5df4b-bc4f-43e4-934c-01c2677f9dae, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:32,921 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk
2025-11-10 10:01:32,925 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=
2025-11-10 10:01:32,925 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=
2025-11-10 10:01:32,926 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d9c90c9-b095-4662-af09-563bf4a06a72, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:32,926 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d9c90c9-b095-4662-af09-563bf4a06a72, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:01:32,927 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d9c90c9-b095-4662-af09-563bf4a06a72, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:33,571 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=
2025-11-10 10:01:33,571 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==
2025-11-10 10:01:33,573 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=
2025-11-10 10:01:33,577 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=
2025-11-10 10:01:33,578 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=
2025-11-10 10:01:33,578 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0814c422-4312-43f4-b59b-9414021fb010, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:33,579 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0814c422-4312-43f4-b59b-9414021fb010, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=" 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-10 10:01:33,580 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0814c422-4312-43f4-b59b-9414021fb010, ghrun-kipqlopw2q.auto.internal:1775): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:01:33,721 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=
2025-11-10 10:01:33,722 - INFO - root - test_log_deviation: check insert: 25500 20500
2025-11-10 10:01:33,724 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.10
2025-11-10 10:01:33,725 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.10
2025-11-10 10:01:33,726 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024e6/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:1775', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-10 10:01:33,727 - DEBUG - ya.test - execute: Command pid: 1890640
2025-11-10 10:01:33,985 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) rc: 0
2025-11-10 10:01:33,986 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) elapsed time (sec): 0.2538931369781494
2025-11-10 10:01:33,986 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) idrss: 0
2025-11-10 10:01:33,986 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) inblock: 0
2025-11-10 10:01:33,987 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) isrss: 0
2025-11-10 10:01:33,987 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) ixrss: 0
2025-11-10 10:01:33,987 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) majflt: 0
2025-11-10 10:01:33,987 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) maxrss: 1216120
2025-11-10 10:01:33,988 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) minflt: 3536
2025-11-10 10:01:33,988 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) msgrcv: 0
2025-11-10 10:01:33,988 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) msgsnd: 0
2025-11-10 10:01:33,988 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) nivcsw: 31
2025-11-10 10:01:33,988 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) nsignals: 0
2025-11-10 10:01:33,989 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) nswap: 0
2025-11-10 10:01:33,989 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) nvcsw: 152
2025-11-10 10:01:33,989 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) oublock: 8
2025-11-10 10:01:33,989 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) stime: 0.03952
2025-11-10 10:01:33,990 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) utime: 0.055327999999999995
2025-11-10 10:01:33,990 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) wtime: 0.259
2025-11-10 10:01:33,990 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) output:
Remove path log...
Remove path log...Ok

2025-11-10 10:01:33,990 - DEBUG - ya.test - _save_outputs: Command (pid 1890640) errors:

2025-11-10 10:01:33,994 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-10 10:01:33,996 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d2fbcd98-6604-469e-8b3a-824c7bf1aa5f, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:33,996 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d2fbcd98-6604-469e-8b3a-824c7bf1aa5f, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjBhNzdiMWQtZTg5OWMwOWEtNmQzNGViYmMtMzg1NWVhYTE=" }
2025-11-10 10:01:33,999 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d2fbcd98-6604-469e-8b3a-824c7bf1aa5f, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,000 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 500c845d-b950-48e4-9ef2-0fa2bf5355e7, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,002 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 500c845d-b950-48e4-9ef2-0fa2bf5355e7, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Mjk2ZGZkNTItNzE1MjVhMzItZjhmZTNhNjQtYzg1MDdhZDk=" }
2025-11-10 10:01:34,004 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 500c845d-b950-48e4-9ef2-0fa2bf5355e7, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,005 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ecf4e25e-b654-480d-bfce-66df69d8a738, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,007 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ecf4e25e-b654-480d-bfce-66df69d8a738, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NWFjNTIwZjQtYmRiNTVkNDgtZjA4OGY3NTItMzUwOTcxYmU=" }
2025-11-10 10:01:34,006 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 4942f249-f771-4668-a0cb-7de0272d4b38, ghrun-kipqlopw2q.auto.internal:1775): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-10 10:01:34,009 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ecf4e25e-b654-480d-bfce-66df69d8a738, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,009 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 043aaed3-61ba-4fb4-bd49-36e44d8b72ea, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,011 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 043aaed3-61ba-4fb4-bd49-36e44d8b72ea, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViZDE4NGItMTdlOTQyZmMtZDQwZGZhOS01NTA0OGFk" }
2025-11-10 10:01:34,010 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 212baded-178c-40fc-894a-6ac89b7c5f57, ghrun-kipqlopw2q.auto.internal:1775): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-10 10:01:34,014 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 043aaed3-61ba-4fb4-bd49-36e44d8b72ea, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,015 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b489023d-9718-4e7c-ab2e-241b51d427f0, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,015 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b489023d-9718-4e7c-ab2e-241b51d427f0, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=Y2RmMGVjM2EtZjIxNDQyMzktMmMwNjRjOTAtOTY0NjZiNTM=" }
2025-11-10 10:01:34,020 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 5b705b7d-91ff-4953-859e-5e2a88b7ad87, ghrun-kipqlopw2q.auto.internal:1775): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-10 10:01:34,038 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b489023d-9718-4e7c-ab2e-241b51d427f0, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,042 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d3b6fdcb-85d6-4fd1-8e81-5a0e4b4c941d, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,043 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d3b6fdcb-85d6-4fd1-8e81-5a0e4b4c941d, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=NjE3N2IwMjUtNTc0MzViM2EtMWJhY2QxNWYtOTIyMzRiYTI=" }
2025-11-10 10:01:34,045 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d3b6fdcb-85d6-4fd1-8e81-5a0e4b4c941d, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,046 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 2d68922a-8327-4858-817b-4110ebacc48a, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,047 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 2d68922a-8327-4858-817b-4110ebacc48a, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ODhlODU5MDQtODI4ZTU4ZTUtZjM2NTdiMS03MDdjYWQ4NA==" }
2025-11-10 10:01:34,048 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 2d68922a-8327-4858-817b-4110ebacc48a, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,049 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 3fcde6ff-cc72-4cd5-9d2a-fddde9fc1a42, ghrun-kipqlopw2q.auto.internal:1775): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-10 10:01:34,050 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 3c864fc5-f4e8-44d7-b7d4-eb57209c301c, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,051 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 3c864fc5-f4e8-44d7-b7d4-eb57209c301c, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MTQwM2FlNjktZGQ2ZTc0ZDktNTkwYmEzOWItNWMwZjlhMzg=" }
2025-11-10 10:01:34,052 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 3c864fc5-f4e8-44d7-b7d4-eb57209c301c, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,053 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 28153051-2f71-47cf-a090-c3cf6bb74e23, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,053 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 28153051-2f71-47cf-a090-c3cf6bb74e23, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=ZjUzNDg3YTYtOWJiMTAyMjgtZjQ4MGJhNWUtNDBjMGZlMTU=" }
2025-11-10 10:01:34,055 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, d19e612c-861a-4e8b-8db0-910fb44848a8, ghrun-kipqlopw2q.auto.internal:1775): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-10 10:01:34,056 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 28153051-2f71-47cf-a090-c3cf6bb74e23, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,057 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 0c4289f1-2db4-423c-b36e-985795a7e880, ghrun-kipqlopw2q.auto.internal:1775): creating call state
2025-11-10 10:01:34,057 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 0c4289f1-2db4-423c-b36e-985795a7e880, ghrun-kipqlopw2q.auto.internal:1775): request = { session_id: "ydb://session/3?node_id=1&id=MjViYmYwYWItNDg3NzgzZjUtZmFjMjEzY2MtMmFkMWQwNjM=" }
2025-11-10 10:01:34,057 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 5419fbdf-0f06-4838-9408-7fca0a6c4865, ghrun-kipqlopw2q.auto.internal:1775): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-10 10:01:34,060 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 0c4289f1-2db4-423c-b36e-985795a7e880, ghrun-kipqlopw2q.auto.internal:1775): response = { status: SUCCESS }
2025-11-10 10:01:34,061 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-10 10:01:34,065 - DEBUG - ydb.topic - close: Close topic client
2025-11-10 10:01:34,065 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-10 10:01:34,065 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1775
2025-11-10 10:01:34,066 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:1775
2025-11-10 10:01:34,068 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-10 10:01:37,077 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:1775/1
2025-11-10 10:01:37,079 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3309
2025-11-10 10:01:37,079 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7128
2025-11-10 10:01:37,079 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18200
2025-11-10 10:01:37,080 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1775
2025-11-10 10:01:37,080 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26089
