2025-11-14 10:09:19,581 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 10:09:19,582 - INFO - ya.test - pytest_runtest_setup: test_interval_unit[v2-client0]
2025-11-14 10:09:19,582 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 10:09:19,583 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 10:09:19,584 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20364
2025-11-14 10:09:19,585 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/contrib/python/moto/bin/moto_server
2025-11-14 10:09:19,598 - DEBUG - root - is_s3_ready: HTTPConnectionPool(host='localhost', port=20364): Max retries exceeded with url: / (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7facaad0cec0>: Failed to establish a new connection: [Errno 111] Connection refused'))
2025-11-14 10:09:21,095 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64671
2025-11-14 10:09:21,097 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3541
2025-11-14 10:09:21,169 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:21,169 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11757
2025-11-14 10:09:21,170 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28156
2025-11-14 10:09:21,171 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25655
2025-11-14 10:09:21,171 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-14 10:09:21,177 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 10:09:21,179 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-14 10:09:21,190 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-14 10:09:21,190 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 2bdd983c-c155-4dbc-b6b6-d5de9b4e2d4d, localhost:11411): creating call state
2025-11-14 10:09:21,191 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 2bdd983c-c155-4dbc-b6b6-d5de9b4e2d4d, localhost:11411): request = { database: "local" }
2025-11-14 10:09:21,197 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 2bdd983c-c155-4dbc-b6b6-d5de9b4e2d4d, localhost:11411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-adxazuzm5e.auto.internal" port: 11411 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-14 10:09:21,197 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-adxazuzm5e.auto.internal:11411, location 1, ssl: False>]>
2025-11-14 10:09:21,198 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-14 10:09:21,206 - INFO - ydb.connection - close: Closing channel for endpoint localhost:11411
2025-11-14 10:09:21,207 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-14 10:09:21,208 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7facaae6ca60 state=pending>
2025-11-14 10:09:21,208 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-14 10:09:21,208 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7facaae6d870>
2025-11-14 10:09:21,209 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7facaae6d870>
2025-11-14 10:09:21,209 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5a0b1a8e-e16a-416a-899b-3cddf5b4e6e9, ghrun-adxazuzm5e.auto.internal:11411): creating call state
2025-11-14 10:09:21,210 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5a0b1a8e-e16a-416a-899b-3cddf5b4e6e9, ghrun-adxazuzm5e.auto.internal:11411): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-14 10:09:21,215 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5a0b1a8e-e16a-416a-899b-3cddf5b4e6e9, ghrun-adxazuzm5e.auto.internal:11411): on response callback started
2025-11-14 10:09:21,215 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5a0b1a8e-e16a-416a-899b-3cddf5b4e6e9, ghrun-adxazuzm5e.auto.internal:11411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=N2QzMjQ1M2MtMTY3MGFiY2QtZTQzNjE5YzAtODY5ZmM0ZDg=" } } } }
2025-11-14 10:09:21,216 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7facaae6d870>
2025-11-14 10:09:21,216 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7facaae6d870>
2025-11-14 10:09:21,216 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 5a0b1a8e-e16a-416a-899b-3cddf5b4e6e9, ghrun-adxazuzm5e.auto.internal:11411): on response callback success
2025-11-14 10:09:21,217 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 4c49693d-91c9-4d42-83bf-a69c37ef05ff, ghrun-adxazuzm5e.auto.internal:11411): creating call state
2025-11-14 10:09:21,218 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 4c49693d-91c9-4d42-83bf-a69c37ef05ff, ghrun-adxazuzm5e.auto.internal:11411): request = { session_id: "ydb://session/3?node_id=1&id=N2QzMjQ1M2MtMTY3MGFiY2QtZTQzNjE5YzAtODY5ZmM0ZDg=" path: "local/DbPoolStorage_ee47b0bf-314c-41d6-ad98-24c59a43d8b7/tenants" columns { name: "tenant" type { optional_type { item { type_id: STRING } } } } columns { name: "vtenant" type { optional_type { item { type_id: STRING } } } } columns { name: "common" type { optional_type { item { type_id: BOOL } } } } columns { name: "state" type { optional_type { item { type_id: UINT32 } } } } columns { name: "state_time" type { optional_type { item { type_id: TIMESTAMP } } } } primary_key: "tenant" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-14 10:09:21,271 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 4c49693d-91c9-4d42-83bf-a69c37ef05ff, ghrun-adxazuzm5e.auto.internal:11411): response = { operation { ready: true status: SUCCESS } }
2025-11-14 10:09:21,272 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7facaae6d870>
2025-11-14 10:09:21,273 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, c94faeac-f885-4162-b834-d9790d0ed2f2, ghrun-adxazuzm5e.auto.internal:11411): creating call state
2025-11-14 10:09:21,273 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, c94faeac-f885-4162-b834-d9790d0ed2f2, ghrun-adxazuzm5e.auto.internal:11411): request = { session_id: "ydb://session/3?node_id=1&id=N2QzMjQ1M2MtMTY3MGFiY2QtZTQzNjE5YzAtODY5ZmM0ZDg=" path: "local/DbPoolStorage_ee47b0bf-314c-41d6-ad98-24c59a43d8b7/mappings" columns { name: "subject_type" type { optional_type { item { type_id: STRING } } } } columns { name: "subject_id" type { optional_type { item { type_id: STRING } } } } columns { name: "vtenant" type { optional_type { item { type_id: STRING } } } } primary_key: "subject_type" primary_key: "subject_id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-14 10:09:21,304 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, c94faeac-f885-4162-b834-d9790d0ed2f2, ghrun-adxazuzm5e.auto.internal:11411): response = { operation { ready: true status: SUCCESS } }
2025-11-14 10:09:21,305 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7facaae6d870>
2025-11-14 10:09:21,306 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 1d38cbe4-32a6-4e0e-8def-60f3ee849cf3, ghrun-adxazuzm5e.auto.internal:11411): creating call state
2025-11-14 10:09:21,307 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 1d38cbe4-32a6-4e0e-8def-60f3ee849cf3, ghrun-adxazuzm5e.auto.internal:11411): request = { session_id: "ydb://session/3?node_id=1&id=N2QzMjQ1M2MtMTY3MGFiY2QtZTQzNjE5YzAtODY5ZmM0ZDg=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "--!syntax_v1\n            PRAGMA TablePathPrefix(\"local/DbPoolStorage_ee47b0bf-314c-41d6-ad98-24c59a43d8b7\");\n            UPSERT INTO tenants (tenant, vtenant, common, state, state_time) values(\"/default\", \"/default\", true, 0, CurrentUtcTimestamp());\n                " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-14 10:09:21,359 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 1d38cbe4-32a6-4e0e-8def-60f3ee849cf3, ghrun-adxazuzm5e.auto.internal:11411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-14 10:09:21,360 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7facaae6d870>
2025-11-14 10:09:21,436 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:21,437 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24834
2025-11-14 10:09:21,438 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9960
2025-11-14 10:09:21,439 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/default/kikimr_configs"
2025-11-14 10:09:21,440 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --tenant=/default --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/default/node_1/logfile_o_co5cxx.log --grpc-port=25655 --mon-port=24834 --ic-port=11757
2025-11-14 10:09:21,441 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/default/kikimr_configs/config.yaml', '--node=1', '--tenant=/default', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/default/node_1/logfile_o_co5cxx.log', '--grpc-port=25655', '--mon-port=24834', '--ic-port=11757')' in '/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/default/node_1' (no wait)
2025-11-14 10:09:21,446 - DEBUG - ya.test - execute: Command pid: 462219
2025-11-14 10:09:21,447 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:25655/1
2025-11-14 10:09:21,448 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 10:09:21,451 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25498
2025-11-14 10:09:21,453 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23495
2025-11-14 10:09:21,528 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:21,529 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29770
2025-11-14 10:09:21,530 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27034
2025-11-14 10:09:21,593 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:21,593 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2858
2025-11-14 10:09:21,594 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4862
2025-11-14 10:09:21,599 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4630
2025-11-14 10:09:21,601 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-14 10:09:21,603 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 10:09:21,604 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-14 10:09:21,611 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-14 10:09:21,611 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 1181059e-c156-4a63-ad85-afd15f281c20, localhost:11411): creating call state
2025-11-14 10:09:21,612 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 1181059e-c156-4a63-ad85-afd15f281c20, localhost:11411): request = { database: "local" }
2025-11-14 10:09:21,620 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 1181059e-c156-4a63-ad85-afd15f281c20, localhost:11411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-adxazuzm5e.auto.internal" port: 11411 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-14 10:09:21,621 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-adxazuzm5e.auto.internal:11411, location 1, ssl: False>]>
2025-11-14 10:09:21,621 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin')]
2025-11-14 10:09:21,625 - INFO - ydb.connection - close: Closing channel for endpoint localhost:11411
2025-11-14 10:09:21,626 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-14 10:09:21,627 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7facaaf7aea0 state=pending>
2025-11-14 10:09:21,627 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-14 10:09:21,627 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7facaaf7bbc0>
2025-11-14 10:09:21,628 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7facaaf7bbc0>
2025-11-14 10:09:21,628 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d4cf1634-e465-459d-aa31-d383398b55bf, ghrun-adxazuzm5e.auto.internal:11411): creating call state
2025-11-14 10:09:21,629 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d4cf1634-e465-459d-aa31-d383398b55bf, ghrun-adxazuzm5e.auto.internal:11411): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-14 10:09:21,638 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d4cf1634-e465-459d-aa31-d383398b55bf, ghrun-adxazuzm5e.auto.internal:11411): on response callback started
2025-11-14 10:09:21,639 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d4cf1634-e465-459d-aa31-d383398b55bf, ghrun-adxazuzm5e.auto.internal:11411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=N2Q1NzgyZDktOTkyYTA2ZTgtMjllOTJhMzAtYWZhMWE3ZmM=" } } } }
2025-11-14 10:09:21,640 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7facaaf7bbc0>
2025-11-14 10:09:21,640 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7facaaf7bbc0>
2025-11-14 10:09:21,640 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, d4cf1634-e465-459d-aa31-d383398b55bf, ghrun-adxazuzm5e.auto.internal:11411): on response callback success
2025-11-14 10:09:21,641 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, cb269336-9e68-4bbf-80b1-7166ceea659b, ghrun-adxazuzm5e.auto.internal:11411): creating call state
2025-11-14 10:09:21,643 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, cb269336-9e68-4bbf-80b1-7166ceea659b, ghrun-adxazuzm5e.auto.internal:11411): request = { session_id: "ydb://session/3?node_id=1&id=N2Q1NzgyZDktOTkyYTA2ZTgtMjllOTJhMzAtYWZhMWE3ZmM=" path: "local/DbPoolStorage_2749f3ba-ee6d-4eb9-b4f1-da2e1d1bfc39/tenants" columns { name: "tenant" type { optional_type { item { type_id: STRING } } } } columns { name: "vtenant" type { optional_type { item { type_id: STRING } } } } columns { name: "common" type { optional_type { item { type_id: BOOL } } } } columns { name: "state" type { optional_type { item { type_id: UINT32 } } } } columns { name: "state_time" type { optional_type { item { type_id: TIMESTAMP } } } } primary_key: "tenant" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-14 10:09:21,710 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, cb269336-9e68-4bbf-80b1-7166ceea659b, ghrun-adxazuzm5e.auto.internal:11411): response = { operation { ready: true status: SUCCESS } }
2025-11-14 10:09:21,711 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7facaaf7bbc0>
2025-11-14 10:09:21,712 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, f6be9efb-8033-4714-ada6-e563b4455325, ghrun-adxazuzm5e.auto.internal:11411): creating call state
2025-11-14 10:09:21,713 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, f6be9efb-8033-4714-ada6-e563b4455325, ghrun-adxazuzm5e.auto.internal:11411): request = { session_id: "ydb://session/3?node_id=1&id=N2Q1NzgyZDktOTkyYTA2ZTgtMjllOTJhMzAtYWZhMWE3ZmM=" path: "local/DbPoolStorage_2749f3ba-ee6d-4eb9-b4f1-da2e1d1bfc39/mappings" columns { name: "subject_type" type { optional_type { item { type_id: STRING } } } } columns { name: "subject_id" type { optional_type { item { type_id: STRING } } } } columns { name: "vtenant" type { optional_type { item { type_id: STRING } } } } primary_key: "subject_type" primary_key: "subject_id" operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-14 10:09:21,770 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, f6be9efb-8033-4714-ada6-e563b4455325, ghrun-adxazuzm5e.auto.internal:11411): response = { operation { ready: true status: SUCCESS } }
2025-11-14 10:09:21,771 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7facaaf7bbc0>
2025-11-14 10:09:21,772 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 9141fa8b-8a44-44c3-891c-75ae014ec43c, ghrun-adxazuzm5e.auto.internal:11411): creating call state
2025-11-14 10:09:21,773 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 9141fa8b-8a44-44c3-891c-75ae014ec43c, ghrun-adxazuzm5e.auto.internal:11411): request = { session_id: "ydb://session/3?node_id=1&id=N2Q1NzgyZDktOTkyYTA2ZTgtMjllOTJhMzAtYWZhMWE3ZmM=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "--!syntax_v1\n            PRAGMA TablePathPrefix(\"local/DbPoolStorage_2749f3ba-ee6d-4eb9-b4f1-da2e1d1bfc39\");\n            UPSERT INTO tenants (tenant, vtenant, common, state, state_time) values(\"/cp\", \"/cp\", true, 0, CurrentUtcTimestamp());\n                UPSERT INTO tenants (tenant, vtenant, common, state, state_time) values(\"/compute\", \"/compute\", true, 0, CurrentUtcTimestamp());\n                " } operation_params { operation_timeout { seconds: 600 } cancel_after { seconds: 600 } } }
2025-11-14 10:09:21,831 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 9141fa8b-8a44-44c3-891c-75ae014ec43c, ghrun-adxazuzm5e.auto.internal:11411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-14 10:09:21,831 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7facaaf7bbc0>
2025-11-14 10:09:21,832 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20275
2025-11-14 10:09:21,922 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:21,924 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13143
2025-11-14 10:09:21,925 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2293
2025-11-14 10:09:21,928 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/cp/kikimr_configs"
2025-11-14 10:09:21,928 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --tenant=/cp --node-kind=yq --log-file-name=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/cp/node_1/logfile_p8y7l3oc.log --grpc-port=4630 --mon-port=13143 --ic-port=29770
2025-11-14 10:09:21,930 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/cp/kikimr_configs/config.yaml', '--node=1', '--tenant=/cp', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/cp/node_1/logfile_p8y7l3oc.log', '--grpc-port=4630', '--mon-port=13143', '--ic-port=29770')' in '/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/cp/node_1' (no wait)
2025-11-14 10:09:21,934 - DEBUG - ya.test - execute: Command pid: 463161
2025-11-14 10:09:21,935 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:4630/1
2025-11-14 10:09:21,936 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 10:09:21,978 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:21,978 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22612
2025-11-14 10:09:21,979 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24101
2025-11-14 10:09:21,980 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/compute/kikimr_configs"
2025-11-14 10:09:21,980 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/compute/node_1/logfile_yky6pd8f.log --grpc-port=20275 --mon-port=22612 --ic-port=2858
2025-11-14 10:09:21,981 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/compute/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/compute/node_1/logfile_yky6pd8f.log', '--grpc-port=20275', '--mon-port=22612', '--ic-port=2858')' in '/home/runner/.ya/build/build_root/wp2g/003129/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_format_setting/testing_out_stuff/test_format_setting.py.TestS3.test_interval_unit.v2-client0/compute/node_1' (no wait)
2025-11-14 10:09:21,982 - DEBUG - ya.test - execute: Command pid: 463279
2025-11-14 10:09:21,983 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:20275/1
2025-11-14 10:09:26,217 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:26,224 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-14 10:09:26,224 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-14 10:09:26,224 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20275', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 2858\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 10:09:26,230 - DEBUG - ya.test - execute: Command pid: 469151
2025-11-14 10:09:27,288 - DEBUG - ya.test - _save_outputs: Command (pid 469151) rc: 0
2025-11-14 10:09:27,288 - DEBUG - ya.test - _save_outputs: Command (pid 469151) elapsed time (sec): 1.0568475723266602
2025-11-14 10:09:27,288 - DEBUG - ya.test - _save_outputs: Command (pid 469151) idrss: 0
2025-11-14 10:09:27,288 - DEBUG - ya.test - _save_outputs: Command (pid 469151) inblock: 0
2025-11-14 10:09:27,289 - DEBUG - ya.test - _save_outputs: Command (pid 469151) isrss: 0
2025-11-14 10:09:27,289 - DEBUG - ya.test - _save_outputs: Command (pid 469151) ixrss: 0
2025-11-14 10:09:27,289 - DEBUG - ya.test - _save_outputs: Command (pid 469151) majflt: 0
2025-11-14 10:09:27,289 - DEBUG - ya.test - _save_outputs: Command (pid 469151) maxrss: 525772
2025-11-14 10:09:27,289 - DEBUG - ya.test - _save_outputs: Command (pid 469151) minflt: 19683
2025-11-14 10:09:27,289 - DEBUG - ya.test - _save_outputs: Command (pid 469151) msgrcv: 0
2025-11-14 10:09:27,289 - DEBUG - ya.test - _save_outputs: Command (pid 469151) msgsnd: 0
2025-11-14 10:09:27,290 - DEBUG - ya.test - _save_outputs: Command (pid 469151) nivcsw: 170
2025-11-14 10:09:27,290 - DEBUG - ya.test - _save_outputs: Command (pid 469151) nsignals: 0
2025-11-14 10:09:27,290 - DEBUG - ya.test - _save_outputs: Command (pid 469151) nswap: 0
2025-11-14 10:09:27,290 - DEBUG - ya.test - _save_outputs: Command (pid 469151) nvcsw: 127
2025-11-14 10:09:27,290 - DEBUG - ya.test - _save_outputs: Command (pid 469151) oublock: 8
2025-11-14 10:09:27,291 - DEBUG - ya.test - _save_outputs: Command (pid 469151) stime: 0.737553
2025-11-14 10:09:27,291 - DEBUG - ya.test - _save_outputs: Command (pid 469151) utime: 0.10290099999999999
2025-11-14 10:09:27,291 - DEBUG - ya.test - _save_outputs: Command (pid 469151) wtime: 1.063
2025-11-14 10:09:27,291 - DEBUG - ya.test - _save_outputs: Command (pid 469151) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-14 10:09:27,291 - DEBUG - ya.test - _save_outputs: Command (pid 469151) errors:

2025-11-14 10:09:27,292 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:27,293 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-14 10:09:27,294 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-14 10:09:27,294 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20275', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 10:09:27,295 - DEBUG - ya.test - execute: Command pid: 470629
2025-11-14 10:09:28,175 - DEBUG - ya.test - _save_outputs: Command (pid 470629) rc: 0
2025-11-14 10:09:28,175 - DEBUG - ya.test - _save_outputs: Command (pid 470629) elapsed time (sec): 0.8741414546966553
2025-11-14 10:09:28,175 - DEBUG - ya.test - _save_outputs: Command (pid 470629) idrss: 0
2025-11-14 10:09:28,176 - DEBUG - ya.test - _save_outputs: Command (pid 470629) inblock: 0
2025-11-14 10:09:28,176 - DEBUG - ya.test - _save_outputs: Command (pid 470629) isrss: 0
2025-11-14 10:09:28,176 - DEBUG - ya.test - _save_outputs: Command (pid 470629) ixrss: 0
2025-11-14 10:09:28,176 - DEBUG - ya.test - _save_outputs: Command (pid 470629) majflt: 0
2025-11-14 10:09:28,176 - DEBUG - ya.test - _save_outputs: Command (pid 470629) maxrss: 525752
2025-11-14 10:09:28,177 - DEBUG - ya.test - _save_outputs: Command (pid 470629) minflt: 19688
2025-11-14 10:09:28,177 - DEBUG - ya.test - _save_outputs: Command (pid 470629) msgrcv: 0
2025-11-14 10:09:28,177 - DEBUG - ya.test - _save_outputs: Command (pid 470629) msgsnd: 0
2025-11-14 10:09:28,177 - DEBUG - ya.test - _save_outputs: Command (pid 470629) nivcsw: 152
2025-11-14 10:09:28,177 - DEBUG - ya.test - _save_outputs: Command (pid 470629) nsignals: 0
2025-11-14 10:09:28,178 - DEBUG - ya.test - _save_outputs: Command (pid 470629) nswap: 0
2025-11-14 10:09:28,178 - DEBUG - ya.test - _save_outputs: Command (pid 470629) nvcsw: 88
2025-11-14 10:09:28,178 - DEBUG - ya.test - _save_outputs: Command (pid 470629) oublock: 8
2025-11-14 10:09:28,178 - DEBUG - ya.test - _save_outputs: Command (pid 470629) stime: 0.485612
2025-11-14 10:09:28,178 - DEBUG - ya.test - _save_outputs: Command (pid 470629) utime: 0.099315
2025-11-14 10:09:28,179 - DEBUG - ya.test - _save_outputs: Command (pid 470629) wtime: 0.875
2025-11-14 10:09:28,179 - DEBUG - ya.test - _save_outputs: Command (pid 470629) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-14 10:09:28,179 - DEBUG - ya.test - _save_outputs: Command (pid 470629) errors:

2025-11-14 10:09:28,180 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd
2025-11-14 10:09:28,181 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-14 10:09:28,181 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-14 10:09:28,182 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/wp2g/003129/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:20275', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 2\n    Name: "dynamic_storage_pool:2"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "ssd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 10:09:28,183 - DEBUG - ya.test - execute: Command pid: 472041
2025-11-14 10:09:28,929 - DEBUG - ya.test - _save_outputs: Command (pid 472041) rc: 0
2025-11-14 10:09:28,929 - DEBUG - ya.test - _save_outputs: Command (pid 472041) elapsed time (sec): 0.7449829578399658
2025-11-14 10:09:28,929 - DEBUG - ya.test - _save_outputs: Command (pid 472041) idrss: 0
2025-11-14 10:09:28,930 - DEBUG - ya.test - _save_outputs: Command (pid 472041) inblock: 0
2025-11-14 10:09:28,930 - DEBUG - ya.test - _save_outputs: Command (pid 472041) isrss: 0
2025-11-14 10:09:28,930 - DEBUG - ya.test - _save_outputs: Command (pid 472041) ixrss: 0
2025-11-14 10:09:28,930 - DEBUG - ya.test - _save_outputs: Command (pid 472041) majflt: 0
2025-11-14 10:09:28,930 - DEBUG - ya.test - _save_outputs: Command (pid 472041) maxrss: 525744
2025-11-14 10:09:28,930 - DEBUG - ya.test - _save_outputs: Command (pid 472041) minflt: 19688
2025-11-14 10:09:28,930 - DEBUG - ya.test - _save_outputs: Command (pid 472041) msgrcv: 0
2025-11-14 10:09:28,931 - DEBUG - ya.test - _save_outputs: Command (pid 472041) msgsnd: 0
2025-11-14 10:09:28,931 - DEBUG - ya.test - _save_outputs: Command (pid 472041) nivcsw: 102
2025-11-14 10:09:28,931 - DEBUG - ya.test - _save_outputs: Command (pid 472041) nsignals: 0
2025-11-14 10:09:28,931 - DEBUG - ya.test - _save_outputs: Command (pid 472041) nswap: 0
2025-11-14 10:09:28,931 - DEBUG - ya.test - _save_outputs: Command (pid 472041) nvcsw: 133
2025-11-14 10:09:28,931 - DEBUG - ya.test - _save_outputs: Command (pid 472041) oublock: 8
2025-11-14 10:09:28,932 - DEBUG - ya.test - _save_outputs: Command (pid 472041) stime: 0.446025
2025-11-14 10:09:28,932 - DEBUG - ya.test - _save_outputs: Command (pid 472041) utime: 0.10452199999999999
2025-11-14 10:09:28,932 - DEBUG - ya.test - _save_outputs: Command (pid 472041) wtime: 0.746
2025-11-14 10:09:28,932 - DEBUG - ya.test - _save_outputs: Command (pid 472041) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 7

2025-11-14 10:09:28,932 - DEBUG - ya.test - _save_outputs: Command (pid 472041) errors:

2025-11-14 10:09:28,933 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain local: {'dynamic_storage_pool:1': 'hdd', 'dynamic_storage_pool:2': 'ssd'}
2025-11-14 10:09:28,984 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 10:09:28,995 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-14 10:09:29,045 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-14 10:09:29,095 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-14 10:09:29,201 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-14 10:09:29,304 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_format_setting.py::TestS3, test_name: test_interval_unit[v2-client0])
2025-11-14 10:09:29,307 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from creating-client-class.iot-data to creating-client-class.iot-data-plane
2025-11-14 10:09:29,322 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-call.apigateway to before-call.api-gateway
2025-11-14 10:09:29,325 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from request-created.machinelearning.Predict to request-created.machine-learning.Predict
2025-11-14 10:09:29,330 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.autoscaling.CreateLaunchConfiguration to before-parameter-build.auto-scaling.CreateLaunchConfiguration
2025-11-14 10:09:29,331 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.route53 to before-parameter-build.route-53
2025-11-14 10:09:29,334 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from request-created.cloudsearchdomain.Search to request-created.cloudsearch-domain.Search
2025-11-14 10:09:29,336 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.autoscaling.CreateLaunchConfiguration.complete-section to docs.*.auto-scaling.CreateLaunchConfiguration.complete-section
2025-11-14 10:09:29,347 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.logs.CreateExportTask to before-parameter-build.cloudwatch-logs.CreateExportTask
2025-11-14 10:09:29,348 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.logs.CreateExportTask.complete-section to docs.*.cloudwatch-logs.CreateExportTask.complete-section
2025-11-14 10:09:29,348 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from before-parameter-build.cloudsearchdomain.Search to before-parameter-build.cloudsearch-domain.Search
2025-11-14 10:09:29,348 - DEBUG - botocore.hooks - _alias_event_name: Changing event name from docs.*.cloudsearchdomain.Search.complete-section to docs.*.cloudsearch-domain.Search.complete-section
2025-11-14 10:09:29,470 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/boto3/py3/boto3/data/s3/2006-03-01/resources-1.json
2025-11-14 10:09:29,505 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/endpoints.json
2025-11-14 10:09:29,555 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/sdk-default-configuration.json
2025-11-14 10:09:29,556 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7facac218dd0>
2025-11-14 10:09:29,560 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/s3/2006-03-01/service-2.json
2025-11-14 10:09:29,599 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/s3/2006-03-01/endpoint-rule-set-1.json.gz
2025-11-14 10:09:29,607 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/partitions.json
2025-11-14 10:09:29,612 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7facac324ad0>
2025-11-14 10:09:29,612 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7facaab71250>
2025-11-14 10:09:29,649 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7facac3247d0>
2025-11-14 10:09:29,657 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-14 10:09:29,663 - DEBUG - botocore.loaders - _load_resource: Loading JSON file: contrib/python/botocore/py3/botocore/data/_retry.json
2025-11-14 10:09:29,664 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-14 10:09:29,665 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-14 10:09:29,667 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:s3
2025-11-14 10:09:29,670 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:Bucket
2025-11-14 10:09:29,671 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming Bucket attribute name
2025-11-14 10:09:29,673 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.Bucket: calling handler <function lazy_call.<locals>._handler at 0x7facaab71cd0>
2025-11-14 10:09:29,673 - DEBUG - boto3.resources.action - __call__: Calling s3:create_bucket with {'Bucket': 'fbucket', 'ACL': 'public-read'}
2025-11-14 10:09:29,675 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:09:29,675 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:09:29,675 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7facaad0c740>>
2025-11-14 10:09:29,675 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:09:29,676 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:09:29,676 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7facaad0c740>>
2025-11-14 10:09:29,677 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableAccessPoints': True, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:09:29,679 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:09:29,679 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:09:29,679 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:09:29,681 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:09:29,681 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:09:29,681 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:09:29,681 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=CreateBucket) with params: {'url_path': '', 'query_string': {}, 'method': 'PUT', 'headers': {'x-amz-acl': 'public-read', 'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource'}, 'body': b'', 'auth_path': '/fbucket/', 'url': 'http://localhost:20364/fbucket', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7facab177df0>, 'has_streaming_input': False, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'ACL': 'public-read'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:09:29,683 - DEBUG - botocore.hooks - _emit: Event request-created.s3.CreateBucket: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7facab177cb0>>
2025-11-14 10:09:29,683 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.CreateBucket: calling handler <bound method ClientCreator._default_s3_presign_to_sigv2 of <botocore.client.ClientCreator object at 0x7facaaf4f210>>
2025-11-14 10:09:29,684 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.CreateBucket: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:09:29,684 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.CreateBucket: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:09:29,686 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:09:29,686 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
PUT
/fbucket

host:localhost:20364
x-amz-acl:public-read
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251114T100929Z

host;x-amz-acl;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-14 10:09:29,686 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T100929Z
20251114/yandex/s3/aws4_request
b1b3e0270501a9d0f09e55a19f12e29b98d8d89a349ade8b3036eb66593f662e
2025-11-14 10:09:29,686 - DEBUG - botocore.auth - add_auth: Signature:
07592783e0d866bd0ba81bb5e573a614367c561f59d0fe5561f598b57f8d77f1
2025-11-14 10:09:29,687 - DEBUG - botocore.hooks - _emit: Event request-created.s3.CreateBucket: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:09:29,687 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=http://localhost:20364/fbucket, headers={'x-amz-acl': b'public-read', 'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource', 'X-Amz-Date': b'20251114T100929Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=host;x-amz-acl;x-amz-content-sha256;x-amz-date, Signature=07592783e0d866bd0ba81bb5e573a614367c561f59d0fe5561f598b57f8d77f1', 'amz-sdk-invocation-id': b'8997fcbd-7cb5-41de-8508-f57d249baf7e', 'amz-sdk-request': b'attempt=1', 'Content-Length': '0'}>
2025-11-14 10:09:29,700 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:09:29 GMT', 'x-amzn-requestid': '1Mf7qj9W7guDT7QSn8jpcJL3ACv9ERG5WXyl3gBKRXEsBmciBoRq', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '161', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:09:29,701 - DEBUG - botocore.parsers - parse: Response body:
b'<CreateBucketResponse xmlns="http://s3.amazonaws.com/doc/2006-03-01"><CreateBucketResponse><Bucket>fbucket</Bucket></CreateBucketResponse></CreateBucketResponse>'
2025-11-14 10:09:29,703 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.CreateBucket: calling handler <botocore.retryhandler.RetryHandler object at 0x7facab10b210>
2025-11-14 10:09:29,703 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:09:29,703 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.CreateBucket: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7facaad0c740>>
2025-11-14 10:09:29,704 - DEBUG - boto3.resources.action - __call__: Response: {'ResponseMetadata': {'RequestId': '1Mf7qj9W7guDT7QSn8jpcJL3ACv9ERG5WXyl3gBKRXEsBmciBoRq', 'HTTPStatusCode': 200, 'HTTPHeaders': {'server': 'Werkzeug/2.2.3 Python/3.12.9', 'date': 'Fri, 14 Nov 2025 10:09:29 GMT', 'x-amzn-requestid': '1Mf7qj9W7guDT7QSn8jpcJL3ACv9ERG5WXyl3gBKRXEsBmciBoRq', 'content-type': 'text/html; charset=utf-8', 'content-length': '161', 'access-control-allow-origin': '*', 'connection': 'close'}, 'RetryAttempts': 0}}
2025-11-14 10:09:29,705 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7facac218dd0>
2025-11-14 10:09:29,709 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7facac324ad0>
2025-11-14 10:09:29,709 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7facaab71250>
2025-11-14 10:09:29,709 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7facac3247d0>
2025-11-14 10:09:29,718 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-14 10:09:29,723 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-14 10:09:29,724 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-14 10:09:29,727 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function validate_ascii_metadata at 0x7facac21bd10>
2025-11-14 10:09:29,727 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function sse_md5 at 0x7facac21ad50>
2025-11-14 10:09:29,727 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function convert_body_to_file_like_object at 0x7facac23ca10>
2025-11-14 10:09:29,728 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:09:29,728 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:09:29,728 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d9f5eb0>>
2025-11-14 10:09:29,728 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:09:29,729 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:09:29,729 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d9f5eb0>>
2025-11-14 10:09:29,730 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:09:29,869 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:09:29,870 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:09:29,870 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:09:29,871 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function conditionally_calculate_md5 at 0x7facac42fc50>
2025-11-14 10:09:29,872 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:09:29,872 - DEBUG - botocore.handlers - add_expect_header: Adding expect 100 continue header to request.
2025-11-14 10:09:29,872 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:09:29,872 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:09:29,873 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=PutObject) with params: {'url_path': '/fruits.csv', 'query_string': {}, 'method': 'PUT', 'headers': {'Content-Type': 'text/plain', 'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80', 'Content-MD5': '7/E0dWgaQtjK1EKCeKCITQ==', 'Expect': '100-continue'}, 'body': <_io.BytesIO object at 0x7fac8d995be0>, 'auth_path': '/fbucket/fruits.csv', 'url': 'http://localhost:20364/fbucket/fruits.csv', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7fac8d99f670>, 'has_streaming_input': True, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Body': <_io.BytesIO object at 0x7fac8d995be0>, 'Bucket': 'fbucket', 'Key': 'fruits.csv', 'ContentType': 'text/plain'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:09:29,874 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7fac8d99fa80>>
2025-11-14 10:09:29,875 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.PutObject: calling handler <bound method ClientCreator._default_s3_presign_to_sigv2 of <botocore.client.ClientCreator object at 0x7fac8d9f2090>>
2025-11-14 10:09:29,875 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.PutObject: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:09:29,875 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.PutObject: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:09:29,876 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:09:29,876 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
PUT
/fbucket/fruits.csv

content-md5:7/E0dWgaQtjK1EKCeKCITQ==
content-type:text/plain
host:localhost:20364
x-amz-content-sha256:e596233a6fb9c71e32d77a964ba6a558107ab5708fb83d458b6f9b4feca638ff
x-amz-date:20251114T100929Z

content-md5;content-type;host;x-amz-content-sha256;x-amz-date
e596233a6fb9c71e32d77a964ba6a558107ab5708fb83d458b6f9b4feca638ff
2025-11-14 10:09:29,877 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T100929Z
20251114/yandex/s3/aws4_request
7570141c5cb2b22bda49a30fd77742f100cf368df4de062516b26f454b091b6a
2025-11-14 10:09:29,877 - DEBUG - botocore.auth - add_auth: Signature:
89c89d56802e87fd6b5302107175a80666fe670870f0ef0dc9b36c3b0159dc82
2025-11-14 10:09:29,877 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:09:29,878 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=http://localhost:20364/fbucket/fruits.csv, headers={'Content-Type': b'text/plain', 'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80', 'Content-MD5': b'7/E0dWgaQtjK1EKCeKCITQ==', 'Expect': b'100-continue', 'X-Amz-Date': b'20251114T100929Z', 'X-Amz-Content-SHA256': b'e596233a6fb9c71e32d77a964ba6a558107ab5708fb83d458b6f9b4feca638ff', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=content-md5;content-type;host;x-amz-content-sha256;x-amz-date, Signature=89c89d56802e87fd6b5302107175a80666fe670870f0ef0dc9b36c3b0159dc82', 'amz-sdk-invocation-id': b'c525a838-6934-45da-bbf2-bbff10abc447', 'amz-sdk-request': b'attempt=1', 'Content-Length': '55'}>
2025-11-14 10:09:29,880 - DEBUG - botocore.awsrequest - _send_output: Waiting for 100 Continue response.
2025-11-14 10:09:29,883 - DEBUG - botocore.awsrequest - _handle_expect_response: 100 Continue response seen, now sending request body.
2025-11-14 10:09:29,892 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:09:29 GMT', 'ETag': '"eff13475681a42d8cad4428278a0884d"', 'last-modified': 'Fri, 14 Nov 2025 10:09:29 GMT', 'Content-Length': '0', 'x-amzn-requestid': 'K9kUOhNPoVCQrjnHmn6G424btQxBKunEkRLrkwIdjsvJnSgTZYMK', 'Content-Type': 'text/html; charset=utf-8', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:09:29,893 - DEBUG - botocore.parsers - parse: Response body:
b''
2025-11-14 10:09:29,894 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.PutObject: calling handler <botocore.retryhandler.RetryHandler object at 0x7fac8d987f30>
2025-11-14 10:09:29,894 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:09:29,895 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d9f5eb0>>
2025-11-14 10:09:29,895 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-14 10:09:29,955 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-14 10:09:30,990 - DEBUG - root - create_query_impl: Request: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "\n            SELECT *\n            FROM bindings.`h4477858012267010435_test_interval_unitmy_binding`;\n            "
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "e4615b00-a59e-44a7-b77e-9db083ff5763"

2025-11-14 10:09:31,307 - DEBUG - root - create_query_impl: Result: query_id: "ptqudsej778vuhu24bob"

2025-11-14 10:09:31,403 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej778vuhu24bob"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763114971
        nanos: 4944000
      }
      modified_at {
        seconds: 1763114971
        nanos: 4944000
      }
      revision: 1
    }
    execute_mode: RUN
    status: STARTING
    last_job_query_revision: 1
    last_job_id: "ptjudsej778vtoi0gg3t-ptqudsej778vuhu24bob"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763114971
      nanos: 4944000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM bindings.`h4477858012267010435_test_interval_unitmy_binding`;\n            "
    syntax: YQL_V1
  }
}

2025-11-14 10:09:31,937 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-14 10:09:32,465 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-14 10:09:33,116 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej778vuhu24bob"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763114971
        nanos: 4944000
      }
      modified_at {
        seconds: 1763114971
        nanos: 4944000
      }
      revision: 1
    }
    started_at {
      seconds: 1763114972
      nanos: 245000000
    }
    execute_mode: RUN
    status: RUNNING
    last_job_query_revision: 1
    last_job_id: "ptjudsej778vtoi0gg3t-ptqudsej778vuhu24bob"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763114971
      nanos: 4944000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM bindings.`h4477858012267010435_test_interval_unitmy_binding`;\n            "
    syntax: YQL_V1
  }
}

2025-11-14 10:09:33,651 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej778vuhu24bob"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763114971
        nanos: 4944000
      }
      modified_at {
        seconds: 1763114971
        nanos: 4944000
      }
      revision: 1
    }
    started_at {
      seconds: 1763114972
      nanos: 245000000
    }
    execute_mode: RUN
    status: COMPLETING
    last_job_query_revision: 1
    last_job_id: "ptjudsej778vtoi0gg3t-ptqudsej778vuhu24bob"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763114971
      nanos: 4944000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM bindings.`h4477858012267010435_test_interval_unitmy_binding`;\n            "
    syntax: YQL_V1
  }
  plan {
    json: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":5,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":1,\"Operators\":[{\"E-Size\":\"32.35294118\",\"Format\":\"csv_with_names\",\"Name\":\"h4477858012267010435_test_interval_unitmy_binding\",\"Inputs\":[],\"Path\":\"fruits.csv \",\"E-Rows\":\"1.011029412\",\"ExternalDataSource\":\"h4477858012267010435_test_interval_unitfruitbucket\",\"ReadColumns\":[\"Duration\",\"Fruit\",\"Price\"],\"SourceType\":\"s3\",\"E-Cost\":\"55\"}],\"Node Type\":\"Source\"}],\"Node Type\":\"Stage\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Node Type\":\"Collect\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"}}"
  }
  statistics {
    json: "{\"ResultSet\":{\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1},\"CpuTimeUs\":{\"sum\":\"666us\",\"count\":2,\"avg\":\"333us\",\"max\":\"554us\",\"min\":\"112us\"},\"IngressBytes\":{\"sum\":55,\"count\":1,\"avg\":55,\"max\":55,\"min\":55},\"IngressRows\":{\"sum\":3,\"count\":1,\"avg\":3,\"max\":3,\"min\":3},\"InputBytes\":{\"sum\":38,\"count\":1,\"avg\":38,\"max\":38,\"min\":38},\"InputRows\":{\"sum\":3,\"count\":1,\"avg\":3,\"max\":3,\"min\":3},\"OutputBytes\":{\"sum\":76,\"count\":2,\"avg\":38,\"max\":38,\"min\":38},\"OutputRows\":{\"sum\":6,\"count\":2,\"avg\":3,\"max\":3,\"min\":3},\"ResultBytes\":{\"sum\":38,\"count\":1,\"avg\":38,\"max\":38,\"min\":38},\"ResultRows\":{\"sum\":3,\"count\":1,\"avg\":3,\"max\":3,\"min\":3},\"MaxMemoryUsage\":{\"sum\":211812352,\"count\":2,\"avg\":105906176,\"max\":210763776,\"min\":1048576}}}"
  }
  ast {
    data: "(\n(let $1 (DataSource \'\"s3\" \'\"/local/h4477858012267010435_test_interval_unitfruitbucket\" \'\"/local/h4477858012267010435_test_interval_unitmy_binding\"))\n(let $2 (Bool \'\"true\"))\n(let $3 \'\"cluster:default_/local/h4477858012267010435_test_interval_unitfruitbucket\")\n(let $4 (StructType \'(\'\"Duration\" (DataType \'Interval)) \'(\'\"Fruit\" (DataType \'String)) \'(\'\"Price\" (DataType \'Int32))))\n(let $5 (S3ParseSettings world \'(\'((String \'\"Paths {\\n  Name: \\\"fruits.csv\\\"\\n  Size: 55\\n  Read: true\\n}\\n\") $2 (AsStruct))) (SecureParam $3) \'\"\" \'\"fruits.csv \" \'\"csv_with_names\" $4 (lambda \'($10) $2) \'(\'(\'\"csvdelimiter\" \'\";\") \'(\'\"data.interval.unit\" \'\"SECONDS\"))))\n(let $6 (DqPhyStage \'((DqSource $1 $5)) (lambda \'($11) (block \'(\n  (let $12 (DqSourceWideWrap $11 $1 $4 \'(\'(\'format \'\"csv_with_names\"))))\n  (return (FromFlow (NarrowMap $12 (lambda \'($13 $14 $15) (AsStruct \'(\'\"Duration\" $13) \'(\'\"Fruit\" $14) \'(\'\"Price\" $15))))))\n))) \'(\'(\'\"_logical_id\" \'390) \'(\'\"_id\" \'\"4146c355-d705b0a8-6471ac75-c78f1243\"))))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'\"0\")))\n(let $8 (DqPhyStage \'($7) (lambda \'($16) $16) \'(\'(\'\"_logical_id\" \'448) \'(\'\"_id\" \'\"97d875db-a8d62e96-84c60f51-907b494d\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'\"0\") \'()))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"generic\")))) \'((KqpTxResultBinding (ListType $4) \'\"0\" \'\"0\")) \'(\'(\'\"type\" \'\"script\"))))\n)\n"
  }
  timeline {
    svg: "<svg width=\'1280\' height=\'162\' xmlns=\'http://www.w3.org/2000/svg\'>\n<clipPath id=\'clipTextPath\'><rect x=\'0\' y=\'0\' width=\'298\' height=\'162\'/></clipPath>\n\n<defs>\n\n<g id=\'icon_memory\'>\n  <path fill-rule=\'evenodd\' clip-rule=\'evenodd\' d=\'M5 3C3.89543 3 3 3.89543 3 5H1V7H3V9H1V11H3V13H1V15H3V17H1V19H3C3 20.1046 3.89543 21 5 21H9C10.1046 21 11 20.1046 11 19H13C13 20.1046 13.8954 21 15 21H19C20.1046 21 21 20.1046 21 19H23V17H21V15H23V13H21V11H23V9H21V7H23V5H21C21 3.89543 20.1046 3 19 3H15C13.8954 3 13 3.89543 13 5H11C11 3.89543 10.1046 3 9 3H5ZM11 7V9H13V7H11ZM11 11V13H13V11H11ZM11 15V17H13V15H11ZM5 5H9V19H5V5ZM15 5H19V19H15V5Z\' />\n</g>\n\n<g id=\'icon_cpu\'>\n  <path d=\'M13.9802 7.75H10.0102C8.76023 7.75 7.74023 8.76 7.74023 10.02V13.99C7.74023 15.24 8.75023 16.26 10.0102 16.26H13.9802C15.2302 16.26 16.2502 15.25 16.2502 13.99V10.02C16.2502 8.76 15.2402 7.75 13.9802 7.75ZM13.5002 12.98L12.6102 14.53C12.4802 14.76 12.2402 14.88 12.0002 14.88C11.8802 14.88 11.7502 14.85 11.6502 14.79C11.3102 14.6 11.1902 14.17 11.3902 13.83L12.0302 12.72H11.4702C11.0202 12.72 10.6502 12.52 10.4502 12.18C10.2502 11.84 10.2702 11.42 10.5002 11.03L11.3902 9.48C11.5902 9.14 12.0202 9.03 12.3502 9.22C12.6902 9.41 12.8102 9.84 12.6102 10.18L11.9702 11.29H12.5302C12.9802 11.29 13.3502 11.49 13.5502 11.83C13.7502 12.17 13.7302 12.59 13.5002 12.98Z\' />\n  <path d=\'M21.25 12.75C21.67 12.75 22 12.41 22 12C22 11.58 21.67 11.25 21.25 11.25H20V9.05H21.25C21.67 9.05 22 8.72 22 8.3C22 7.89 21.67 7.55 21.25 7.55H19.77C19.29 5.96 18.04 4.71 16.45 4.23V2.75C16.45 2.34 16.11 2 15.7 2C15.29 2 14.95 2.34 14.95 2.75V4H12.75V2.75C12.75 2.34 12.41 2 12 2C11.59 2 11.25 2.34 11.25 2.75V4H9.06V2.75C9.06 2.34 8.72 2 8.31 2C7.89 2 7.56 2.34 7.56 2.75V4.23C5.96 4.71 4.71 5.96 4.23 7.55H2.75C2.34 7.55 2 7.89 2 8.3C2 8.72 2.34 9.05 2.75 9.05H4V11.25H2.75C2.34 11.25 2 11.58 2 12C2 12.41 2.34 12.75 2.75 12.75H4V14.95H2.75C2.34 14.95 2 15.28 2 15.7C2 16.11 2.34 16.45 2.75 16.45H4.23C4.7 18.04 5.96 19.29 7.56 19.77V21.25C7.56 21.66 7.89 22 8.31 22C8.72 22 9.06 21.66 9.06 21.25V20H11.26V21.25C11.26 21.66 11.59 22 12.01 22C12.42 22 12.76 21.66 12.76 21.25V20H14.95V21.25C14.95 21.66 15.29 22 15.7 22C16.11 22 16.45 21.66 16.45 21.25V19.77C18.04 19.29 19.29 18.04 19.77 16.45H21.25C21.67 16.45 22 16.11 22 15.7C22 15.28 21.67 14.95 21.25 14.95H20V12.75H21.25ZM17.26 14.26C17.26 15.91 15.91 17.26 14.26 17.26H9.74C8.09 17.26 6.74 15.91 6.74 14.26V9.74C6.74 8.09 8.09 6.74 9.74 6.74H14.26C15.91 6.74 17.26 8.09 17.26 9.74V14.26Z\' />\n</g>\n\n<g id=\'icon_input\'>\n  <path d=\'M3 6L6 6L6 12L10 12L10 6L13 6V5L8 0L3 5L3 6Z\' />\n  <path d=\'M2 16L14 16V14L2 14V16Z\' />\n</g>\n\n<g id=\'icon_output\'>\n  <path d=\'M13 9H10V16H6V9L3 9V8L8 3L13 8V9Z\' />\n  <path d=\'M14 2H2V0H14V2Z\' />\n</g>\n\n</defs>\n<rect x=\'0\' y=\'27\' width=\'298\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'301\' y=\'27\' width=\'64\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'395\' y=\'27\' width=\'200\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'368\' y=\'27\' width=\'24\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'598\' y=\'27\' width=\'682\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<circle cx=\'8\' cy=\'60\' r=\'7\' stroke=\'var(--stage-main, #F2F2F2)\' stroke-width=\'1\' fill=\'var(--stage-clone, #D9D9D9)\' />\n<text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'8\' y=\'64\'>1</text>\n<g><title>Output Rows 33%, 3</title>\n  <rect x=\'303\' y=\'33\' width=\'60\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'303,33 333,33 363,33 363,47 303,47\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'303\' y=\'36\' width=\'5\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'303\' y=\'44\'>3</text>\n</g>\n<g><title>Output 50%, 38B, Rows 50%, 3</title>\n<use href=\'#icon_output\' transform=\'translate(395 29) scale(0.9 0.9)\' fill=\'var(--output-medium, #5781B9)\'/>\n  <rect x=\'413\' y=\'29\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'413,29 503,29 593,29 593,43 413,43\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'413\' y=\'32\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'40\'>38B</text>\n</g>\n<g><title>Output, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'604\' y=\'29\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-medium, #5781B9)\'/>\n</g>\n<g><title>Memory 0%, 1.00MB</title>\n<use href=\'#icon_memory\' transform=\'translate(395 45) scale(0.6 0.6)\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <rect x=\'413\' y=\'45\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--mem-light, #AA7785)\'/>  <polygon points=\'413,45 413,45 413,45 413,59 413,59\' stroke=\'none\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n<rect x=\'413\' y=\'48\' width=\'33\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'56\'>1.00MB</text>\n</g>\n<g><title>CPU Usage 16%, 112us</title>\n<use href=\'#icon_cpu\' transform=\'translate(395 61) scale(0.6 0.6)\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <rect x=\'413\' y=\'61\' width=\'36\' height=\'14\' stroke-width=\'0\' fill=\'var(--cpu-light, #B78C98)\'/>  <polygon points=\'413,61 431,61 449,61 449,75 413,75\' stroke=\'none\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n<rect x=\'413\' y=\'64\' width=\'28\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'72\'>112us</text>\n</g>\n<g><title>Input 100%, 38B, Rows 100%, 3, Width 12B</title>\n<use href=\'#icon_input\' transform=\'translate(395 77) scale(0.9 0.9)\' fill=\'var(--input-medium, #5A8183)\'/>\n  <rect x=\'413\' y=\'77\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--input-light, #7CA3A5)\'/>  <polygon points=\'413,77 503,77 593,77 593,91 413,91\' stroke=\'none\' fill=\'var(--input-medium, #5A8183)\'/>\n<rect x=\'413\' y=\'80\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'88\'>38B</text>\n</g>\n<g><title>Input, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'604\' y=\'77\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--input-medium, #5A8183)\'/>\n</g>\n<rect x=\'0\' y=\'96\' width=\'298\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'301\' y=\'96\' width=\'64\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'395\' y=\'96\' width=\'200\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'368\' y=\'96\' width=\'24\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'598\' y=\'96\' width=\'682\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<circle cx=\'8\' cy=\'129\' r=\'7\' stroke=\'var(--stage-main, #F2F2F2)\' stroke-width=\'1\' fill=\'var(--stage-clone, #D9D9D9)\' />\n<text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'8\' y=\'133\'>0</text>\n<g><title>Output Rows 33%, 3</title>\n  <rect x=\'303\' y=\'102\' width=\'60\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'303,102 333,102 363,102 363,116 303,116\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'303\' y=\'105\' width=\'5\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'303\' y=\'113\'>3</text>\n</g>\n<g><title>Output Rows 33%, 3, Est: 55 Rows: 1 Size: 32B</title>\n  <rect x=\'303\' y=\'122\' width=\'60\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'303,122 333,122 363,122 363,136 303,136\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'303\' y=\'125\' width=\'5\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'303\' y=\'133\'>3</text>\n</g>\n<g><title>Output 50%, 38B, Rows 50%, 3</title>\n<use href=\'#icon_output\' transform=\'translate(395 98) scale(0.9 0.9)\' fill=\'var(--output-medium, #5781B9)\'/>\n  <rect x=\'413\' y=\'98\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'413,98 503,98 593,98 593,112 413,112\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'413\' y=\'101\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'109\'>38B</text>\n</g>\n<g><title>Output, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'604\' y=\'98\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-medium, #5781B9)\'/>\n</g>\n<g><title>Memory 99%, 201.00MB</title>\n<use href=\'#icon_memory\' transform=\'translate(395 114) scale(0.6 0.6)\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <rect x=\'413\' y=\'114\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--mem-light, #AA7785)\'/>  <polygon points=\'413,114 503,114 593,114 593,128 413,128\' stroke=\'none\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n<rect x=\'413\' y=\'117\' width=\'44\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'125\'>201.00MB</text>\n</g>\n<g><title>CPU Usage 83%, 554us</title>\n<use href=\'#icon_cpu\' transform=\'translate(395 130) scale(0.6 0.6)\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <rect x=\'413\' y=\'130\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--cpu-light, #B78C98)\'/>  <polygon points=\'413,130 503,130 593,130 593,144 413,144\' stroke=\'none\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n<rect x=\'413\' y=\'133\' width=\'28\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'141\'>554us</text>\n</g>\n<g><title>Ingress 100%, 55B, Rows 100%, 3, Width 18B</title>\n<use href=\'#icon_input\' transform=\'translate(395 146) scale(0.9 0.9)\' fill=\'var(--ingress-medium, #466364)\'/>\n  <rect x=\'413\' y=\'146\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--ingress-light, #5A8183)\'/>  <polygon points=\'413,146 503,146 593,146 593,160 413,160\' stroke=\'none\' fill=\'var(--ingress-medium, #466364)\'/>\n<rect x=\'413\' y=\'149\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'157\'>55B</text>\n</g>\n<g><title>Ingress, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'604\' y=\'146\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--ingress-medium, #466364)\'/>\n</g>\n\n<line x1=\'600\' y1=\'0\' x2=\'600\' y2=\'162\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'602\' y=\'11\'>0:00</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'0\' y=\'11\'>ResultSet</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'303\' y=\'11\'>Operators</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'397\' y=\'11\'>Stages</text>\n<text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'11\'>Tasks</text>\n<text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'22\'>2</text>\n<g><title>Ingress 55B, Rows 3, Width 18B, Avg 55B/s</title>\n  <rect x=\'395\' y=\'14\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--ingress-medium, #466364)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'397\' y=\'22\'>55B</text>\n</g>\n<g><title>CPU Usage 666us, Avg 0.00 CPU/s</title>\n  <rect x=\'462\' y=\'14\' width=\'66\' height=\'10\' stroke-width=\'0\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'464\' y=\'22\'>666us</text>\n</g>\n<g><title>Memory 202.00MB</title>\n  <rect x=\'530\' y=\'14\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'532\' y=\'22\'>202.00MB</text>\n</g>\n<g><title>Duration: 1.00, Total 1.00</title>\n  <rect x=\'1215\' y=\'3\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--stage-grid, #B2B2B2)\'/>\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-inv, #FFFFFF)\' x=\'1278\' y=\'11\'>1.00</text>\n</g>\n<g><title>Max CPU 0.50</title>\n<polygon points=\'599,26 599,26 599,26 599,26 600,26 600,26 600,26 600,26 601,26 601,26 601,26 602,26 602,26 602,26 602,26 603,26 603,13 604,25 604,26 604,26 604,26 604,26 605,26 605,26 606,26 606,26 606,26 606,26 606,26 607,26 607,26 608,26 608,26 608,26 608,26 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n<g><title>Collect: </title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'38\'>Collect</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'48\'></text></g>\n<g><title>Stage 1, tasks: 1, finished: 1</title>\n<line x1=\'371\' y1=\'27\' x2=\'371\' y2=\'93\' stroke-width=\'6\' stroke=\'var(--stage-text, #262626)\' stroke-dasharray=\'1,1\' />\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'64\'>1</text>\n</g>\n<g><title>Max MEM 1.00MB</title>\n<polygon points=\'599,58 599,45 608,45 608,58 \' stroke-width=\'1\' stroke=\'var(--mem-medium, #7E4E5B)\' fill=\'var(--mem-medium, #7E4E5B)\' />\n</g>\n<g><title>Max CPU 0.05</title>\n<polygon points=\'599,74 599,74 599,74 599,74 600,74 600,74 600,74 600,74 601,74 601,74 601,74 602,74 602,74 602,74 602,74 603,74 603,74 604,74 604,74 604,74 604,74 604,74 605,74 605,74 606,74 606,74 606,74 606,74 606,74 607,74 607,74 608,74 608,61 608,74 608,74 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n<g><title>Connection: UnionAll</title>\n  <polygon points=\'16,110 0,110 0,96 8,92 16,96\' stroke-width=\'1\' stroke=\'var(--conn-line, #BFBFBF)\' fill=\'var(--conn-fill, #BFBFBF)\'/>\n  <text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'6px\' fill=\'var(--conn-text, #393939)\' x=\'8\' y=\'106\'>U</text>\n</g>\n<g><title>Stage: </title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'107\'>Stage</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'117\'></text></g>\n<line x1=\'18\' y1=\'119\' x2=\'298\' y2=\'119\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n<g><title>Source s3: h4477858012267010435_test_interval_unitmy_binding(Duration, Fruit, Price)</title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'127\'>Source s3</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'137\'>h4477858012267010435_test_interval_unitmy_binding(Duration, Fruit, Price)</text></g>\n<g><title>Stage 0, tasks: 1, finished: 1</title>\n<line x1=\'371\' y1=\'96\' x2=\'371\' y2=\'162\' stroke-width=\'6\' stroke=\'var(--stage-text, #262626)\' stroke-dasharray=\'1,1\' />\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'133\'>1</text>\n</g>\n<g><title>Max MEM 201.00MB</title>\n<polygon points=\'599,127 599,114 604,114 604,127 \' stroke-width=\'1\' stroke=\'var(--mem-medium, #7E4E5B)\' fill=\'var(--mem-medium, #7E4E5B)\' />\n</g>\n<g><title>Max CPU 1.01</title>\n<polygon points=\'599,143 599,143 599,143 599,143 599,143 599,143 600,143 600,143 600,143 600,143 600,143 600,143 600,143 600,143 600,143 601,143 601,143 601,143 601,143 601,143 602,143 602,143 602,143 602,143 602,143 602,143 602,143 602,143 602,143 603,143 603,143 603,143 603,130 604,142 604,143 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n\n</svg>\n"
  }
}

2025-11-14 10:09:34,174 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej778vuhu24bob"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763114971
        nanos: 4944000
      }
      modified_at {
        seconds: 1763114971
        nanos: 4944000
      }
      revision: 1
    }
    started_at {
      seconds: 1763114972
      nanos: 245000000
    }
    finished_at {
      seconds: 1763114973
      nanos: 941000000
    }
    execute_mode: RUN
    status: COMPLETED
    last_job_query_revision: 1
    last_job_id: "ptjudsej778vtoi0gg3t-ptqudsej778vuhu24bob"
    result_expire_at {
      seconds: 1763201372
      nanos: 136151000
    }
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763114971
      nanos: 4944000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM bindings.`h4477858012267010435_test_interval_unitmy_binding`;\n            "
    syntax: YQL_V1
  }
  plan {
    json: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":5,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":1,\"Operators\":[{\"E-Size\":\"32.35294118\",\"Format\":\"csv_with_names\",\"Name\":\"h4477858012267010435_test_interval_unitmy_binding\",\"Inputs\":[],\"Path\":\"fruits.csv \",\"E-Rows\":\"1.011029412\",\"ExternalDataSource\":\"h4477858012267010435_test_interval_unitfruitbucket\",\"ReadColumns\":[\"Duration\",\"Fruit\",\"Price\"],\"SourceType\":\"s3\",\"E-Cost\":\"55\"}],\"Node Type\":\"Source\"}],\"Node Type\":\"Stage\"}],\"Node Type\":\"UnionAll\",\"PlanNodeType\":\"Connection\"}],\"Node Type\":\"Collect\"}],\"Node Type\":\"ResultSet\",\"PlanNodeType\":\"ResultSet\"}],\"Node Type\":\"Query\",\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"}}"
  }
  statistics {
    json: "{\"ResultSet\":{\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1},\"CpuTimeUs\":{\"sum\":\"666us\",\"count\":2,\"avg\":\"333us\",\"max\":\"554us\",\"min\":\"112us\"},\"IngressBytes\":{\"sum\":55,\"count\":1,\"avg\":55,\"max\":55,\"min\":55},\"IngressRows\":{\"sum\":3,\"count\":1,\"avg\":3,\"max\":3,\"min\":3},\"InputBytes\":{\"sum\":38,\"count\":1,\"avg\":38,\"max\":38,\"min\":38},\"InputRows\":{\"sum\":3,\"count\":1,\"avg\":3,\"max\":3,\"min\":3},\"OutputBytes\":{\"sum\":76,\"count\":2,\"avg\":38,\"max\":38,\"min\":38},\"OutputRows\":{\"sum\":6,\"count\":2,\"avg\":3,\"max\":3,\"min\":3},\"ResultBytes\":{\"sum\":38,\"count\":1,\"avg\":38,\"max\":38,\"min\":38},\"ResultRows\":{\"sum\":3,\"count\":1,\"avg\":3,\"max\":3,\"min\":3},\"MaxMemoryUsage\":{\"sum\":211812352,\"count\":2,\"avg\":105906176,\"max\":210763776,\"min\":1048576}}}"
  }
  result_set_meta {
    column {
      name: "Duration"
      type {
        type_id: INTERVAL
      }
    }
    column {
      name: "Fruit"
      type {
        type_id: STRING
      }
    }
    column {
      name: "Price"
      type {
        type_id: INT32
      }
    }
    rows_count: 3
  }
  ast {
    data: "(\n(let $1 (DataSource \'\"s3\" \'\"/local/h4477858012267010435_test_interval_unitfruitbucket\" \'\"/local/h4477858012267010435_test_interval_unitmy_binding\"))\n(let $2 (Bool \'\"true\"))\n(let $3 \'\"cluster:default_/local/h4477858012267010435_test_interval_unitfruitbucket\")\n(let $4 (StructType \'(\'\"Duration\" (DataType \'Interval)) \'(\'\"Fruit\" (DataType \'String)) \'(\'\"Price\" (DataType \'Int32))))\n(let $5 (S3ParseSettings world \'(\'((String \'\"Paths {\\n  Name: \\\"fruits.csv\\\"\\n  Size: 55\\n  Read: true\\n}\\n\") $2 (AsStruct))) (SecureParam $3) \'\"\" \'\"fruits.csv \" \'\"csv_with_names\" $4 (lambda \'($10) $2) \'(\'(\'\"csvdelimiter\" \'\";\") \'(\'\"data.interval.unit\" \'\"SECONDS\"))))\n(let $6 (DqPhyStage \'((DqSource $1 $5)) (lambda \'($11) (block \'(\n  (let $12 (DqSourceWideWrap $11 $1 $4 \'(\'(\'format \'\"csv_with_names\"))))\n  (return (FromFlow (NarrowMap $12 (lambda \'($13 $14 $15) (AsStruct \'(\'\"Duration\" $13) \'(\'\"Fruit\" $14) \'(\'\"Price\" $15))))))\n))) \'(\'(\'\"_logical_id\" \'390) \'(\'\"_id\" \'\"4146c355-d705b0a8-6471ac75-c78f1243\"))))\n(let $7 (DqCnUnionAll (TDqOutput $6 \'\"0\")))\n(let $8 (DqPhyStage \'($7) (lambda \'($16) $16) \'(\'(\'\"_logical_id\" \'448) \'(\'\"_id\" \'\"97d875db-a8d62e96-84c60f51-907b494d\"))))\n(let $9 (DqCnResult (TDqOutput $8 \'\"0\") \'()))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($6 $8) \'($9) \'() \'(\'(\'\"type\" \'\"generic\")))) \'((KqpTxResultBinding (ListType $4) \'\"0\" \'\"0\")) \'(\'(\'\"type\" \'\"script\"))))\n)\n"
  }
  timeline {
    svg: "<svg width=\'1280\' height=\'162\' xmlns=\'http://www.w3.org/2000/svg\'>\n<clipPath id=\'clipTextPath\'><rect x=\'0\' y=\'0\' width=\'298\' height=\'162\'/></clipPath>\n\n<defs>\n\n<g id=\'icon_memory\'>\n  <path fill-rule=\'evenodd\' clip-rule=\'evenodd\' d=\'M5 3C3.89543 3 3 3.89543 3 5H1V7H3V9H1V11H3V13H1V15H3V17H1V19H3C3 20.1046 3.89543 21 5 21H9C10.1046 21 11 20.1046 11 19H13C13 20.1046 13.8954 21 15 21H19C20.1046 21 21 20.1046 21 19H23V17H21V15H23V13H21V11H23V9H21V7H23V5H21C21 3.89543 20.1046 3 19 3H15C13.8954 3 13 3.89543 13 5H11C11 3.89543 10.1046 3 9 3H5ZM11 7V9H13V7H11ZM11 11V13H13V11H11ZM11 15V17H13V15H11ZM5 5H9V19H5V5ZM15 5H19V19H15V5Z\' />\n</g>\n\n<g id=\'icon_cpu\'>\n  <path d=\'M13.9802 7.75H10.0102C8.76023 7.75 7.74023 8.76 7.74023 10.02V13.99C7.74023 15.24 8.75023 16.26 10.0102 16.26H13.9802C15.2302 16.26 16.2502 15.25 16.2502 13.99V10.02C16.2502 8.76 15.2402 7.75 13.9802 7.75ZM13.5002 12.98L12.6102 14.53C12.4802 14.76 12.2402 14.88 12.0002 14.88C11.8802 14.88 11.7502 14.85 11.6502 14.79C11.3102 14.6 11.1902 14.17 11.3902 13.83L12.0302 12.72H11.4702C11.0202 12.72 10.6502 12.52 10.4502 12.18C10.2502 11.84 10.2702 11.42 10.5002 11.03L11.3902 9.48C11.5902 9.14 12.0202 9.03 12.3502 9.22C12.6902 9.41 12.8102 9.84 12.6102 10.18L11.9702 11.29H12.5302C12.9802 11.29 13.3502 11.49 13.5502 11.83C13.7502 12.17 13.7302 12.59 13.5002 12.98Z\' />\n  <path d=\'M21.25 12.75C21.67 12.75 22 12.41 22 12C22 11.58 21.67 11.25 21.25 11.25H20V9.05H21.25C21.67 9.05 22 8.72 22 8.3C22 7.89 21.67 7.55 21.25 7.55H19.77C19.29 5.96 18.04 4.71 16.45 4.23V2.75C16.45 2.34 16.11 2 15.7 2C15.29 2 14.95 2.34 14.95 2.75V4H12.75V2.75C12.75 2.34 12.41 2 12 2C11.59 2 11.25 2.34 11.25 2.75V4H9.06V2.75C9.06 2.34 8.72 2 8.31 2C7.89 2 7.56 2.34 7.56 2.75V4.23C5.96 4.71 4.71 5.96 4.23 7.55H2.75C2.34 7.55 2 7.89 2 8.3C2 8.72 2.34 9.05 2.75 9.05H4V11.25H2.75C2.34 11.25 2 11.58 2 12C2 12.41 2.34 12.75 2.75 12.75H4V14.95H2.75C2.34 14.95 2 15.28 2 15.7C2 16.11 2.34 16.45 2.75 16.45H4.23C4.7 18.04 5.96 19.29 7.56 19.77V21.25C7.56 21.66 7.89 22 8.31 22C8.72 22 9.06 21.66 9.06 21.25V20H11.26V21.25C11.26 21.66 11.59 22 12.01 22C12.42 22 12.76 21.66 12.76 21.25V20H14.95V21.25C14.95 21.66 15.29 22 15.7 22C16.11 22 16.45 21.66 16.45 21.25V19.77C18.04 19.29 19.29 18.04 19.77 16.45H21.25C21.67 16.45 22 16.11 22 15.7C22 15.28 21.67 14.95 21.25 14.95H20V12.75H21.25ZM17.26 14.26C17.26 15.91 15.91 17.26 14.26 17.26H9.74C8.09 17.26 6.74 15.91 6.74 14.26V9.74C6.74 8.09 8.09 6.74 9.74 6.74H14.26C15.91 6.74 17.26 8.09 17.26 9.74V14.26Z\' />\n</g>\n\n<g id=\'icon_input\'>\n  <path d=\'M3 6L6 6L6 12L10 12L10 6L13 6V5L8 0L3 5L3 6Z\' />\n  <path d=\'M2 16L14 16V14L2 14V16Z\' />\n</g>\n\n<g id=\'icon_output\'>\n  <path d=\'M13 9H10V16H6V9L3 9V8L8 3L13 8V9Z\' />\n  <path d=\'M14 2H2V0H14V2Z\' />\n</g>\n\n</defs>\n<rect x=\'0\' y=\'27\' width=\'298\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'301\' y=\'27\' width=\'64\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'395\' y=\'27\' width=\'200\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'368\' y=\'27\' width=\'24\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'598\' y=\'27\' width=\'682\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<circle cx=\'8\' cy=\'60\' r=\'7\' stroke=\'var(--stage-main, #F2F2F2)\' stroke-width=\'1\' fill=\'var(--stage-clone, #D9D9D9)\' />\n<text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'8\' y=\'64\'>1</text>\n<g><title>Output Rows 33%, 3</title>\n  <rect x=\'303\' y=\'33\' width=\'60\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'303,33 333,33 363,33 363,47 303,47\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'303\' y=\'36\' width=\'5\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'303\' y=\'44\'>3</text>\n</g>\n<g><title>Output 50%, 38B, Rows 50%, 3</title>\n<use href=\'#icon_output\' transform=\'translate(395 29) scale(0.9 0.9)\' fill=\'var(--output-medium, #5781B9)\'/>\n  <rect x=\'413\' y=\'29\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'413,29 503,29 593,29 593,43 413,43\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'413\' y=\'32\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'40\'>38B</text>\n</g>\n<g><title>Output, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'604\' y=\'29\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-medium, #5781B9)\'/>\n</g>\n<g><title>Memory 0%, 1.00MB</title>\n<use href=\'#icon_memory\' transform=\'translate(395 45) scale(0.6 0.6)\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <rect x=\'413\' y=\'45\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--mem-light, #AA7785)\'/>  <polygon points=\'413,45 413,45 413,45 413,59 413,59\' stroke=\'none\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n<rect x=\'413\' y=\'48\' width=\'33\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'56\'>1.00MB</text>\n</g>\n<g><title>CPU Usage 16%, 112us</title>\n<use href=\'#icon_cpu\' transform=\'translate(395 61) scale(0.6 0.6)\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <rect x=\'413\' y=\'61\' width=\'36\' height=\'14\' stroke-width=\'0\' fill=\'var(--cpu-light, #B78C98)\'/>  <polygon points=\'413,61 431,61 449,61 449,75 413,75\' stroke=\'none\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n<rect x=\'413\' y=\'64\' width=\'28\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'72\'>112us</text>\n</g>\n<g><title>Input 100%, 38B, Rows 100%, 3, Width 12B</title>\n<use href=\'#icon_input\' transform=\'translate(395 77) scale(0.9 0.9)\' fill=\'var(--input-medium, #5A8183)\'/>\n  <rect x=\'413\' y=\'77\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--input-light, #7CA3A5)\'/>  <polygon points=\'413,77 503,77 593,77 593,91 413,91\' stroke=\'none\' fill=\'var(--input-medium, #5A8183)\'/>\n<rect x=\'413\' y=\'80\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'88\'>38B</text>\n</g>\n<g><title>Input, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'604\' y=\'77\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--input-medium, #5A8183)\'/>\n</g>\n<rect x=\'0\' y=\'96\' width=\'298\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'301\' y=\'96\' width=\'64\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'395\' y=\'96\' width=\'200\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'368\' y=\'96\' width=\'24\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'598\' y=\'96\' width=\'682\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<circle cx=\'8\' cy=\'129\' r=\'7\' stroke=\'var(--stage-main, #F2F2F2)\' stroke-width=\'1\' fill=\'var(--stage-clone, #D9D9D9)\' />\n<text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'8\' y=\'133\'>0</text>\n<g><title>Output Rows 33%, 3</title>\n  <rect x=\'303\' y=\'102\' width=\'60\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'303,102 333,102 363,102 363,116 303,116\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'303\' y=\'105\' width=\'5\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'303\' y=\'113\'>3</text>\n</g>\n<g><title>Output Rows 33%, 3, Est: 55 Rows: 1 Size: 32B</title>\n  <rect x=\'303\' y=\'122\' width=\'60\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'303,122 333,122 363,122 363,136 303,136\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'303\' y=\'125\' width=\'5\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'303\' y=\'133\'>3</text>\n</g>\n<g><title>Output 50%, 38B, Rows 50%, 3</title>\n<use href=\'#icon_output\' transform=\'translate(395 98) scale(0.9 0.9)\' fill=\'var(--output-medium, #5781B9)\'/>\n  <rect x=\'413\' y=\'98\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'413,98 503,98 593,98 593,112 413,112\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'413\' y=\'101\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'109\'>38B</text>\n</g>\n<g><title>Output, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'604\' y=\'98\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-medium, #5781B9)\'/>\n</g>\n<g><title>Memory 99%, 201.00MB</title>\n<use href=\'#icon_memory\' transform=\'translate(395 114) scale(0.6 0.6)\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <rect x=\'413\' y=\'114\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--mem-light, #AA7785)\'/>  <polygon points=\'413,114 503,114 593,114 593,128 413,128\' stroke=\'none\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n<rect x=\'413\' y=\'117\' width=\'44\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'125\'>201.00MB</text>\n</g>\n<g><title>CPU Usage 83%, 554us</title>\n<use href=\'#icon_cpu\' transform=\'translate(395 130) scale(0.6 0.6)\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <rect x=\'413\' y=\'130\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--cpu-light, #B78C98)\'/>  <polygon points=\'413,130 503,130 593,130 593,144 413,144\' stroke=\'none\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n<rect x=\'413\' y=\'133\' width=\'28\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'141\'>554us</text>\n</g>\n<g><title>Ingress 100%, 55B, Rows 100%, 3, Width 18B</title>\n<use href=\'#icon_input\' transform=\'translate(395 146) scale(0.9 0.9)\' fill=\'var(--ingress-medium, #466364)\'/>\n  <rect x=\'413\' y=\'146\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--ingress-light, #5A8183)\'/>  <polygon points=\'413,146 503,146 593,146 593,160 413,160\' stroke=\'none\' fill=\'var(--ingress-medium, #466364)\'/>\n<rect x=\'413\' y=\'149\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'157\'>55B</text>\n</g>\n<g><title>Ingress, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'604\' y=\'146\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--ingress-medium, #466364)\'/>\n</g>\n\n<line x1=\'600\' y1=\'0\' x2=\'600\' y2=\'162\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'602\' y=\'11\'>0:00</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'0\' y=\'11\'>ResultSet</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'303\' y=\'11\'>Operators</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'397\' y=\'11\'>Stages</text>\n<text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'11\'>Tasks</text>\n<text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'22\'>2</text>\n<g><title>Ingress 55B, Rows 3, Width 18B, Avg 55B/s</title>\n  <rect x=\'395\' y=\'14\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--ingress-medium, #466364)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'397\' y=\'22\'>55B</text>\n</g>\n<g><title>CPU Usage 666us, Avg 0.00 CPU/s</title>\n  <rect x=\'462\' y=\'14\' width=\'66\' height=\'10\' stroke-width=\'0\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'464\' y=\'22\'>666us</text>\n</g>\n<g><title>Memory 202.00MB</title>\n  <rect x=\'530\' y=\'14\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'532\' y=\'22\'>202.00MB</text>\n</g>\n<g><title>Duration: 1.00, Total 1.00</title>\n  <rect x=\'1215\' y=\'3\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--stage-grid, #B2B2B2)\'/>\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-inv, #FFFFFF)\' x=\'1278\' y=\'11\'>1.00</text>\n</g>\n<g><title>Max CPU 0.50</title>\n<polygon points=\'599,26 599,26 599,26 599,26 600,26 600,26 600,26 600,26 601,26 601,26 601,26 602,26 602,26 602,26 602,26 603,26 603,13 604,25 604,26 604,26 604,26 604,26 605,26 605,26 606,26 606,26 606,26 606,26 606,26 607,26 607,26 608,26 608,26 608,26 608,26 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n<g><title>Collect: </title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'38\'>Collect</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'48\'></text></g>\n<g><title>Stage 1, tasks: 1, finished: 1</title>\n<line x1=\'371\' y1=\'27\' x2=\'371\' y2=\'93\' stroke-width=\'6\' stroke=\'var(--stage-text, #262626)\' stroke-dasharray=\'1,1\' />\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'64\'>1</text>\n</g>\n<g><title>Max MEM 1.00MB</title>\n<polygon points=\'599,58 599,45 608,45 608,58 \' stroke-width=\'1\' stroke=\'var(--mem-medium, #7E4E5B)\' fill=\'var(--mem-medium, #7E4E5B)\' />\n</g>\n<g><title>Max CPU 0.05</title>\n<polygon points=\'599,74 599,74 599,74 599,74 600,74 600,74 600,74 600,74 601,74 601,74 601,74 602,74 602,74 602,74 602,74 603,74 603,74 604,74 604,74 604,74 604,74 604,74 605,74 605,74 606,74 606,74 606,74 606,74 606,74 607,74 607,74 608,74 608,61 608,74 608,74 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n<g><title>Connection: UnionAll</title>\n  <polygon points=\'16,110 0,110 0,96 8,92 16,96\' stroke-width=\'1\' stroke=\'var(--conn-line, #BFBFBF)\' fill=\'var(--conn-fill, #BFBFBF)\'/>\n  <text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'6px\' fill=\'var(--conn-text, #393939)\' x=\'8\' y=\'106\'>U</text>\n</g>\n<g><title>Stage: </title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'107\'>Stage</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'117\'></text></g>\n<line x1=\'18\' y1=\'119\' x2=\'298\' y2=\'119\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n<g><title>Source s3: h4477858012267010435_test_interval_unitmy_binding(Duration, Fruit, Price)</title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'127\'>Source s3</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'137\'>h4477858012267010435_test_interval_unitmy_binding(Duration, Fruit, Price)</text></g>\n<g><title>Stage 0, tasks: 1, finished: 1</title>\n<line x1=\'371\' y1=\'96\' x2=\'371\' y2=\'162\' stroke-width=\'6\' stroke=\'var(--stage-text, #262626)\' stroke-dasharray=\'1,1\' />\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'133\'>1</text>\n</g>\n<g><title>Max MEM 201.00MB</title>\n<polygon points=\'599,127 599,114 604,114 604,127 \' stroke-width=\'1\' stroke=\'var(--mem-medium, #7E4E5B)\' fill=\'var(--mem-medium, #7E4E5B)\' />\n</g>\n<g><title>Max CPU 1.01</title>\n<polygon points=\'599,143 599,143 599,143 599,143 599,143 599,143 600,143 600,143 600,143 600,143 600,143 600,143 600,143 600,143 600,143 601,143 601,143 601,143 601,143 601,143 602,143 602,143 602,143 602,143 602,143 602,143 602,143 602,143 602,143 603,143 603,143 603,143 603,130 604,142 604,143 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n\n</svg>\n"
  }
}

2025-11-14 10:09:34,388 - DEBUG - root - test_interval_unit: columns {
  name: "Duration"
  type {
    type_id: INTERVAL
  }
}
columns {
  name: "Fruit"
  type {
    type_id: STRING
  }
}
columns {
  name: "Price"
  type {
    type_id: INT32
  }
}
rows {
  items {
    int64_value: 100000000
  }
  items {
    bytes_value: "Banana"
  }
  items {
    int32_value: 3
  }
}
rows {
  items {
    int64_value: 22000000
  }
  items {
    bytes_value: "Apple"
  }
  items {
    int32_value: 2
  }
}
rows {
  items {
    int64_value: 33000000
  }
  items {
    bytes_value: "Pear"
  }
  items {
    int32_value: 15
  }
}

2025-11-14 10:09:34,391 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-14 10:17:41,564 - DEBUG - ya.test - _verify_test: Verifying [test_format_setting.TestS3.test_interval_unit[v2-client0] in ydb/tests/fq/s3] canonical results
2025-11-14 10:17:41,567 - DEBUG - ya.test - verify: Test test_format_setting.TestS3.test_interval_unit[v2-client0] status: good
