2025-11-14 10:12:17,322 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 10:12:17,322 - INFO - ya.test - pytest_runtest_setup: test_double_optional_types_validation[v2-client0]
2025-11-14 10:12:17,323 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 10:12:17,323 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 10:12:18,324 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10924/1
2025-11-14 10:12:18,326 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15999
2025-11-14 10:12:18,326 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17286
2025-11-14 10:12:18,326 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10924
2025-11-14 10:12:18,326 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31176
2025-11-14 10:12:18,326 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26059
2025-11-14 10:12:18,326 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5817
2025-11-14 10:12:19,094 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 10:12:19,095 - 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:12:19,099 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-14 10:12:19,099 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 457c8eb0-643b-49f1-9eae-e5108d879c38, localhost:4203): creating call state
2025-11-14 10:12:19,100 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 457c8eb0-643b-49f1-9eae-e5108d879c38, localhost:4203): request = { database: "local" }
2025-11-14 10:12:19,103 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 457c8eb0-643b-49f1-9eae-e5108d879c38, localhost:4203): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-adxazuzm5e.auto.internal" port: 4203 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:12:19,104 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-adxazuzm5e.auto.internal:4203, location 1, ssl: False>]>
2025-11-14 10:12:19,104 - INFO - ydb.connection - close: Closing channel for endpoint localhost:4203
2025-11-14 10:12:21,327 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:3385/1
2025-11-14 10:12:21,328 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16390
2025-11-14 10:12:21,329 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16760
2025-11-14 10:12:21,329 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3385
2025-11-14 10:12:21,329 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/8855
2025-11-14 10:12:21,329 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4994
2025-11-14 10:12:28,331 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11542/1
2025-11-14 10:12:28,332 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6143
2025-11-14 10:12:28,332 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10151
2025-11-14 10:12:28,332 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11542
2025-11-14 10:12:28,333 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27214
2025-11-14 10:12:28,333 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1194
2025-11-14 10:12:28,333 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5888
2025-11-14 10:12:28,335 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10962
2025-11-14 10:12:28,336 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8093
2025-11-14 10:12:28,416 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:28,417 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6864
2025-11-14 10:12:28,417 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5183
2025-11-14 10:12:28,418 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12262
2025-11-14 10:12:28,419 - 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:12:28,420 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 10:12:28,421 - 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:12:28,424 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-14 10:12:28,424 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 2a72a28b-6699-46bc-8dcb-0cbda934f6a2, localhost:4203): creating call state
2025-11-14 10:12:28,425 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 2a72a28b-6699-46bc-8dcb-0cbda934f6a2, localhost:4203): request = { database: "local" }
2025-11-14 10:12:28,427 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 2a72a28b-6699-46bc-8dcb-0cbda934f6a2, localhost:4203): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-adxazuzm5e.auto.internal" port: 4203 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:12:28,428 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-adxazuzm5e.auto.internal:4203, location 1, ssl: False>]>
2025-11-14 10:12:28,428 - 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:12:28,431 - INFO - ydb.connection - close: Closing channel for endpoint localhost:4203
2025-11-14 10:12:28,432 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-14 10:12:28,433 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f2efec481f0 state=pending>
2025-11-14 10:12:28,433 - 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:12:28,433 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f2efec4b120>
2025-11-14 10:12:28,433 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f2efec4b120>
2025-11-14 10:12:28,434 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2ac5bf68-87ea-4522-b865-234bf58aac39, ghrun-adxazuzm5e.auto.internal:4203): creating call state
2025-11-14 10:12:28,434 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2ac5bf68-87ea-4522-b865-234bf58aac39, ghrun-adxazuzm5e.auto.internal:4203): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-14 10:12:28,436 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 2ac5bf68-87ea-4522-b865-234bf58aac39, ghrun-adxazuzm5e.auto.internal:4203): on response callback started
2025-11-14 10:12:28,437 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2ac5bf68-87ea-4522-b865-234bf58aac39, ghrun-adxazuzm5e.auto.internal:4203): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YzY3NDVhZDUtZDQwMTE3ZjktYWM1ZGViYjItN2NlMzllNjE=" } } } }
2025-11-14 10:12:28,438 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2efec4b120>
2025-11-14 10:12:28,438 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2efec4b120>
2025-11-14 10:12:28,438 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, b30e7c87-0fba-4628-897b-838873a776ed, ghrun-adxazuzm5e.auto.internal:4203): creating call state
2025-11-14 10:12:28,439 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 2ac5bf68-87ea-4522-b865-234bf58aac39, ghrun-adxazuzm5e.auto.internal:4203): on response callback success
2025-11-14 10:12:28,440 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, b30e7c87-0fba-4628-897b-838873a776ed, ghrun-adxazuzm5e.auto.internal:4203): request = { session_id: "ydb://session/3?node_id=1&id=YzY3NDVhZDUtZDQwMTE3ZjktYWM1ZGViYjItN2NlMzllNjE=" path: "local/DbPoolStorage_42695727-34fe-4a64-acbd-d2b483bfabc8/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:12:28,456 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, b30e7c87-0fba-4628-897b-838873a776ed, ghrun-adxazuzm5e.auto.internal:4203): response = { operation { ready: true status: SUCCESS } }
2025-11-14 10:12:28,457 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2efec4b120>
2025-11-14 10:12:28,457 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, ca2d261f-2f88-48b1-a5ee-5a50a1c8ffcf, ghrun-adxazuzm5e.auto.internal:4203): creating call state
2025-11-14 10:12:28,458 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, ca2d261f-2f88-48b1-a5ee-5a50a1c8ffcf, ghrun-adxazuzm5e.auto.internal:4203): request = { session_id: "ydb://session/3?node_id=1&id=YzY3NDVhZDUtZDQwMTE3ZjktYWM1ZGViYjItN2NlMzllNjE=" path: "local/DbPoolStorage_42695727-34fe-4a64-acbd-d2b483bfabc8/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:12:28,474 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, ca2d261f-2f88-48b1-a5ee-5a50a1c8ffcf, ghrun-adxazuzm5e.auto.internal:4203): response = { operation { ready: true status: SUCCESS } }
2025-11-14 10:12:28,474 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2efec4b120>
2025-11-14 10:12:28,475 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 63b1b8bf-3364-4bce-888b-8eff14f20c3a, ghrun-adxazuzm5e.auto.internal:4203): creating call state
2025-11-14 10:12:28,476 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 63b1b8bf-3364-4bce-888b-8eff14f20c3a, ghrun-adxazuzm5e.auto.internal:4203): request = { session_id: "ydb://session/3?node_id=1&id=YzY3NDVhZDUtZDQwMTE3ZjktYWM1ZGViYjItN2NlMzllNjE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "--!syntax_v1\n            PRAGMA TablePathPrefix(\"local/DbPoolStorage_42695727-34fe-4a64-acbd-d2b483bfabc8\");\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:12:28,494 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 63b1b8bf-3364-4bce-888b-8eff14f20c3a, ghrun-adxazuzm5e.auto.internal:4203): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-14 10:12:28,495 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2efec4b120>
2025-11-14 10:12:28,559 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:28,560 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8596
2025-11-14 10:12:28,560 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21663
2025-11-14 10:12:28,561 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/default/kikimr_configs"
2025-11-14 10:12:28,561 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/wp2g/00310a/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/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/default/node_1/logfile_2b6lad8w.log --grpc-port=12262 --mon-port=8596 --ic-port=6864
2025-11-14 10:12:28,563 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/default/kikimr_configs/config.yaml', '--node=1', '--tenant=/default', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/default/node_1/logfile_2b6lad8w.log', '--grpc-port=12262', '--mon-port=8596', '--ic-port=6864')' in '/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/default/node_1' (no wait)
2025-11-14 10:12:28,564 - DEBUG - ya.test - execute: Command pid: 618973
2025-11-14 10:12:28,565 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:12262/1
2025-11-14 10:12:28,565 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 10:12:28,568 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10975
2025-11-14 10:12:28,568 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11399
2025-11-14 10:12:28,613 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:28,613 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/65139
2025-11-14 10:12:28,614 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25702
2025-11-14 10:12:28,682 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:28,683 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13954
2025-11-14 10:12:28,683 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18222
2025-11-14 10:12:28,684 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61647
2025-11-14 10:12:28,685 - 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:12:28,687 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 10:12:28,688 - 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:12:28,692 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-14 10:12:28,693 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 2770d3eb-86a9-46c6-8ff3-8977d34cd4b4, localhost:4203): creating call state
2025-11-14 10:12:28,693 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 2770d3eb-86a9-46c6-8ff3-8977d34cd4b4, localhost:4203): request = { database: "local" }
2025-11-14 10:12:28,696 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 2770d3eb-86a9-46c6-8ff3-8977d34cd4b4, localhost:4203): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-adxazuzm5e.auto.internal" port: 4203 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:12:28,697 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-adxazuzm5e.auto.internal:4203, location 1, ssl: False>]>
2025-11-14 10:12:28,697 - 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:12:28,700 - INFO - ydb.connection - close: Closing channel for endpoint localhost:4203
2025-11-14 10:12:28,701 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-14 10:12:28,701 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f2f1ac2cdd0 state=pending>
2025-11-14 10:12:28,702 - 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:12:28,702 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f2f1ac2d5f0>
2025-11-14 10:12:28,702 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f2f1ac2d5f0>
2025-11-14 10:12:28,702 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 568f08e8-fa6f-4abd-80bf-cb4d886027fc, ghrun-adxazuzm5e.auto.internal:4203): creating call state
2025-11-14 10:12:28,703 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 568f08e8-fa6f-4abd-80bf-cb4d886027fc, ghrun-adxazuzm5e.auto.internal:4203): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-14 10:12:28,705 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 568f08e8-fa6f-4abd-80bf-cb4d886027fc, ghrun-adxazuzm5e.auto.internal:4203): on response callback started
2025-11-14 10:12:28,706 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 568f08e8-fa6f-4abd-80bf-cb4d886027fc, ghrun-adxazuzm5e.auto.internal:4203): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MjEwNWYxNzEtYTEwN2ZkYTUtZTVhNjM4YzEtYWY3ZDliNGY=" } } } }
2025-11-14 10:12:28,706 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2f1ac2d5f0>
2025-11-14 10:12:28,706 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f2f1ac2d5f0>
2025-11-14 10:12:28,707 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 33f7acba-cd49-48a8-89bf-7530fcd749f1, ghrun-adxazuzm5e.auto.internal:4203): creating call state
2025-11-14 10:12:28,708 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 568f08e8-fa6f-4abd-80bf-cb4d886027fc, ghrun-adxazuzm5e.auto.internal:4203): on response callback success
2025-11-14 10:12:28,709 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 33f7acba-cd49-48a8-89bf-7530fcd749f1, ghrun-adxazuzm5e.auto.internal:4203): request = { session_id: "ydb://session/3?node_id=1&id=MjEwNWYxNzEtYTEwN2ZkYTUtZTVhNjM4YzEtYWY3ZDliNGY=" path: "local/DbPoolStorage_16c652c6-3fd7-46fe-90cf-2e2af3cc3e90/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:12:28,727 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 33f7acba-cd49-48a8-89bf-7530fcd749f1, ghrun-adxazuzm5e.auto.internal:4203): response = { operation { ready: true status: SUCCESS } }
2025-11-14 10:12:28,727 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2f1ac2d5f0>
2025-11-14 10:12:28,728 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 75070571-99c0-4130-bc70-21e8325d27b3, ghrun-adxazuzm5e.auto.internal:4203): creating call state
2025-11-14 10:12:28,729 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 75070571-99c0-4130-bc70-21e8325d27b3, ghrun-adxazuzm5e.auto.internal:4203): request = { session_id: "ydb://session/3?node_id=1&id=MjEwNWYxNzEtYTEwN2ZkYTUtZTVhNjM4YzEtYWY3ZDliNGY=" path: "local/DbPoolStorage_16c652c6-3fd7-46fe-90cf-2e2af3cc3e90/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:12:28,744 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 75070571-99c0-4130-bc70-21e8325d27b3, ghrun-adxazuzm5e.auto.internal:4203): response = { operation { ready: true status: SUCCESS } }
2025-11-14 10:12:28,744 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2f1ac2d5f0>
2025-11-14 10:12:28,745 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 36c95d02-4d71-4edc-8a3f-3ac578b49e2f, ghrun-adxazuzm5e.auto.internal:4203): creating call state
2025-11-14 10:12:28,746 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 36c95d02-4d71-4edc-8a3f-3ac578b49e2f, ghrun-adxazuzm5e.auto.internal:4203): request = { session_id: "ydb://session/3?node_id=1&id=MjEwNWYxNzEtYTEwN2ZkYTUtZTVhNjM4YzEtYWY3ZDliNGY=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "--!syntax_v1\n            PRAGMA TablePathPrefix(\"local/DbPoolStorage_16c652c6-3fd7-46fe-90cf-2e2af3cc3e90\");\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:12:28,766 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 36c95d02-4d71-4edc-8a3f-3ac578b49e2f, ghrun-adxazuzm5e.auto.internal:4203): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-14 10:12:28,766 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f2f1ac2d5f0>
2025-11-14 10:12:28,767 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17028
2025-11-14 10:12:28,825 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:28,826 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20549
2025-11-14 10:12:28,826 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7265
2025-11-14 10:12:28,827 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/cp/kikimr_configs"
2025-11-14 10:12:28,827 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/wp2g/00310a/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/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/cp/node_1/logfile_3atszjcx.log --grpc-port=61647 --mon-port=20549 --ic-port=65139
2025-11-14 10:12:28,828 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/cp/kikimr_configs/config.yaml', '--node=1', '--tenant=/cp', '--node-kind=yq', '--log-file-name=/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/cp/node_1/logfile_3atszjcx.log', '--grpc-port=61647', '--mon-port=20549', '--ic-port=65139')' in '/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/cp/node_1' (no wait)
2025-11-14 10:12:28,830 - DEBUG - ya.test - execute: Command pid: 619374
2025-11-14 10:12:28,831 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:61647/1
2025-11-14 10:12:28,831 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 10:12:28,866 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:28,867 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13804
2025-11-14 10:12:28,867 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13154
2025-11-14 10:12:28,868 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/compute/kikimr_configs"
2025-11-14 10:12:28,868 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/wp2g/00310a/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/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/compute/node_1/logfile_vu84je5n.log --grpc-port=17028 --mon-port=13804 --ic-port=13954
2025-11-14 10:12:28,869 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/compute/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/compute/node_1/logfile_vu84je5n.log', '--grpc-port=17028', '--mon-port=13804', '--ic-port=13954')' in '/home/runner/.ya/build/build_root/wp2g/00310a/ydb/tests/fq/s3/test-results/py3test/testing_out_stuff/test_s3_0/testing_out_stuff/test_s3_0.py.TestS3.test_double_optional_types_validation.v2-client0/compute/node_1' (no wait)
2025-11-14 10:12:28,870 - DEBUG - ya.test - execute: Command pid: 619447
2025-11-14 10:12:28,871 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:17028/1
2025-11-14 10:12:30,701 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:30,702 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.11
2025-11-14 10:12:30,703 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.11
2025-11-14 10:12:30,703 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17028', '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: 13954\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 10:12:30,704 - DEBUG - ya.test - execute: Command pid: 621394
2025-11-14 10:12:31,131 - DEBUG - ya.test - _save_outputs: Command (pid 621394) rc: 0
2025-11-14 10:12:31,131 - DEBUG - ya.test - _save_outputs: Command (pid 621394) elapsed time (sec): 0.42589306831359863
2025-11-14 10:12:31,131 - DEBUG - ya.test - _save_outputs: Command (pid 621394) idrss: 0
2025-11-14 10:12:31,132 - DEBUG - ya.test - _save_outputs: Command (pid 621394) inblock: 0
2025-11-14 10:12:31,132 - DEBUG - ya.test - _save_outputs: Command (pid 621394) isrss: 0
2025-11-14 10:12:31,132 - DEBUG - ya.test - _save_outputs: Command (pid 621394) ixrss: 0
2025-11-14 10:12:31,132 - DEBUG - ya.test - _save_outputs: Command (pid 621394) majflt: 0
2025-11-14 10:12:31,132 - DEBUG - ya.test - _save_outputs: Command (pid 621394) maxrss: 525760
2025-11-14 10:12:31,132 - DEBUG - ya.test - _save_outputs: Command (pid 621394) minflt: 19682
2025-11-14 10:12:31,132 - DEBUG - ya.test - _save_outputs: Command (pid 621394) msgrcv: 0
2025-11-14 10:12:31,132 - DEBUG - ya.test - _save_outputs: Command (pid 621394) msgsnd: 0
2025-11-14 10:12:31,133 - DEBUG - ya.test - _save_outputs: Command (pid 621394) nivcsw: 5
2025-11-14 10:12:31,133 - DEBUG - ya.test - _save_outputs: Command (pid 621394) nsignals: 0
2025-11-14 10:12:31,133 - DEBUG - ya.test - _save_outputs: Command (pid 621394) nswap: 0
2025-11-14 10:12:31,133 - DEBUG - ya.test - _save_outputs: Command (pid 621394) nvcsw: 125
2025-11-14 10:12:31,133 - DEBUG - ya.test - _save_outputs: Command (pid 621394) oublock: 8
2025-11-14 10:12:31,133 - DEBUG - ya.test - _save_outputs: Command (pid 621394) stime: 0.307492
2025-11-14 10:12:31,133 - DEBUG - ya.test - _save_outputs: Command (pid 621394) utime: 0.074901
2025-11-14 10:12:31,134 - DEBUG - ya.test - _save_outputs: Command (pid 621394) wtime: 0.427
2025-11-14 10:12:31,134 - DEBUG - ya.test - _save_outputs: Command (pid 621394) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-14 10:12:31,134 - DEBUG - ya.test - _save_outputs: Command (pid 621394) errors:

2025-11-14 10:12:31,135 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:31,136 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.12
2025-11-14 10:12:31,137 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.12
2025-11-14 10:12:31,137 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17028', '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:12:31,138 - DEBUG - ya.test - execute: Command pid: 621661
2025-11-14 10:12:31,467 - DEBUG - ya.test - _save_outputs: Command (pid 621661) rc: 0
2025-11-14 10:12:31,467 - DEBUG - ya.test - _save_outputs: Command (pid 621661) elapsed time (sec): 0.3278660774230957
2025-11-14 10:12:31,467 - DEBUG - ya.test - _save_outputs: Command (pid 621661) idrss: 0
2025-11-14 10:12:31,467 - DEBUG - ya.test - _save_outputs: Command (pid 621661) inblock: 0
2025-11-14 10:12:31,468 - DEBUG - ya.test - _save_outputs: Command (pid 621661) isrss: 0
2025-11-14 10:12:31,468 - DEBUG - ya.test - _save_outputs: Command (pid 621661) ixrss: 0
2025-11-14 10:12:31,468 - DEBUG - ya.test - _save_outputs: Command (pid 621661) majflt: 0
2025-11-14 10:12:31,468 - DEBUG - ya.test - _save_outputs: Command (pid 621661) maxrss: 525792
2025-11-14 10:12:31,468 - DEBUG - ya.test - _save_outputs: Command (pid 621661) minflt: 19682
2025-11-14 10:12:31,468 - DEBUG - ya.test - _save_outputs: Command (pid 621661) msgrcv: 0
2025-11-14 10:12:31,468 - DEBUG - ya.test - _save_outputs: Command (pid 621661) msgsnd: 0
2025-11-14 10:12:31,468 - DEBUG - ya.test - _save_outputs: Command (pid 621661) nivcsw: 1
2025-11-14 10:12:31,469 - DEBUG - ya.test - _save_outputs: Command (pid 621661) nsignals: 0
2025-11-14 10:12:31,469 - DEBUG - ya.test - _save_outputs: Command (pid 621661) nswap: 0
2025-11-14 10:12:31,469 - DEBUG - ya.test - _save_outputs: Command (pid 621661) nvcsw: 131
2025-11-14 10:12:31,469 - DEBUG - ya.test - _save_outputs: Command (pid 621661) oublock: 8
2025-11-14 10:12:31,469 - DEBUG - ya.test - _save_outputs: Command (pid 621661) stime: 0.20404599999999998
2025-11-14 10:12:31,469 - DEBUG - ya.test - _save_outputs: Command (pid 621661) utime: 0.080848
2025-11-14 10:12:31,469 - DEBUG - ya.test - _save_outputs: Command (pid 621661) wtime: 0.329
2025-11-14 10:12:31,469 - DEBUG - ya.test - _save_outputs: Command (pid 621661) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-14 10:12:31,470 - DEBUG - ya.test - _save_outputs: Command (pid 621661) errors:

2025-11-14 10:12:31,470 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd
2025-11-14 10:12:31,471 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.13
2025-11-14 10:12:31,472 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.13
2025-11-14 10:12:31,472 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/wp2g/00310a/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:17028', '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:12:31,472 - DEBUG - ya.test - execute: Command pid: 622032
2025-11-14 10:12:31,871 - DEBUG - ya.test - _save_outputs: Command (pid 622032) rc: 0
2025-11-14 10:12:31,871 - DEBUG - ya.test - _save_outputs: Command (pid 622032) elapsed time (sec): 0.3981630802154541
2025-11-14 10:12:31,872 - DEBUG - ya.test - _save_outputs: Command (pid 622032) idrss: 0
2025-11-14 10:12:31,872 - DEBUG - ya.test - _save_outputs: Command (pid 622032) inblock: 0
2025-11-14 10:12:31,872 - DEBUG - ya.test - _save_outputs: Command (pid 622032) isrss: 0
2025-11-14 10:12:31,872 - DEBUG - ya.test - _save_outputs: Command (pid 622032) ixrss: 0
2025-11-14 10:12:31,872 - DEBUG - ya.test - _save_outputs: Command (pid 622032) majflt: 0
2025-11-14 10:12:31,872 - DEBUG - ya.test - _save_outputs: Command (pid 622032) maxrss: 525776
2025-11-14 10:12:31,872 - DEBUG - ya.test - _save_outputs: Command (pid 622032) minflt: 19681
2025-11-14 10:12:31,873 - DEBUG - ya.test - _save_outputs: Command (pid 622032) msgrcv: 0
2025-11-14 10:12:31,873 - DEBUG - ya.test - _save_outputs: Command (pid 622032) msgsnd: 0
2025-11-14 10:12:31,873 - DEBUG - ya.test - _save_outputs: Command (pid 622032) nivcsw: 1
2025-11-14 10:12:31,873 - DEBUG - ya.test - _save_outputs: Command (pid 622032) nsignals: 0
2025-11-14 10:12:31,873 - DEBUG - ya.test - _save_outputs: Command (pid 622032) nswap: 0
2025-11-14 10:12:31,873 - DEBUG - ya.test - _save_outputs: Command (pid 622032) nvcsw: 122
2025-11-14 10:12:31,873 - DEBUG - ya.test - _save_outputs: Command (pid 622032) oublock: 8
2025-11-14 10:12:31,873 - DEBUG - ya.test - _save_outputs: Command (pid 622032) stime: 0.27408499999999997
2025-11-14 10:12:31,874 - DEBUG - ya.test - _save_outputs: Command (pid 622032) utime: 0.08061299999999999
2025-11-14 10:12:31,874 - DEBUG - ya.test - _save_outputs: Command (pid 622032) wtime: 0.399
2025-11-14 10:12:31,874 - DEBUG - ya.test - _save_outputs: Command (pid 622032) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 7

2025-11-14 10:12:31,874 - DEBUG - ya.test - _save_outputs: Command (pid 622032) errors:

2025-11-14 10:12:31,874 - 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:12:31,884 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 10:12:31,892 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-14 10:12:31,930 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-14 10:12:31,967 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-14 10:12:32,048 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-14 10:12:32,130 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_s3_0.py::TestS3, test_name: test_double_optional_types_validation[v2-client0])
2025-11-14 10:12:32,132 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7f2f1bb28890>
2025-11-14 10:12:32,133 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7f2f1bc34590>
2025-11-14 10:12:32,134 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7f2f1adea810>
2025-11-14 10:12:32,134 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7f2f1bc34290>
2025-11-14 10:12:32,137 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-14 10:12:32,141 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-14 10:12:32,142 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-14 10:12:32,143 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:s3
2025-11-14 10:12:32,145 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:Bucket
2025-11-14 10:12:32,145 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming Bucket attribute name
2025-11-14 10:12:32,147 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.Bucket: calling handler <function lazy_call.<locals>._handler at 0x7f2f1adeb290>
2025-11-14 10:12:32,147 - DEBUG - boto3.resources.action - __call__: Calling s3:create_bucket with {'Bucket': 'fbucket', 'ACL': 'public-read'}
2025-11-14 10:12:32,148 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function validate_bucket_name at 0x7f2f1bb2a750>
2025-11-14 10:12:32,148 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function remove_bucket_from_url_paths_from_model at 0x7f2f1bb4d010>
2025-11-14 10:12:32,148 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,148 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function generate_idempotent_uuid at 0x7f2f1bb2a510>
2025-11-14 10:12:32,149 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7f2f1bb4d250>
2025-11-14 10:12:32,149 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,149 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:12406', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableAccessPoints': True, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:12:32,151 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:12406/fbucket
2025-11-14 10:12:32,151 - 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:12:32,152 - 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:12:32,152 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function add_expect_header at 0x7f2f1bb2abd0>
2025-11-14 10:12:32,153 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function add_recursion_detection_header at 0x7f2f1bb28ad0>
2025-11-14 10:12:32,153 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function inject_api_version_header_if_needed at 0x7f2f1bb4c650>
2025-11-14 10:12:32,153 - 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:12406/fbucket', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7f2ef2d1e860>, '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:12:32,154 - DEBUG - botocore.hooks - _emit: Event request-created.s3.CreateBucket: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7f2ef2d1e5e0>>
2025-11-14 10:12:32,155 - 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 0x7f2ef2d4e680>>
2025-11-14 10:12:32,155 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.CreateBucket: calling handler <function set_operation_specific_signer at 0x7f2f1bb2a390>
2025-11-14 10:12:32,156 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.CreateBucket: calling handler <function remove_arn_from_signing_path at 0x7f2f1bb4d190>
2025-11-14 10:12:32,156 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:12:32,157 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
PUT
/fbucket

host:localhost:12406
x-amz-acl:public-read
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251114T101232Z

host;x-amz-acl;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-14 10:12:32,157 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101232Z
20251114/yandex/s3/aws4_request
10759ebd2c850f4878d05e1d494fa3da945e1b15f374e91a695bf7cbd34bc891
2025-11-14 10:12:32,157 - DEBUG - botocore.auth - add_auth: Signature:
41d2dd9fa831810a5cb0a219be6fd4c6be5d45772ad4e21c9cdc9720fce7c15d
2025-11-14 10:12:32,158 - DEBUG - botocore.hooks - _emit: Event request-created.s3.CreateBucket: calling handler <function add_retry_headers at 0x7f2f1bb4cf50>
2025-11-14 10:12:32,158 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=http://localhost:12406/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'20251114T101232Z', '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=41d2dd9fa831810a5cb0a219be6fd4c6be5d45772ad4e21c9cdc9720fce7c15d', 'amz-sdk-invocation-id': b'7be5f409-ea6f-48db-a7d7-4511ef58f877', 'amz-sdk-request': b'attempt=1', 'Content-Length': '0'}>
2025-11-14 10:12:32,172 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:12:32 GMT', 'x-amzn-requestid': 'Afesif0dItibk8UtM6kCvjRtND4uRwrBUQpUcVloWVmrhCuE2njH', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '161', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:12:32,173 - 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:12:32,174 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.CreateBucket: calling handler <botocore.retryhandler.RetryHandler object at 0x7f2efe9612d0>
2025-11-14 10:12:32,174 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:12:32,174 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.CreateBucket: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,175 - DEBUG - boto3.resources.action - __call__: Response: {'ResponseMetadata': {'RequestId': 'Afesif0dItibk8UtM6kCvjRtND4uRwrBUQpUcVloWVmrhCuE2njH', 'HTTPStatusCode': 200, 'HTTPHeaders': {'server': 'Werkzeug/2.2.3 Python/3.12.9', 'date': 'Fri, 14 Nov 2025 10:12:32 GMT', 'x-amzn-requestid': 'Afesif0dItibk8UtM6kCvjRtND4uRwrBUQpUcVloWVmrhCuE2njH', 'content-type': 'text/html; charset=utf-8', 'content-length': '161', 'access-control-allow-origin': '*', 'connection': 'close'}, 'RetryAttempts': 0}}
2025-11-14 10:12:32,176 - DEBUG - boto3.resources.collection - pages: Calling paginated s3:list_objects with {'Bucket': 'fbucket'}
2025-11-14 10:12:32,177 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function set_list_objects_encoding_type_url at 0x7f2f1bb4c050>
2025-11-14 10:12:32,177 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function validate_bucket_name at 0x7f2f1bb2a750>
2025-11-14 10:12:32,177 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function remove_bucket_from_url_paths_from_model at 0x7f2f1bb4d010>
2025-11-14 10:12:32,178 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,178 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function generate_idempotent_uuid at 0x7f2f1bb2a510>
2025-11-14 10:12:32,178 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7f2f1bb4d250>
2025-11-14 10:12:32,179 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,179 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:12406', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:12:32,180 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:12406/fbucket
2025-11-14 10:12:32,181 - 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:12:32,181 - 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:12:32,182 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function add_expect_header at 0x7f2f1bb2abd0>
2025-11-14 10:12:32,182 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function add_recursion_detection_header at 0x7f2f1bb28ad0>
2025-11-14 10:12:32,182 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function inject_api_version_header_if_needed at 0x7f2f1bb4c650>
2025-11-14 10:12:32,183 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=ListObjects) with params: {'url_path': '', 'query_string': {'encoding-type': 'url'}, 'method': 'GET', 'headers': {'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:12406/fbucket?encoding-type=url', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7f2ef2d1e860>, 'has_streaming_input': False, 'auth_type': 'v4', 'encoding_type_auto_set': True, 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'EncodingType': 'url'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:12:32,184 - DEBUG - botocore.hooks - _emit: Event request-created.s3.ListObjects: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7f2ef2d1e5e0>>
2025-11-14 10:12:32,184 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.ListObjects: calling handler <bound method ClientCreator._default_s3_presign_to_sigv2 of <botocore.client.ClientCreator object at 0x7f2ef2d4e680>>
2025-11-14 10:12:32,184 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.ListObjects: calling handler <function set_operation_specific_signer at 0x7f2f1bb2a390>
2025-11-14 10:12:32,185 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.ListObjects: calling handler <function remove_arn_from_signing_path at 0x7f2f1bb4d190>
2025-11-14 10:12:32,185 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:12:32,186 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
GET
/fbucket
encoding-type=url
host:localhost:12406
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251114T101232Z

host;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-14 10:12:32,186 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101232Z
20251114/yandex/s3/aws4_request
d5f2797d5d0f5411f50bb1beba40190bde120ed4f66654a072458bfb0acb50e8
2025-11-14 10:12:32,186 - DEBUG - botocore.auth - add_auth: Signature:
09189d5d9013441beee0527cd91ade7393a058dbfa880a1bad426123cb6e110b
2025-11-14 10:12:32,187 - DEBUG - botocore.hooks - _emit: Event request-created.s3.ListObjects: calling handler <function add_retry_headers at 0x7f2f1bb4cf50>
2025-11-14 10:12:32,187 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=GET, url=http://localhost:12406/fbucket?encoding-type=url, headers={'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'20251114T101232Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=host;x-amz-content-sha256;x-amz-date, Signature=09189d5d9013441beee0527cd91ade7393a058dbfa880a1bad426123cb6e110b', 'amz-sdk-invocation-id': b'd53a6404-529c-4132-b0da-a237c60fbc90', 'amz-sdk-request': b'attempt=1'}>
2025-11-14 10:12:32,194 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:12:32 GMT', 'x-amzn-requestid': 'pPO2qPi2WOJ5GRN9CqC7H3t0PTDYRt0rItrnbSkQ9h0VVxqfXU9r', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '554', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:12:32,194 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?><ListBucketResult xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Name>fbucket</Name><MaxKeys>1000</MaxKeys><EncodingType>url</EncodingType><IsTruncated>false</IsTruncated><Contents><Key>fruits.csv</Key><LastModified>2025-11-14T10:10:14.000Z</LastModified><ETag>&#34;e39a9ea5256659abbca6b8b24c67876e&#34;</ETag><Size>47</Size><StorageClass>STANDARD</StorageClass><Owner><ID>75aa57f09aa0c8caeab4f8c24e99d10f8e7faeebf76c078efc7c6caea54ba06a</ID><DisplayName>webfile</DisplayName></Owner></Contents></ListBucketResult>'
2025-11-14 10:12:32,197 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.ListObjects: calling handler <botocore.retryhandler.RetryHandler object at 0x7f2efe9612d0>
2025-11-14 10:12:32,197 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:12:32,198 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.ListObjects: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,198 - DEBUG - botocore.hooks - _emit: Event after-call.s3.ListObjects: calling handler <function decode_list_object at 0x7f2f1bb4c110>
2025-11-14 10:12:32,199 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:ObjectSummary
2025-11-14 10:12:32,199 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming ObjectSummary attribute key
2025-11-14 10:12:32,200 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.ObjectSummary: calling handler <function lazy_call.<locals>._handler at 0x7f2f1adeb410>
2025-11-14 10:12:32,201 - DEBUG - boto3.resources.action - __call__: Calling s3:delete_objects with {'Bucket': 'fbucket', 'Delete': {'Objects': [{'Key': 'fruits.csv'}]}}
2025-11-14 10:12:32,202 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function validate_bucket_name at 0x7f2f1bb2a750>
2025-11-14 10:12:32,203 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function remove_bucket_from_url_paths_from_model at 0x7f2f1bb4d010>
2025-11-14 10:12:32,203 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,203 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function generate_idempotent_uuid at 0x7f2f1bb2a510>
2025-11-14 10:12:32,203 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7f2f1bb4d250>
2025-11-14 10:12:32,203 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,203 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:12406', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:12:32,204 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:12406/fbucket
2025-11-14 10:12:32,204 - 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:12:32,204 - 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:12:32,205 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function escape_xml_payload at 0x7f2f1bb2a150>
2025-11-14 10:12:32,205 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function add_expect_header at 0x7f2f1bb2abd0>
2025-11-14 10:12:32,205 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function add_recursion_detection_header at 0x7f2f1bb28ad0>
2025-11-14 10:12:32,205 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function inject_api_version_header_if_needed at 0x7f2f1bb4c650>
2025-11-14 10:12:32,206 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=DeleteObjects) with params: {'url_path': '?delete', 'query_string': {}, 'method': 'POST', 'headers': {'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', 'Content-MD5': 'qhYwzsEvymlDQ0qxfrex2g=='}, 'body': b'<Delete xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Object><Key>fruits.csv</Key></Object></Delete>', 'auth_path': '/fbucket/', 'url': 'http://localhost:12406/fbucket?delete', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7f2ef2d1e860>, 'has_streaming_input': False, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'Delete': {'Objects': [{'Key': 'fruits.csv'}]}}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}, 'checksum': {'request_algorithm': 'conditional-md5'}}}
2025-11-14 10:12:32,206 - DEBUG - botocore.hooks - _emit: Event request-created.s3.DeleteObjects: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7f2ef2d1e5e0>>
2025-11-14 10:12:32,207 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.DeleteObjects: calling handler <bound method ClientCreator._default_s3_presign_to_sigv2 of <botocore.client.ClientCreator object at 0x7f2ef2d4e680>>
2025-11-14 10:12:32,207 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.DeleteObjects: calling handler <function set_operation_specific_signer at 0x7f2f1bb2a390>
2025-11-14 10:12:32,207 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.DeleteObjects: calling handler <function remove_arn_from_signing_path at 0x7f2f1bb4d190>
2025-11-14 10:12:32,207 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:12:32,207 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/fbucket
delete=
content-md5:qhYwzsEvymlDQ0qxfrex2g==
host:localhost:12406
x-amz-content-sha256:c1e075aa57263477fc032bb83fe20baad49e1b1d446bac947bae3aca35a1d621
x-amz-date:20251114T101232Z

content-md5;host;x-amz-content-sha256;x-amz-date
c1e075aa57263477fc032bb83fe20baad49e1b1d446bac947bae3aca35a1d621
2025-11-14 10:12:32,208 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101232Z
20251114/yandex/s3/aws4_request
0fccea4fc337d15b1f10e6687e81fcc22a675e90fc939bba51722992027bad96
2025-11-14 10:12:32,208 - DEBUG - botocore.auth - add_auth: Signature:
652685616aed5cff59bac99b644d216cb325ac07b240263182fde8329cbfdbdb
2025-11-14 10:12:32,208 - DEBUG - botocore.hooks - _emit: Event request-created.s3.DeleteObjects: calling handler <function add_retry_headers at 0x7f2f1bb4cf50>
2025-11-14 10:12:32,208 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:12406/fbucket?delete, headers={'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', 'Content-MD5': b'qhYwzsEvymlDQ0qxfrex2g==', 'X-Amz-Date': b'20251114T101232Z', 'X-Amz-Content-SHA256': b'c1e075aa57263477fc032bb83fe20baad49e1b1d446bac947bae3aca35a1d621', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=content-md5;host;x-amz-content-sha256;x-amz-date, Signature=652685616aed5cff59bac99b644d216cb325ac07b240263182fde8329cbfdbdb', 'amz-sdk-invocation-id': b'abd27a29-2136-4017-82b3-ee005d53dd02', 'amz-sdk-request': b'attempt=1', 'Content-Length': '103'}>
2025-11-14 10:12:32,214 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:12:32 GMT', 'x-amzn-requestid': 'pWalKAu0zs82OaEYcSNIRNzg0P1gFgu4SI98EpObnrCsmluk2lvj', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '154', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:12:32,214 - DEBUG - botocore.parsers - parse: Response body:
b'<?xml version="1.0" encoding="UTF-8"?><DeleteResult xmlns="http://s3.amazonaws.com/doc/2006-03-01"><Deleted><Key>fruits.csv</Key></Deleted></DeleteResult>'
2025-11-14 10:12:32,215 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.DeleteObjects: calling handler <botocore.retryhandler.RetryHandler object at 0x7f2efe9612d0>
2025-11-14 10:12:32,215 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:12:32,216 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.DeleteObjects: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f1b086310>>
2025-11-14 10:12:32,216 - DEBUG - boto3.resources.action - __call__: Response: {'ResponseMetadata': {'RequestId': 'pWalKAu0zs82OaEYcSNIRNzg0P1gFgu4SI98EpObnrCsmluk2lvj', 'HTTPStatusCode': 200, 'HTTPHeaders': {'server': 'Werkzeug/2.2.3 Python/3.12.9', 'date': 'Fri, 14 Nov 2025 10:12:32 GMT', 'x-amzn-requestid': 'pWalKAu0zs82OaEYcSNIRNzg0P1gFgu4SI98EpObnrCsmluk2lvj', 'content-type': 'text/html; charset=utf-8', 'content-length': '154', 'access-control-allow-origin': '*', 'connection': 'close'}, 'RetryAttempts': 0}, 'Deleted': [{'Key': 'fruits.csv'}]}
2025-11-14 10:12:32,217 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7f2f1bb28890>
2025-11-14 10:12:32,219 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7f2f1bc34590>
2025-11-14 10:12:32,219 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7f2f1adea810>
2025-11-14 10:12:32,219 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7f2f1bc34290>
2025-11-14 10:12:32,221 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-14 10:12:32,225 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-14 10:12:32,225 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-14 10:12:32,227 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function validate_ascii_metadata at 0x7f2f1bb2b7d0>
2025-11-14 10:12:32,227 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function sse_md5 at 0x7f2f1bb2a810>
2025-11-14 10:12:32,228 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function convert_body_to_file_like_object at 0x7f2f1bb4c4d0>
2025-11-14 10:12:32,228 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function validate_bucket_name at 0x7f2f1bb2a750>
2025-11-14 10:12:32,228 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function remove_bucket_from_url_paths_from_model at 0x7f2f1bb4d010>
2025-11-14 10:12:32,228 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f06f52360>>
2025-11-14 10:12:32,228 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function generate_idempotent_uuid at 0x7f2f1bb2a510>
2025-11-14 10:12:32,229 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7f2f1bb4d250>
2025-11-14 10:12:32,229 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f06f52360>>
2025-11-14 10:12:32,229 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:12406', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:12:32,231 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:12406/fbucket
2025-11-14 10:12:32,231 - 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:12:32,231 - 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:12:32,232 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function conditionally_calculate_md5 at 0x7f2f1bd3f710>
2025-11-14 10:12:32,233 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function add_expect_header at 0x7f2f1bb2abd0>
2025-11-14 10:12:32,233 - DEBUG - botocore.handlers - add_expect_header: Adding expect 100 continue header to request.
2025-11-14 10:12:32,233 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function add_recursion_detection_header at 0x7f2f1bb28ad0>
2025-11-14 10:12:32,233 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function inject_api_version_header_if_needed at 0x7f2f1bb4c650>
2025-11-14 10:12:32,233 - 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': 'syluNHiGATUP+5/OfJsmcg==', 'Expect': '100-continue'}, 'body': <_io.BytesIO object at 0x7f2eff39cb40>, 'auth_path': '/fbucket/fruits.csv', 'url': 'http://localhost:12406/fbucket/fruits.csv', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7f2ef28ee400>, 'has_streaming_input': True, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Body': <_io.BytesIO object at 0x7f2eff39cb40>, 'Bucket': 'fbucket', 'Key': 'fruits.csv', 'ContentType': 'text/plain'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:12:32,235 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7f2ef28ec5b0>>
2025-11-14 10:12:32,235 - 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 0x7f2efef9bdf0>>
2025-11-14 10:12:32,235 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.PutObject: calling handler <function set_operation_specific_signer at 0x7f2f1bb2a390>
2025-11-14 10:12:32,236 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.PutObject: calling handler <function remove_arn_from_signing_path at 0x7f2f1bb4d190>
2025-11-14 10:12:32,236 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:12:32,237 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
PUT
/fbucket/fruits.csv

content-md5:syluNHiGATUP+5/OfJsmcg==
content-type:text/plain
host:localhost:12406
x-amz-content-sha256:be1c1c4a83c407c74f1385ef6ac12822772856cf51582ff70769720688989a00
x-amz-date:20251114T101232Z

content-md5;content-type;host;x-amz-content-sha256;x-amz-date
be1c1c4a83c407c74f1385ef6ac12822772856cf51582ff70769720688989a00
2025-11-14 10:12:32,237 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101232Z
20251114/yandex/s3/aws4_request
9330afac64e111cff6c3a110e4aa24b7a5ea3732b572a1f2086207d6d1a379ff
2025-11-14 10:12:32,237 - DEBUG - botocore.auth - add_auth: Signature:
835c2697d9aeed516c1656082d12fd11e963956af06b48aa08d7b660904f4c1a
2025-11-14 10:12:32,237 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <function add_retry_headers at 0x7f2f1bb4cf50>
2025-11-14 10:12:32,238 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=http://localhost:12406/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'syluNHiGATUP+5/OfJsmcg==', 'Expect': b'100-continue', 'X-Amz-Date': b'20251114T101232Z', 'X-Amz-Content-SHA256': b'be1c1c4a83c407c74f1385ef6ac12822772856cf51582ff70769720688989a00', '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=835c2697d9aeed516c1656082d12fd11e963956af06b48aa08d7b660904f4c1a', 'amz-sdk-invocation-id': b'0e336e94-876b-4629-8311-d90275c1371b', 'amz-sdk-request': b'attempt=1', 'Content-Length': '53'}>
2025-11-14 10:12:32,240 - DEBUG - botocore.awsrequest - _send_output: Waiting for 100 Continue response.
2025-11-14 10:12:32,241 - DEBUG - botocore.awsrequest - _handle_expect_response: 100 Continue response seen, now sending request body.
2025-11-14 10:12:32,245 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:12:32 GMT', 'ETag': '"b3296e34788601350ffb9fce7c9b2672"', 'last-modified': 'Fri, 14 Nov 2025 10:12:32 GMT', 'Content-Length': '0', 'x-amzn-requestid': 'va6KToQ5zyOm3OkGkApMfdDY5LEXmMGgmN3LVJFwVzWekHxXD3sE', 'Content-Type': 'text/html; charset=utf-8', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:12:32,246 - DEBUG - botocore.parsers - parse: Response body:
b''
2025-11-14 10:12:32,247 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.PutObject: calling handler <botocore.retryhandler.RetryHandler object at 0x7f2f06f515f0>
2025-11-14 10:12:32,247 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:12:32,247 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2f06f52360>>
2025-11-14 10:12:32,247 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-14 10:12:32,285 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-14 10:12:32,692 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 10:12:32,693 - 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:12:32,697 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-14 10:12:32,698 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, a5ced8a0-33c7-48cd-86b8-16ea59b63fc4, localhost:4203): creating call state
2025-11-14 10:12:32,698 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, a5ced8a0-33c7-48cd-86b8-16ea59b63fc4, localhost:4203): request = { database: "local" }
2025-11-14 10:12:32,701 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, a5ced8a0-33c7-48cd-86b8-16ea59b63fc4, localhost:4203): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-adxazuzm5e.auto.internal" port: 4203 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:12:32,702 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-adxazuzm5e.auto.internal:4203, location 1, ssl: False>]>
2025-11-14 10:12:32,702 - INFO - ydb.connection - close: Closing channel for endpoint localhost:4203
2025-11-14 10:12:32,768 - DEBUG - root - create_query_impl: Request: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "\n            SELECT *\n            FROM `h243322219316014593_test_double_optional_types_validationfruitbucket`.`fruits.csv`\n            WITH (format=\'csv_with_names\', SCHEMA (\n                Name Int32??,\n            ));\n            "
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "fd2158d1-1a71-40fc-bb50-c44861b908f5"

2025-11-14 10:12:32,939 - DEBUG - root - create_query_impl: Result: query_id: "ptqudsej1ptr3mipe69n"

2025-11-14 10:12:32,994 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej1ptr3mipe69n"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763115152
        nanos: 774003000
      }
      modified_at {
        seconds: 1763115152
        nanos: 774003000
      }
      revision: 1
    }
    execute_mode: RUN
    status: STARTING
    last_job_query_revision: 1
    last_job_id: "ptjudsej1ptr2m94jqpe-ptqudsej1ptr3mipe69n"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763115152
      nanos: 774003000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM `h243322219316014593_test_double_optional_types_validationfruitbucket`.`fruits.csv`\n            WITH (format=\'csv_with_names\', SCHEMA (\n                Name Int32??,\n            ));\n            "
    syntax: YQL_V1
  }
}

2025-11-14 10:12:33,508 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-14 10:12:34,020 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej1ptr3mipe69n"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763115152
        nanos: 774003000
      }
      modified_at {
        seconds: 1763115152
        nanos: 774003000
      }
      revision: 1
    }
    started_at {
      seconds: 1763115153
      nanos: 541000000
    }
    execute_mode: RUN
    status: RUNNING
    last_job_query_revision: 1
    last_job_id: "ptjudsej1ptr2m94jqpe-ptqudsej1ptr3mipe69n"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763115152
      nanos: 774003000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM `h243322219316014593_test_double_optional_types_validationfruitbucket`.`fruits.csv`\n            WITH (format=\'csv_with_names\', SCHEMA (\n                Name Int32??,\n            ));\n            "
    syntax: YQL_V1
  }
}

2025-11-14 10:12:34,534 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej1ptr3mipe69n"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763115152
        nanos: 774003000
      }
      modified_at {
        seconds: 1763115152
        nanos: 774003000
      }
      revision: 1
    }
    started_at {
      seconds: 1763115153
      nanos: 541000000
    }
    finished_at {
      seconds: 1763115154
      nanos: 463000000
    }
    execute_mode: RUN
    status: FAILED
    last_job_query_revision: 1
    last_job_id: "ptjudsej1ptr2m94jqpe-ptqudsej1ptr3mipe69n"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763115152
      nanos: 774003000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM `h243322219316014593_test_double_optional_types_validationfruitbucket`.`fruits.csv`\n            WITH (format=\'csv_with_names\', SCHEMA (\n                Name Int32??,\n            ));\n            "
    syntax: YQL_V1
  }
  plan {
    json: "{}"
  }
  issue {
    message: "Query failed with code GENERIC_ERROR at 2025-11-14T10:12:34.307644Z"
    severity: 1
    issues {
      message: "Type annotation"
      issue_code: 1030
      severity: 1
      issues {
        position {
          row: 3
          column: 18
        }
        message: "At function: S3ReadObject!"
        end_position {
          row: 3
          column: 18
        }
        severity: 1
        issues {
          position {
            row: 4
            column: 44
          }
          message: "Field \'Name\' has incompatible with S3 csv_with_names input format type: Optional<Int32> (double optional types are not supported)"
          end_position {
            row: 4
            column: 44
          }
          severity: 1
        }
      }
    }
  }
  statistics {
    json: "{}"
  }
  ast {
    data: "(\n(let $1 \'\"/local/h243322219316014593_test_double_optional_types_validationfruitbucket\")\n(let $2 (S3Object \'(\'((String \'\"Paths {\\n  Name: \\\"fruits.csv\\\"\\n  Size: 53\\n  Read: true\\n}\\n\") (Bool \'\"true\") (AsStruct))) \'\"csv_with_names\" \'()))\n(let $3 (StructType \'(\'\"Name\" (OptionalType (OptionalType (DataType \'Int32))))))\n(let $4 \'(\'\"Name\"))\n(let $5 (DataSink \'result))\n(let $6 (S3Object \'(\'((String \'\"Paths {\\n  Name: \\\"fruits.csv\\\"\\n  Size: 53\\n  Read: true\\n}\\n\") (Bool \'\"true\") (AsStruct))) \'\"csv_with_names\" \'()))\n(let $7 (Right! (S3ReadObject! world (DataSource \'\"s3\" $1) $6 $3 \'\"fruits.csv \" (lambda \'($10) (Bool \'\"true\")) $4)))\n(let $8 (ResWrite! (Left! (S3ReadObject! world (DataSource \'\"s3\" $1) $2 $3 \'\"fruits.csv \" (lambda \'($9) (Bool \'\"true\")) $4)) $5 (Key) (RemovePrefixMembers (RemoveSystemMembers (PersistableRepr (SqlProject $7 \'((SqlProjectStarItem (TypeOf $7) \'\"\" (lambda \'($11) $11) \'()))))) \'(\'_yql_sys_)) \'(\'(\'type) \'(\'autoref))))\n(return (Commit! (Commit! $8 $5) (DataSink \'\"kikimr\" \'\"db\") \'(\'(\'\"mode\" \'\"flush\"))))\n)\n"
  }
  timeline {
    svg: "<svg width=\'1280\' height=\'0\' xmlns=\'http://www.w3.org/2000/svg\'>\n<clipPath id=\'clipTextPath\'><rect x=\'0\' y=\'0\' width=\'298\' height=\'0\'/></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\n<line x1=\'600\' y1=\'0\' x2=\'600\' y2=\'0\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n\n</svg>\n"
  }
}

2025-11-14 10:12:34,543 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-14 10:12:34,543 - DEBUG - root - create_query_impl: Request: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "\n            INSERT INTO `h243322219316014593_test_double_optional_types_validationfruitbucket`.`insert/`\n            WITH\n            (\n                FORMAT=\"csv_with_names\"\n            )\n            SELECT CAST(42 AS Int32??) as Weight;"
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "11d94b45-3fbc-4de2-a693-819791c0033e"

2025-11-14 10:12:34,563 - DEBUG - root - create_query_impl: Result: query_id: "ptqudsej1o7m3qvr1f4s"

2025-11-14 10:12:34,574 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej1o7m3qvr1f4s"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763115154
        nanos: 548604000
      }
      modified_at {
        seconds: 1763115154
        nanos: 548604000
      }
      revision: 1
    }
    execute_mode: RUN
    status: STARTING
    last_job_query_revision: 1
    last_job_id: "ptjudsej1o7m1juffo4o-ptqudsej1o7m3qvr1f4s"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763115154
      nanos: 548604000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            INSERT INTO `h243322219316014593_test_double_optional_types_validationfruitbucket`.`insert/`\n            WITH\n            (\n                FORMAT=\"csv_with_names\"\n            )\n            SELECT CAST(42 AS Int32??) as Weight;"
    syntax: YQL_V1
  }
}

2025-11-14 10:12:35,086 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-14 10:12:35,599 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej1o7m3qvr1f4s"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763115154
        nanos: 548604000
      }
      modified_at {
        seconds: 1763115154
        nanos: 548604000
      }
      revision: 1
    }
    started_at {
      seconds: 1763115155
      nanos: 462000000
    }
    execute_mode: RUN
    status: FAILING
    last_job_query_revision: 1
    last_job_id: "ptjudsej1o7m1juffo4o-ptqudsej1o7m3qvr1f4s"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763115154
      nanos: 548604000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            INSERT INTO `h243322219316014593_test_double_optional_types_validationfruitbucket`.`insert/`\n            WITH\n            (\n                FORMAT=\"csv_with_names\"\n            )\n            SELECT CAST(42 AS Int32??) as Weight;"
    syntax: YQL_V1
  }
  plan {
    json: "{}"
  }
  issue {
    message: "Query failed with code GENERIC_ERROR at 2025-11-14T10:12:35.548293Z"
    severity: 1
    issues {
      message: "Type annotation"
      issue_code: 1030
      severity: 1
      issues {
        position {
          row: 5
          column: 24
        }
        message: "At function: S3WriteObject!"
        end_position {
          row: 5
          column: 24
        }
        severity: 1
        issues {
          position {
            row: 7
            column: 13
          }
          message: "Field \'Weight\' has incompatible with S3 csv_with_names output format type: Optional<Int32> (double optional types are not supported)"
          end_position {
            row: 7
            column: 13
          }
          severity: 1
        }
      }
    }
  }
  statistics {
    json: "{}"
  }
  ast {
    data: "(\n(let $1 \'\"/local/h243322219316014593_test_double_optional_types_validationfruitbucket\")\n(let $2 (S3Target \'\"insert/\" \'\"csv_with_names\" \'(\'(\'mode \'insert_abort))))\n(let $3 (S3WriteObject! world (DataSink \'\"s3\" $1) $2 (OrderedSqlProject (AsList (AsStruct)) \'((SqlProjectItem (ListType (StructType)) \'\"Weight\" (lambda \'($4) (SafeCast (Int32 \'\"42\") (OptionalType (OptionalType (DataType \'Int32))))))))))\n(return (Commit! $3 (DataSink \'\"kikimr\" \'\"db\") \'(\'(\'\"mode\" \'\"flush\"))))\n)\n"
  }
  timeline {
    svg: "<svg width=\'1280\' height=\'0\' xmlns=\'http://www.w3.org/2000/svg\'>\n<clipPath id=\'clipTextPath\'><rect x=\'0\' y=\'0\' width=\'298\' height=\'0\'/></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\n<line x1=\'600\' y1=\'0\' x2=\'600\' y2=\'0\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n\n</svg>\n"
  }
}

2025-11-14 10:12:36,112 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-14 10:12:36,622 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsej1o7m3qvr1f4s"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763115154
        nanos: 548604000
      }
      modified_at {
        seconds: 1763115154
        nanos: 548604000
      }
      revision: 1
    }
    started_at {
      seconds: 1763115155
      nanos: 462000000
    }
    finished_at {
      seconds: 1763115156
      nanos: 463000000
    }
    execute_mode: RUN
    status: FAILED
    last_job_query_revision: 1
    last_job_id: "ptjudsej1o7m1juffo4o-ptqudsej1o7m3qvr1f4s"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763115154
      nanos: 548604000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            INSERT INTO `h243322219316014593_test_double_optional_types_validationfruitbucket`.`insert/`\n            WITH\n            (\n                FORMAT=\"csv_with_names\"\n            )\n            SELECT CAST(42 AS Int32??) as Weight;"
    syntax: YQL_V1
  }
  plan {
    json: "{}"
  }
  issue {
    message: "Query failed with code GENERIC_ERROR at 2025-11-14T10:12:35.548293Z"
    severity: 1
    issues {
      message: "Type annotation"
      issue_code: 1030
      severity: 1
      issues {
        position {
          row: 5
          column: 24
        }
        message: "At function: S3WriteObject!"
        end_position {
          row: 5
          column: 24
        }
        severity: 1
        issues {
          position {
            row: 7
            column: 13
          }
          message: "Field \'Weight\' has incompatible with S3 csv_with_names output format type: Optional<Int32> (double optional types are not supported)"
          end_position {
            row: 7
            column: 13
          }
          severity: 1
        }
      }
    }
  }
  statistics {
    json: "{}"
  }
  ast {
    data: "(\n(let $1 \'\"/local/h243322219316014593_test_double_optional_types_validationfruitbucket\")\n(let $2 (S3Target \'\"insert/\" \'\"csv_with_names\" \'(\'(\'mode \'insert_abort))))\n(let $3 (S3WriteObject! world (DataSink \'\"s3\" $1) $2 (OrderedSqlProject (AsList (AsStruct)) \'((SqlProjectItem (ListType (StructType)) \'\"Weight\" (lambda \'($4) (SafeCast (Int32 \'\"42\") (OptionalType (OptionalType (DataType \'Int32))))))))))\n(return (Commit! $3 (DataSink \'\"kikimr\" \'\"db\") \'(\'(\'\"mode\" \'\"flush\"))))\n)\n"
  }
  timeline {
    svg: "<svg width=\'1280\' height=\'0\' xmlns=\'http://www.w3.org/2000/svg\'>\n<clipPath id=\'clipTextPath\'><rect x=\'0\' y=\'0\' width=\'298\' height=\'0\'/></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\n<line x1=\'600\' y1=\'0\' x2=\'600\' y2=\'0\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n\n</svg>\n"
  }
}

2025-11-14 10:12:36,631 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-14 10:12:36,633 - INFO - ya.test - pytest_runtest_teardown: Test teardown
