2025-11-10 09:37:37,421 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 09:37:37,422 - INFO - ya.test - pytest_runtest_setup: test_double_optional_types_validation[v2-client0]
2025-11-10 09:37:37,422 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 09:37:37,422 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-10 09:37:40,426 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:14723/1
2025-11-10 09:37:40,427 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3038
2025-11-10 09:37:40,431 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63840
2025-11-10 09:37:40,605 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10138
2025-11-10 09:37:40,605 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14723
2025-11-10 09:37:40,609 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61289
2025-11-10 09:37:40,612 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29468
2025-11-10 09:37:47,621 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:21909/1
2025-11-10 09:37:47,658 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10549
2025-11-10 09:37:47,659 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18979
2025-11-10 09:37:47,659 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11886
2025-11-10 09:37:47,659 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21909
2025-11-10 09:37:47,659 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26980
2025-11-10 09:37:48,661 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:15742/1
2025-11-10 09:37:48,667 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29726
2025-11-10 09:37:48,667 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15625
2025-11-10 09:37:48,667 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/12592
2025-11-10 09:37:48,667 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15742
2025-11-10 09:37:48,685 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3542
2025-11-10 09:37:48,686 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26112
2025-11-10 09:37:48,688 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64396
2025-11-10 09:37:48,719 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16274
2025-11-10 09:37:48,788 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:48,803 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6867
2025-11-10 09:37:48,898 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64070
2025-11-10 09:37:48,901 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 09:37:48,904 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 09:37:48,961 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 09:37:48,969 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-10 09:37:48,971 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, a75d883a-a650-4332-869a-62872b73f82b, localhost:12880): creating call state
2025-11-10 09:37:48,971 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, a75d883a-a650-4332-869a-62872b73f82b, localhost:12880): request = { database: "local" }
2025-11-10 09:37:48,974 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, a75d883a-a650-4332-869a-62872b73f82b, localhost:12880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kipqlopw2q.auto.internal" port: 12880 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-10 09:37:48,975 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-kipqlopw2q.auto.internal:12880, location 1, ssl: False>]>
2025-11-10 09:37:48,975 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 09:37:48,984 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:12880
2025-11-10 09:37:48,997 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-10 09:37:49,033 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f224615e5e0 state=pending>
2025-11-10 09:37:49,034 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-10 09:37:49,034 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f224615e1d0>
2025-11-10 09:37:49,034 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f224615e1d0>
2025-11-10 09:37:49,035 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 46723c66-a8f1-4306-934a-da079e07f53a, ghrun-kipqlopw2q.auto.internal:12880): creating call state
2025-11-10 09:37:49,035 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 46723c66-a8f1-4306-934a-da079e07f53a, ghrun-kipqlopw2q.auto.internal:12880): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-10 09:37:49,039 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 46723c66-a8f1-4306-934a-da079e07f53a, ghrun-kipqlopw2q.auto.internal:12880): on response callback started
2025-11-10 09:37:49,056 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 46723c66-a8f1-4306-934a-da079e07f53a, ghrun-kipqlopw2q.auto.internal:12880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=MWFmNmY5OWYtZTI2MDM0ODEtZDdjYjFmNTEtZDA5ZTgwYTE=" } } } }
2025-11-10 09:37:49,069 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f224615e1d0>
2025-11-10 09:37:49,074 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f224615e1d0>
2025-11-10 09:37:49,077 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 183f9255-7737-4e0d-9edc-781d046db7c9, ghrun-kipqlopw2q.auto.internal:12880): creating call state
2025-11-10 09:37:49,092 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 46723c66-a8f1-4306-934a-da079e07f53a, ghrun-kipqlopw2q.auto.internal:12880): on response callback success
2025-11-10 09:37:49,127 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 183f9255-7737-4e0d-9edc-781d046db7c9, ghrun-kipqlopw2q.auto.internal:12880): request = { session_id: "ydb://session/3?node_id=1&id=MWFmNmY5OWYtZTI2MDM0ODEtZDdjYjFmNTEtZDA5ZTgwYTE=" path: "local/DbPoolStorage_8f7149e5-0271-4884-89a5-91d4198490aa/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-10 09:37:49,149 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 183f9255-7737-4e0d-9edc-781d046db7c9, ghrun-kipqlopw2q.auto.internal:12880): response = { operation { ready: true status: SUCCESS } }
2025-11-10 09:37:49,171 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f224615e1d0>
2025-11-10 09:37:49,187 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, e85ea309-12d9-49a8-8b06-df81295e3c7f, ghrun-kipqlopw2q.auto.internal:12880): creating call state
2025-11-10 09:37:49,202 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, e85ea309-12d9-49a8-8b06-df81295e3c7f, ghrun-kipqlopw2q.auto.internal:12880): request = { session_id: "ydb://session/3?node_id=1&id=MWFmNmY5OWYtZTI2MDM0ODEtZDdjYjFmNTEtZDA5ZTgwYTE=" path: "local/DbPoolStorage_8f7149e5-0271-4884-89a5-91d4198490aa/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 } } } } columns { name: "node" 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-10 09:37:49,234 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, e85ea309-12d9-49a8-8b06-df81295e3c7f, ghrun-kipqlopw2q.auto.internal:12880): response = { operation { ready: true status: SUCCESS } }
2025-11-10 09:37:49,241 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f224615e1d0>
2025-11-10 09:37:49,242 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, ee6d6b4f-2f1e-47a3-b31b-c3bdec94276b, ghrun-kipqlopw2q.auto.internal:12880): creating call state
2025-11-10 09:37:49,255 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, ee6d6b4f-2f1e-47a3-b31b-c3bdec94276b, ghrun-kipqlopw2q.auto.internal:12880): request = { session_id: "ydb://session/3?node_id=1&id=MWFmNmY5OWYtZTI2MDM0ODEtZDdjYjFmNTEtZDA5ZTgwYTE=" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "--!syntax_v1\n            PRAGMA TablePathPrefix(\"local/DbPoolStorage_8f7149e5-0271-4884-89a5-91d4198490aa\");\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-10 09:37:49,276 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, ee6d6b4f-2f1e-47a3-b31b-c3bdec94276b, ghrun-kipqlopw2q.auto.internal:12880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-10 09:37:49,277 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f224615e1d0>
2025-11-10 09:37:49,376 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:49,378 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29888
2025-11-10 09:37:49,378 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20716
2025-11-10 09:37:49,379 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2233
2025-11-10 09:37:49,384 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:49,401 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_p5jwl1gk.log --grpc-port=64070 --mon-port=29888 --ic-port=6867
2025-11-10 09:37:49,401 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:49,402 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_p5jwl1gk.log --grpc-port=64070 --mon-port=29888 --ic-port=6867
2025-11-10 09:37:49,403 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_p5jwl1gk.log', '--grpc-port=64070', '--mon-port=29888', '--ic-port=6867')' in '/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:49,410 - DEBUG - ya.test - execute: Command pid: 353913
2025-11-10 09:37:49,421 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:64070/1
2025-11-10 09:37:49,431 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.24
2025-11-10 09:37:49,435 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.24
2025-11-10 09:37:49,436 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-10 09:37:49,437 - DEBUG - ya.test - execute: Command pid: 353956
2025-11-10 09:37:49,864 - DEBUG - ya.test - _save_outputs: Command (pid 353956) rc: 0
2025-11-10 09:37:49,864 - DEBUG - ya.test - _save_outputs: Command (pid 353956) elapsed time (sec): 0.36890625953674316
2025-11-10 09:37:49,864 - DEBUG - ya.test - _save_outputs: Command (pid 353956) idrss: 0
2025-11-10 09:37:49,865 - DEBUG - ya.test - _save_outputs: Command (pid 353956) inblock: 0
2025-11-10 09:37:49,865 - DEBUG - ya.test - _save_outputs: Command (pid 353956) isrss: 0
2025-11-10 09:37:49,870 - DEBUG - ya.test - _save_outputs: Command (pid 353956) ixrss: 0
2025-11-10 09:37:49,870 - DEBUG - ya.test - _save_outputs: Command (pid 353956) majflt: 0
2025-11-10 09:37:49,885 - DEBUG - ya.test - _save_outputs: Command (pid 353956) maxrss: 541012
2025-11-10 09:37:49,885 - DEBUG - ya.test - _save_outputs: Command (pid 353956) minflt: 16173
2025-11-10 09:37:49,890 - DEBUG - ya.test - _save_outputs: Command (pid 353956) msgrcv: 0
2025-11-10 09:37:49,890 - DEBUG - ya.test - _save_outputs: Command (pid 353956) msgsnd: 0
2025-11-10 09:37:49,890 - DEBUG - ya.test - _save_outputs: Command (pid 353956) nivcsw: 20
2025-11-10 09:37:49,891 - DEBUG - ya.test - _save_outputs: Command (pid 353956) nsignals: 0
2025-11-10 09:37:49,891 - DEBUG - ya.test - _save_outputs: Command (pid 353956) nswap: 0
2025-11-10 09:37:49,891 - DEBUG - ya.test - _save_outputs: Command (pid 353956) nvcsw: 12
2025-11-10 09:37:49,891 - DEBUG - ya.test - _save_outputs: Command (pid 353956) oublock: 8
2025-11-10 09:37:49,892 - DEBUG - ya.test - _save_outputs: Command (pid 353956) stime: 0.24904099999999998
2025-11-10 09:37:49,892 - DEBUG - ya.test - _save_outputs: Command (pid 353956) utime: 0.0939
2025-11-10 09:37:49,892 - DEBUG - ya.test - _save_outputs: Command (pid 353956) wtime: 0.399
2025-11-10 09:37:49,892 - DEBUG - ya.test - _save_outputs: Command (pid 353956) output:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kipqlopw2q
    Host information: 
        Linux ghrun-kipqlopw2q 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-10 09:37:49,893 - DEBUG - ya.test - _save_outputs: Command (pid 353956) errors:

2025-11-10 09:37:49,913 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d
2025-11-10 09:37:49,914 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-10 09:37:49,948 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12829
2025-11-10 09:37:49,950 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27079
2025-11-10 09:37:50,045 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:50,114 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6285
2025-11-10 09:37:50,183 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:50,184 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20535
2025-11-10 09:37:50,187 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10670
2025-11-10 09:37:50,188 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 09:37:50,197 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 09:37:50,198 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 09:37:50,258 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-10 09:37:50,259 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, a714fa7e-e4db-43a2-b003-69bde7491f5b, localhost:12880): creating call state
2025-11-10 09:37:50,260 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, a714fa7e-e4db-43a2-b003-69bde7491f5b, localhost:12880): request = { database: "local" }
2025-11-10 09:37:50,270 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, a714fa7e-e4db-43a2-b003-69bde7491f5b, localhost:12880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kipqlopw2q.auto.internal" port: 12880 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-10 09:37:50,270 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-kipqlopw2q.auto.internal:12880, location 1, ssl: False>]>
2025-11-10 09:37:50,271 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 09:37:50,299 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:12880
2025-11-10 09:37:50,302 - DEBUG - ydb.table.SessionPool - subscribe: Active session queue is empty, subscribe waiter for a session
2025-11-10 09:37:50,382 - DEBUG - ydb.table.SessionPool - subscribe: Subscribe waiter <Future at 0x7f225a91ca60 state=pending>
2025-11-10 09:37:50,383 - DEBUG - ydb.table.SessionPool - subscribe: Session pool is not large enough (active_count < size: 0 < 1). will create a new session.
2025-11-10 09:37:50,383 - DEBUG - ydb.table.SessionPool - _create: Created session <ydb.table.Session object at 0x7f225a91c600>
2025-11-10 09:37:50,384 - DEBUG - ydb.table.SessionPool - _prepare: Preparing session <ydb.table.Session object at 0x7f225a91c600>
2025-11-10 09:37:50,384 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 76483499-6c9a-4435-a2ad-5a5edbb9dae3, ghrun-kipqlopw2q.auto.internal:12880): creating call state
2025-11-10 09:37:50,387 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 76483499-6c9a-4435-a2ad-5a5edbb9dae3, ghrun-kipqlopw2q.auto.internal:12880): request = { operation_params { operation_timeout { seconds: 3 } cancel_after { seconds: 3 } } }
2025-11-10 09:37:50,391 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 76483499-6c9a-4435-a2ad-5a5edbb9dae3, ghrun-kipqlopw2q.auto.internal:12880): on response callback started
2025-11-10 09:37:50,392 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 76483499-6c9a-4435-a2ad-5a5edbb9dae3, ghrun-kipqlopw2q.auto.internal:12880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.CreateSessionResult] { session_id: "ydb://session/3?node_id=1&id=YTI0ZDgzYy1lNjhiZmI5ZS02NzgwMzlmLTFiODUyODdi" } } } }
2025-11-10 09:37:50,392 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f225a91c600>
2025-11-10 09:37:50,393 - DEBUG - ydb.table.SessionPool - put: Replying to waiter with a session <ydb.table.Session object at 0x7f225a91c600>
2025-11-10 09:37:50,393 - DEBUG - ydb.connection - _on_response_callback: RpcState(CreateSession, 76483499-6c9a-4435-a2ad-5a5edbb9dae3, ghrun-kipqlopw2q.auto.internal:12880): on response callback success
2025-11-10 09:37:50,394 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 2e04bbe0-418f-4527-b31d-0b129d1b434c, ghrun-kipqlopw2q.auto.internal:12880): creating call state
2025-11-10 09:37:50,402 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 2e04bbe0-418f-4527-b31d-0b129d1b434c, ghrun-kipqlopw2q.auto.internal:12880): request = { session_id: "ydb://session/3?node_id=1&id=YTI0ZDgzYy1lNjhiZmI5ZS02NzgwMzlmLTFiODUyODdi" path: "local/DbPoolStorage_e3243f1a-8766-4dfd-9da1-7a8e4c242752/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-10 09:37:50,441 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 2e04bbe0-418f-4527-b31d-0b129d1b434c, ghrun-kipqlopw2q.auto.internal:12880): response = { operation { ready: true status: SUCCESS } }
2025-11-10 09:37:50,442 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f225a91c600>
2025-11-10 09:37:50,442 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateTable, 29743ed1-d3d6-4338-a315-246fc54a4a28, ghrun-kipqlopw2q.auto.internal:12880): creating call state
2025-11-10 09:37:50,443 - DEBUG - ydb.connection - _log_request: RpcState(CreateTable, 29743ed1-d3d6-4338-a315-246fc54a4a28, ghrun-kipqlopw2q.auto.internal:12880): request = { session_id: "ydb://session/3?node_id=1&id=YTI0ZDgzYy1lNjhiZmI5ZS02NzgwMzlmLTFiODUyODdi" path: "local/DbPoolStorage_e3243f1a-8766-4dfd-9da1-7a8e4c242752/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 } } } } columns { name: "node" 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-10 09:37:50,483 - DEBUG - ydb.connection - _log_response: RpcState(CreateTable, 29743ed1-d3d6-4338-a315-246fc54a4a28, ghrun-kipqlopw2q.auto.internal:12880): response = { operation { ready: true status: SUCCESS } }
2025-11-10 09:37:50,556 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f225a91c600>
2025-11-10 09:37:50,558 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteDataQuery, 6d53a98e-67cd-48a7-9c48-be38ea68dac9, ghrun-kipqlopw2q.auto.internal:12880): creating call state
2025-11-10 09:37:50,568 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteDataQuery, 6d53a98e-67cd-48a7-9c48-be38ea68dac9, ghrun-kipqlopw2q.auto.internal:12880): request = { session_id: "ydb://session/3?node_id=1&id=YTI0ZDgzYy1lNjhiZmI5ZS02NzgwMzlmLTFiODUyODdi" tx_control { begin_tx { serializable_read_write { } } commit_tx: true } query { yql_text: "--!syntax_v1\n            PRAGMA TablePathPrefix(\"local/DbPoolStorage_e3243f1a-8766-4dfd-9da1-7a8e4c242752\");\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-10 09:37:50,594 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteDataQuery, 6d53a98e-67cd-48a7-9c48-be38ea68dac9, ghrun-kipqlopw2q.auto.internal:12880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Table.ExecuteQueryResult] { tx_meta { } } } } }
2025-11-10 09:37:50,598 - DEBUG - ydb.table.SessionPool - put: Put on session <ydb.table.Session object at 0x7f225a91c600>
2025-11-10 09:37:50,599 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15397
2025-11-10 09:37:50,676 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:50,688 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10124
2025-11-10 09:37:50,688 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23958
2025-11-10 09:37:50,689 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16458
2025-11-10 09:37:50,701 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:50,703 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_gar9sg0_.log --grpc-port=10670 --mon-port=10124 --ic-port=6285
2025-11-10 09:37:50,712 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:50,716 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_gar9sg0_.log --grpc-port=10670 --mon-port=10124 --ic-port=6285
2025-11-10 09:37:50,726 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_gar9sg0_.log', '--grpc-port=10670', '--mon-port=10124', '--ic-port=6285')' in '/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:50,729 - DEBUG - ya.test - execute: Command pid: 355705
2025-11-10 09:37:50,730 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10670/1
2025-11-10 09:37:50,732 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.25
2025-11-10 09:37:50,733 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.25
2025-11-10 09:37:50,748 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-10 09:37:50,753 - DEBUG - ya.test - execute: Command pid: 355728
2025-11-10 09:37:51,287 - DEBUG - ya.test - _save_outputs: Command (pid 355728) rc: 0
2025-11-10 09:37:51,288 - DEBUG - ya.test - _save_outputs: Command (pid 355728) elapsed time (sec): 0.5216925144195557
2025-11-10 09:37:51,289 - DEBUG - ya.test - _save_outputs: Command (pid 355728) idrss: 0
2025-11-10 09:37:51,289 - DEBUG - ya.test - _save_outputs: Command (pid 355728) inblock: 0
2025-11-10 09:37:51,289 - DEBUG - ya.test - _save_outputs: Command (pid 355728) isrss: 0
2025-11-10 09:37:51,289 - DEBUG - ya.test - _save_outputs: Command (pid 355728) ixrss: 0
2025-11-10 09:37:51,289 - DEBUG - ya.test - _save_outputs: Command (pid 355728) majflt: 0
2025-11-10 09:37:51,290 - DEBUG - ya.test - _save_outputs: Command (pid 355728) maxrss: 541032
2025-11-10 09:37:51,290 - DEBUG - ya.test - _save_outputs: Command (pid 355728) minflt: 16173
2025-11-10 09:37:51,290 - DEBUG - ya.test - _save_outputs: Command (pid 355728) msgrcv: 0
2025-11-10 09:37:51,290 - DEBUG - ya.test - _save_outputs: Command (pid 355728) msgsnd: 0
2025-11-10 09:37:51,301 - DEBUG - ya.test - _save_outputs: Command (pid 355728) nivcsw: 21
2025-11-10 09:37:51,321 - DEBUG - ya.test - _save_outputs: Command (pid 355728) nsignals: 0
2025-11-10 09:37:51,333 - DEBUG - ya.test - _save_outputs: Command (pid 355728) nswap: 0
2025-11-10 09:37:51,333 - DEBUG - ya.test - _save_outputs: Command (pid 355728) nvcsw: 15
2025-11-10 09:37:51,333 - DEBUG - ya.test - _save_outputs: Command (pid 355728) oublock: 8
2025-11-10 09:37:51,333 - DEBUG - ya.test - _save_outputs: Command (pid 355728) stime: 0.29437399999999997
2025-11-10 09:37:51,334 - DEBUG - ya.test - _save_outputs: Command (pid 355728) utime: 0.11682
2025-11-10 09:37:51,334 - DEBUG - ya.test - _save_outputs: Command (pid 355728) wtime: 0.528
2025-11-10 09:37:51,334 - DEBUG - ya.test - _save_outputs: Command (pid 355728) output:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kipqlopw2q
    Host information: 
        Linux ghrun-kipqlopw2q 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-10 09:37:51,334 - DEBUG - ya.test - _save_outputs: Command (pid 355728) errors:

2025-11-10 09:37:51,335 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d
2025-11-10 09:37:51,335 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-10 09:37:51,410 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:51,411 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11848
2025-11-10 09:37:51,411 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28554
2025-11-10 09:37:51,412 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27999
2025-11-10 09:37:51,413 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:51,413 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_92kexfzm.log --grpc-port=15397 --mon-port=11848 --ic-port=20535
2025-11-10 09:37:51,414 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:51,414 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_92kexfzm.log --grpc-port=15397 --mon-port=11848 --ic-port=20535
2025-11-10 09:37:51,442 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6t6o/002514/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/6t6o/002514/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_92kexfzm.log', '--grpc-port=15397', '--mon-port=11848', '--ic-port=20535')' in '/home/runner/.ya/build/build_root/6t6o/002514/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-10 09:37:51,450 - DEBUG - ya.test - execute: Command pid: 356710
2025-11-10 09:37:51,453 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:15397/1
2025-11-10 09:37:51,455 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.26
2025-11-10 09:37:51,456 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.26
2025-11-10 09:37:51,456 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-10 09:37:51,469 - DEBUG - ya.test - execute: Command pid: 356730
2025-11-10 09:37:51,999 - DEBUG - ya.test - _save_outputs: Command (pid 356730) rc: 0
2025-11-10 09:37:51,999 - DEBUG - ya.test - _save_outputs: Command (pid 356730) elapsed time (sec): 0.5056784152984619
2025-11-10 09:37:51,999 - DEBUG - ya.test - _save_outputs: Command (pid 356730) idrss: 0
2025-11-10 09:37:52,000 - DEBUG - ya.test - _save_outputs: Command (pid 356730) inblock: 0
2025-11-10 09:37:52,000 - DEBUG - ya.test - _save_outputs: Command (pid 356730) isrss: 0
2025-11-10 09:37:52,000 - DEBUG - ya.test - _save_outputs: Command (pid 356730) ixrss: 0
2025-11-10 09:37:52,000 - DEBUG - ya.test - _save_outputs: Command (pid 356730) majflt: 0
2025-11-10 09:37:52,000 - DEBUG - ya.test - _save_outputs: Command (pid 356730) maxrss: 541012
2025-11-10 09:37:52,001 - DEBUG - ya.test - _save_outputs: Command (pid 356730) minflt: 16174
2025-11-10 09:37:52,001 - DEBUG - ya.test - _save_outputs: Command (pid 356730) msgrcv: 0
2025-11-10 09:37:52,001 - DEBUG - ya.test - _save_outputs: Command (pid 356730) msgsnd: 0
2025-11-10 09:37:52,001 - DEBUG - ya.test - _save_outputs: Command (pid 356730) nivcsw: 71
2025-11-10 09:37:52,001 - DEBUG - ya.test - _save_outputs: Command (pid 356730) nsignals: 0
2025-11-10 09:37:52,001 - DEBUG - ya.test - _save_outputs: Command (pid 356730) nswap: 0
2025-11-10 09:37:52,002 - DEBUG - ya.test - _save_outputs: Command (pid 356730) nvcsw: 14
2025-11-10 09:37:52,002 - DEBUG - ya.test - _save_outputs: Command (pid 356730) oublock: 8
2025-11-10 09:37:52,002 - DEBUG - ya.test - _save_outputs: Command (pid 356730) stime: 0.280173
2025-11-10 09:37:52,002 - DEBUG - ya.test - _save_outputs: Command (pid 356730) utime: 0.146005
2025-11-10 09:37:52,002 - DEBUG - ya.test - _save_outputs: Command (pid 356730) wtime: 0.536
2025-11-10 09:37:52,003 - DEBUG - ya.test - _save_outputs: Command (pid 356730) output:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-kipqlopw2q
    Host information: 
        Linux ghrun-kipqlopw2q 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-10 09:37:52,024 - DEBUG - ya.test - _save_outputs: Command (pid 356730) errors:

2025-11-10 09:37:52,025 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d
2025-11-10 09:37:54,210 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:54,246 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.27
2025-11-10 09:37:54,247 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.27
2025-11-10 09:37:54,248 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15397', '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: 20535\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-10 09:37:54,249 - DEBUG - ya.test - execute: Command pid: 360107
2025-11-10 09:37:54,749 - DEBUG - ya.test - _save_outputs: Command (pid 360107) rc: 0
2025-11-10 09:37:54,754 - DEBUG - ya.test - _save_outputs: Command (pid 360107) elapsed time (sec): 0.4771275520324707
2025-11-10 09:37:54,755 - DEBUG - ya.test - _save_outputs: Command (pid 360107) idrss: 0
2025-11-10 09:37:54,755 - DEBUG - ya.test - _save_outputs: Command (pid 360107) inblock: 0
2025-11-10 09:37:54,755 - DEBUG - ya.test - _save_outputs: Command (pid 360107) isrss: 0
2025-11-10 09:37:54,755 - DEBUG - ya.test - _save_outputs: Command (pid 360107) ixrss: 0
2025-11-10 09:37:54,755 - DEBUG - ya.test - _save_outputs: Command (pid 360107) majflt: 0
2025-11-10 09:37:54,756 - DEBUG - ya.test - _save_outputs: Command (pid 360107) maxrss: 549256
2025-11-10 09:37:54,756 - DEBUG - ya.test - _save_outputs: Command (pid 360107) minflt: 18312
2025-11-10 09:37:54,756 - DEBUG - ya.test - _save_outputs: Command (pid 360107) msgrcv: 0
2025-11-10 09:37:54,756 - DEBUG - ya.test - _save_outputs: Command (pid 360107) msgsnd: 0
2025-11-10 09:37:54,757 - DEBUG - ya.test - _save_outputs: Command (pid 360107) nivcsw: 25
2025-11-10 09:37:54,757 - DEBUG - ya.test - _save_outputs: Command (pid 360107) nsignals: 0
2025-11-10 09:37:54,757 - DEBUG - ya.test - _save_outputs: Command (pid 360107) nswap: 0
2025-11-10 09:37:54,757 - DEBUG - ya.test - _save_outputs: Command (pid 360107) nvcsw: 122
2025-11-10 09:37:54,757 - DEBUG - ya.test - _save_outputs: Command (pid 360107) oublock: 8
2025-11-10 09:37:54,758 - DEBUG - ya.test - _save_outputs: Command (pid 360107) stime: 0.29117699999999996
2025-11-10 09:37:54,758 - DEBUG - ya.test - _save_outputs: Command (pid 360107) utime: 0.129849
2025-11-10 09:37:54,758 - DEBUG - ya.test - _save_outputs: Command (pid 360107) wtime: 0.479
2025-11-10 09:37:54,758 - DEBUG - ya.test - _save_outputs: Command (pid 360107) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-10 09:37:54,758 - DEBUG - ya.test - _save_outputs: Command (pid 360107) errors:

2025-11-10 09:37:54,760 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:54,762 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.28
2025-11-10 09:37:54,763 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.28
2025-11-10 09:37:54,763 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15397', '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-10 09:37:54,764 - DEBUG - ya.test - execute: Command pid: 360810
2025-11-10 09:37:55,254 - DEBUG - ya.test - _save_outputs: Command (pid 360810) rc: 0
2025-11-10 09:37:55,255 - DEBUG - ya.test - _save_outputs: Command (pid 360810) elapsed time (sec): 0.4784433841705322
2025-11-10 09:37:55,255 - DEBUG - ya.test - _save_outputs: Command (pid 360810) idrss: 0
2025-11-10 09:37:55,255 - DEBUG - ya.test - _save_outputs: Command (pid 360810) inblock: 0
2025-11-10 09:37:55,255 - DEBUG - ya.test - _save_outputs: Command (pid 360810) isrss: 0
2025-11-10 09:37:55,255 - DEBUG - ya.test - _save_outputs: Command (pid 360810) ixrss: 0
2025-11-10 09:37:55,256 - DEBUG - ya.test - _save_outputs: Command (pid 360810) majflt: 0
2025-11-10 09:37:55,256 - DEBUG - ya.test - _save_outputs: Command (pid 360810) maxrss: 549280
2025-11-10 09:37:55,256 - DEBUG - ya.test - _save_outputs: Command (pid 360810) minflt: 18315
2025-11-10 09:37:55,256 - DEBUG - ya.test - _save_outputs: Command (pid 360810) msgrcv: 0
2025-11-10 09:37:55,257 - DEBUG - ya.test - _save_outputs: Command (pid 360810) msgsnd: 0
2025-11-10 09:37:55,257 - DEBUG - ya.test - _save_outputs: Command (pid 360810) nivcsw: 31
2025-11-10 09:37:55,258 - DEBUG - ya.test - _save_outputs: Command (pid 360810) nsignals: 0
2025-11-10 09:37:55,258 - DEBUG - ya.test - _save_outputs: Command (pid 360810) nswap: 0
2025-11-10 09:37:55,258 - DEBUG - ya.test - _save_outputs: Command (pid 360810) nvcsw: 121
2025-11-10 09:37:55,258 - DEBUG - ya.test - _save_outputs: Command (pid 360810) oublock: 8
2025-11-10 09:37:55,258 - DEBUG - ya.test - _save_outputs: Command (pid 360810) stime: 0.275463
2025-11-10 09:37:55,258 - DEBUG - ya.test - _save_outputs: Command (pid 360810) utime: 0.151704
2025-11-10 09:37:55,258 - DEBUG - ya.test - _save_outputs: Command (pid 360810) wtime: 0.48
2025-11-10 09:37:55,258 - DEBUG - ya.test - _save_outputs: Command (pid 360810) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-10 09:37:55,259 - DEBUG - ya.test - _save_outputs: Command (pid 360810) errors:

2025-11-10 09:37:55,259 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd
2025-11-10 09:37:55,265 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.29
2025-11-10 09:37:55,266 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.29
2025-11-10 09:37:55,266 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/002514/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15397', '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-10 09:37:55,267 - DEBUG - ya.test - execute: Command pid: 361515
2025-11-10 09:37:55,695 - DEBUG - ya.test - _save_outputs: Command (pid 361515) rc: 0
2025-11-10 09:37:55,695 - DEBUG - ya.test - _save_outputs: Command (pid 361515) elapsed time (sec): 0.42649412155151367
2025-11-10 09:37:55,709 - DEBUG - ya.test - _save_outputs: Command (pid 361515) idrss: 0
2025-11-10 09:37:55,710 - DEBUG - ya.test - _save_outputs: Command (pid 361515) inblock: 0
2025-11-10 09:37:55,715 - DEBUG - ya.test - _save_outputs: Command (pid 361515) isrss: 0
2025-11-10 09:37:55,716 - DEBUG - ya.test - _save_outputs: Command (pid 361515) ixrss: 0
2025-11-10 09:37:55,721 - DEBUG - ya.test - _save_outputs: Command (pid 361515) majflt: 0
2025-11-10 09:37:55,721 - DEBUG - ya.test - _save_outputs: Command (pid 361515) maxrss: 549252
2025-11-10 09:37:55,721 - DEBUG - ya.test - _save_outputs: Command (pid 361515) minflt: 18314
2025-11-10 09:37:55,721 - DEBUG - ya.test - _save_outputs: Command (pid 361515) msgrcv: 0
2025-11-10 09:37:55,722 - DEBUG - ya.test - _save_outputs: Command (pid 361515) msgsnd: 0
2025-11-10 09:37:55,722 - DEBUG - ya.test - _save_outputs: Command (pid 361515) nivcsw: 43
2025-11-10 09:37:55,722 - DEBUG - ya.test - _save_outputs: Command (pid 361515) nsignals: 0
2025-11-10 09:37:55,722 - DEBUG - ya.test - _save_outputs: Command (pid 361515) nswap: 0
2025-11-10 09:37:55,722 - DEBUG - ya.test - _save_outputs: Command (pid 361515) nvcsw: 134
2025-11-10 09:37:55,723 - DEBUG - ya.test - _save_outputs: Command (pid 361515) oublock: 8
2025-11-10 09:37:55,723 - DEBUG - ya.test - _save_outputs: Command (pid 361515) stime: 0.23696899999999999
2025-11-10 09:37:55,723 - DEBUG - ya.test - _save_outputs: Command (pid 361515) utime: 0.13428199999999998
2025-11-10 09:37:55,723 - DEBUG - ya.test - _save_outputs: Command (pid 361515) wtime: 0.428
2025-11-10 09:37:55,723 - DEBUG - ya.test - _save_outputs: Command (pid 361515) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-10 09:37:55,724 - DEBUG - ya.test - _save_outputs: Command (pid 361515) errors:

2025-11-10 09:37:55,724 - 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-10 09:37:55,745 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-10 09:37:55,763 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-10 09:37:55,795 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-10 09:37:55,822 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-10 09:37:55,874 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-10 09:37:55,945 - 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-10 09:37:55,947 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7f2270349910>
2025-11-10 09:37:55,951 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7f227046d0d0>
2025-11-10 09:37:55,952 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7f226f497290>
2025-11-10 09:37:55,953 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7f227046cdd0>
2025-11-10 09:37:55,958 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-10 09:37:55,964 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-10 09:37:55,966 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-10 09:37:55,967 - DEBUG - botocore.utils - register: Registering S3Express Identity Resolver
2025-11-10 09:37:55,969 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:s3
2025-11-10 09:37:55,971 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:Bucket
2025-11-10 09:37:55,995 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming Bucket attribute name
2025-11-10 09:37:56,003 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.Bucket: calling handler <function lazy_call.<locals>._handler at 0x7f226f497d10>
2025-11-10 09:37:56,004 - DEBUG - boto3.resources.action - __call__: Calling s3:create_bucket with {'Bucket': 'fbucket', 'ACL': 'public-read'}
2025-11-10 09:37:56,005 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function validate_bucket_name at 0x7f227034b7d0>
2025-11-10 09:37:56,024 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function remove_bucket_from_url_paths_from_model at 0x7f227037a090>
2025-11-10 09:37:56,025 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,025 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <bound method ClientCreator._inject_s3_input_parameters of <botocore.client.ClientCreator object at 0x7f224616e220>>
2025-11-10 09:37:56,025 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function generate_idempotent_uuid at 0x7f227034b590>
2025-11-10 09:37:56,026 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7f227037a2d0>
2025-11-10 09:37:56,026 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,027 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:17161', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableAccessPoints': True, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True, 'UseS3ExpressControlEndpoint': True}
2025-11-10 09:37:56,029 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:17161/fbucket
2025-11-10 09:37:56,029 - 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-10 09:37:56,029 - 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-10 09:37:56,042 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function add_expect_header at 0x7f227034bc50>
2025-11-10 09:37:56,042 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <bound method S3ExpressIdentityResolver.apply_signing_cache_key of <botocore.utils.S3ExpressIdentityResolver object at 0x7f2253a6d190>>
2025-11-10 09:37:56,042 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function add_recursion_detection_header at 0x7f2270349b50>
2025-11-10 09:37:56,043 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function inject_api_version_header_if_needed at 0x7f22703796d0>
2025-11-10 09:37:56,043 - 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.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89 Resource'}, 'body': b'', 'auth_path': '/fbucket/', 'url': 'http://localhost:17161/fbucket', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7f22461527c0>, 'has_streaming_input': False, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'ACL': 'public-read'}}, 'input_params': {'Bucket': 'fbucket'}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}, 'endpoint_properties': {'authSchemes': [{'disableDoubleEncoding': True, 'name': 'sigv4', 'signingName': 's3', 'signingRegion': 'yandex'}]}}}
2025-11-10 09:37:56,044 - DEBUG - botocore.hooks - _emit: Event request-created.s3.CreateBucket: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7f22461529f0>>
2025-11-10 09:37:56,073 - 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 0x7f224616e220>>
2025-11-10 09:37:56,073 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.CreateBucket: calling handler <function set_operation_specific_signer at 0x7f227034b410>
2025-11-10 09:37:56,074 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.CreateBucket: calling handler <function remove_arn_from_signing_path at 0x7f227037a210>
2025-11-10 09:37:56,074 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.CreateBucket: calling handler <bound method S3ExpressIdentityResolver.resolve_s3express_identity of <botocore.utils.S3ExpressIdentityResolver object at 0x7f2253a6d190>>
2025-11-10 09:37:56,075 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-10 09:37:56,075 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
PUT
/fbucket

host:localhost:17161
x-amz-acl:public-read
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251110T093756Z

host;x-amz-acl;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-10 09:37:56,076 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251110T093756Z
20251110/yandex/s3/aws4_request
523054b5bf89438cd3a14d937a609a6d9ae48171e6278b89b7fb8d2d33c6c356
2025-11-10 09:37:56,076 - DEBUG - botocore.auth - add_auth: Signature:
340c51929afc983ade34535edde68a560c19b03a44e97b57d1fea7fe5ad7d772
2025-11-10 09:37:56,076 - DEBUG - botocore.hooks - _emit: Event request-created.s3.CreateBucket: calling handler <function add_retry_headers at 0x7f2270379fd0>
2025-11-10 09:37:56,078 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=http://localhost:17161/fbucket, headers={'x-amz-acl': b'public-read', 'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89 Resource', 'X-Amz-Date': b'20251110T093756Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251110/yandex/s3/aws4_request, SignedHeaders=host;x-amz-acl;x-amz-content-sha256;x-amz-date, Signature=340c51929afc983ade34535edde68a560c19b03a44e97b57d1fea7fe5ad7d772', 'amz-sdk-invocation-id': b'502cf055-d2f8-459b-a4ef-ada07e8bfd75', 'amz-sdk-request': b'attempt=1', 'Content-Length': '0'}>
2025-11-10 09:37:56,096 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.12', 'Date': 'Mon, 10 Nov 2025 09:37:56 GMT', 'x-amzn-requestid': 'QGTl1MJLb1FK2e3rwzfozpbhkTSIKXe1f5Yt9VfiQZAf4PMnErxT', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '161', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-10 09:37:56,104 - 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-10 09:37:56,105 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.CreateBucket: calling handler <botocore.retryhandler.RetryHandler object at 0x7f2253a6d5f0>
2025-11-10 09:37:56,115 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-10 09:37:56,115 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.CreateBucket: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,116 - DEBUG - boto3.resources.action - __call__: Response: {'ResponseMetadata': {'RequestId': 'QGTl1MJLb1FK2e3rwzfozpbhkTSIKXe1f5Yt9VfiQZAf4PMnErxT', 'HTTPStatusCode': 200, 'HTTPHeaders': {'server': 'Werkzeug/2.2.3 Python/3.12.12', 'date': 'Mon, 10 Nov 2025 09:37:56 GMT', 'x-amzn-requestid': 'QGTl1MJLb1FK2e3rwzfozpbhkTSIKXe1f5Yt9VfiQZAf4PMnErxT', 'content-type': 'text/html; charset=utf-8', 'content-length': '161', 'access-control-allow-origin': '*', 'connection': 'close'}, 'RetryAttempts': 0}}
2025-11-10 09:37:56,116 - DEBUG - boto3.resources.collection - pages: Calling paginated s3:list_objects with {'Bucket': 'fbucket'}
2025-11-10 09:37:56,118 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function set_list_objects_encoding_type_url at 0x7f22703790d0>
2025-11-10 09:37:56,118 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function validate_bucket_name at 0x7f227034b7d0>
2025-11-10 09:37:56,118 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function remove_bucket_from_url_paths_from_model at 0x7f227037a090>
2025-11-10 09:37:56,118 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,119 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <bound method ClientCreator._inject_s3_input_parameters of <botocore.client.ClientCreator object at 0x7f224616e220>>
2025-11-10 09:37:56,119 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function generate_idempotent_uuid at 0x7f227034b590>
2025-11-10 09:37:56,119 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7f227037a2d0>
2025-11-10 09:37:56,120 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,120 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:17161', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-10 09:37:56,121 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:17161/fbucket
2025-11-10 09:37:56,122 - 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-10 09:37:56,122 - 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-10 09:37:56,123 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function add_expect_header at 0x7f227034bc50>
2025-11-10 09:37:56,123 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <bound method S3ExpressIdentityResolver.apply_signing_cache_key of <botocore.utils.S3ExpressIdentityResolver object at 0x7f2253a6d190>>
2025-11-10 09:37:56,123 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function add_recursion_detection_header at 0x7f2270349b50>
2025-11-10 09:37:56,124 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function inject_api_version_header_if_needed at 0x7f22703796d0>
2025-11-10 09:37:56,124 - 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.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89 Resource'}, 'body': b'', 'auth_path': '/fbucket/', 'url': 'http://localhost:17161/fbucket?encoding-type=url', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7f22461527c0>, 'has_streaming_input': False, 'auth_type': 'v4', 'encoding_type_auto_set': True, 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'EncodingType': 'url'}}, 'input_params': {'Bucket': 'fbucket'}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}, 'endpoint_properties': {'authSchemes': [{'disableDoubleEncoding': True, 'name': 'sigv4', 'signingName': 's3', 'signingRegion': 'yandex'}]}}}
2025-11-10 09:37:56,125 - DEBUG - botocore.hooks - _emit: Event request-created.s3.ListObjects: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7f22461529f0>>
2025-11-10 09:37:56,126 - 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 0x7f224616e220>>
2025-11-10 09:37:56,126 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.ListObjects: calling handler <function set_operation_specific_signer at 0x7f227034b410>
2025-11-10 09:37:56,126 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.ListObjects: calling handler <function remove_arn_from_signing_path at 0x7f227037a210>
2025-11-10 09:37:56,127 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.ListObjects: calling handler <bound method S3ExpressIdentityResolver.resolve_s3express_identity of <botocore.utils.S3ExpressIdentityResolver object at 0x7f2253a6d190>>
2025-11-10 09:37:56,128 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-10 09:37:56,128 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
GET
/fbucket
encoding-type=url
host:localhost:17161
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251110T093756Z

host;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-10 09:37:56,128 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251110T093756Z
20251110/yandex/s3/aws4_request
ffe94fac36a9b460df64a2eab008ccee8f1c5a47913c0044ba74ba72cf288e07
2025-11-10 09:37:56,129 - DEBUG - botocore.auth - add_auth: Signature:
0fa79131a6ededfee5080a729be06dd778f32c4ff9819d4b3a22925689e852a4
2025-11-10 09:37:56,129 - DEBUG - botocore.hooks - _emit: Event request-created.s3.ListObjects: calling handler <function add_retry_headers at 0x7f2270379fd0>
2025-11-10 09:37:56,129 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=GET, url=http://localhost:17161/fbucket?encoding-type=url, headers={'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89 Resource', 'X-Amz-Date': b'20251110T093756Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251110/yandex/s3/aws4_request, SignedHeaders=host;x-amz-content-sha256;x-amz-date, Signature=0fa79131a6ededfee5080a729be06dd778f32c4ff9819d4b3a22925689e852a4', 'amz-sdk-invocation-id': b'd62bffe3-d448-4be1-8577-66704f277629', 'amz-sdk-request': b'attempt=1'}>
2025-11-10 09:37:56,155 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.12', 'Date': 'Mon, 10 Nov 2025 09:37:56 GMT', 'x-amzn-requestid': 'PitMVApAqyQPntB8FBKbELXC9kNq6HBptTk3eLj3d3yp9KQQm0oT', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '554', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-10 09:37:56,156 - 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-10T09:35:19.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-10 09:37:56,159 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.ListObjects: calling handler <botocore.retryhandler.RetryHandler object at 0x7f2253a6d5f0>
2025-11-10 09:37:56,164 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-10 09:37:56,164 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.ListObjects: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,165 - DEBUG - botocore.hooks - _emit: Event after-call.s3.ListObjects: calling handler <function decode_list_object at 0x7f2270379190>
2025-11-10 09:37:56,165 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:ObjectSummary
2025-11-10 09:37:56,166 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming ObjectSummary attribute key
2025-11-10 09:37:56,167 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.ObjectSummary: calling handler <function lazy_call.<locals>._handler at 0x7f226f497e90>
2025-11-10 09:37:56,173 - DEBUG - boto3.resources.action - __call__: Calling s3:delete_objects with {'Bucket': 'fbucket', 'Delete': {'Objects': [{'Key': 'fruits.csv'}]}}
2025-11-10 09:37:56,174 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function validate_bucket_name at 0x7f227034b7d0>
2025-11-10 09:37:56,174 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function remove_bucket_from_url_paths_from_model at 0x7f227037a090>
2025-11-10 09:37:56,175 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,175 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <bound method ClientCreator._inject_s3_input_parameters of <botocore.client.ClientCreator object at 0x7f224616e220>>
2025-11-10 09:37:56,175 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function generate_idempotent_uuid at 0x7f227034b590>
2025-11-10 09:37:56,175 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7f227037a2d0>
2025-11-10 09:37:56,175 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,176 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:17161', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-10 09:37:56,176 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:17161/fbucket
2025-11-10 09:37:56,176 - 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-10 09:37:56,176 - 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-10 09:37:56,178 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function escape_xml_payload at 0x7f227034b1d0>
2025-11-10 09:37:56,178 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function conditionally_calculate_checksum at 0x7f227057b410>
2025-11-10 09:37:56,178 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function add_expect_header at 0x7f227034bc50>
2025-11-10 09:37:56,178 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <bound method S3ExpressIdentityResolver.apply_signing_cache_key of <botocore.utils.S3ExpressIdentityResolver object at 0x7f2253a6d190>>
2025-11-10 09:37:56,179 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function add_recursion_detection_header at 0x7f2270349b50>
2025-11-10 09:37:56,179 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function inject_api_version_header_if_needed at 0x7f22703796d0>
2025-11-10 09:37:56,179 - 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.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89 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:17161/fbucket?delete', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7f22461527c0>, 'has_streaming_input': False, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'Delete': {'Objects': [{'Key': 'fruits.csv'}]}}}, 'input_params': {'Bucket': 'fbucket', 'Delete': {'Objects': [{'Key': 'fruits.csv'}]}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}, 'endpoint_properties': {'authSchemes': [{'disableDoubleEncoding': True, 'name': 'sigv4', 'signingName': 's3', 'signingRegion': 'yandex'}]}, 'checksum': {'request_algorithm': 'conditional-md5'}}}
2025-11-10 09:37:56,180 - DEBUG - botocore.hooks - _emit: Event request-created.s3.DeleteObjects: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7f22461529f0>>
2025-11-10 09:37:56,204 - 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 0x7f224616e220>>
2025-11-10 09:37:56,204 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.DeleteObjects: calling handler <function set_operation_specific_signer at 0x7f227034b410>
2025-11-10 09:37:56,204 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.DeleteObjects: calling handler <function remove_arn_from_signing_path at 0x7f227037a210>
2025-11-10 09:37:56,207 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.DeleteObjects: calling handler <bound method S3ExpressIdentityResolver.resolve_s3express_identity of <botocore.utils.S3ExpressIdentityResolver object at 0x7f2253a6d190>>
2025-11-10 09:37:56,208 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-10 09:37:56,208 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/fbucket
delete=
content-md5:qhYwzsEvymlDQ0qxfrex2g==
host:localhost:17161
x-amz-content-sha256:c1e075aa57263477fc032bb83fe20baad49e1b1d446bac947bae3aca35a1d621
x-amz-date:20251110T093756Z

content-md5;host;x-amz-content-sha256;x-amz-date
c1e075aa57263477fc032bb83fe20baad49e1b1d446bac947bae3aca35a1d621
2025-11-10 09:37:56,208 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251110T093756Z
20251110/yandex/s3/aws4_request
34dfdf3f386f98a54b00b8edc8e17a3fc3bb21ea546e117b2dbf69b491aff2a0
2025-11-10 09:37:56,209 - DEBUG - botocore.auth - add_auth: Signature:
ded1cbb6b6c91213356f81383ae867b2a34d0bb124ae4d4b711737e9d5c06a86
2025-11-10 09:37:56,209 - DEBUG - botocore.hooks - _emit: Event request-created.s3.DeleteObjects: calling handler <function add_retry_headers at 0x7f2270379fd0>
2025-11-10 09:37:56,209 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:17161/fbucket?delete, headers={'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89 Resource', 'Content-MD5': b'qhYwzsEvymlDQ0qxfrex2g==', 'X-Amz-Date': b'20251110T093756Z', 'X-Amz-Content-SHA256': b'c1e075aa57263477fc032bb83fe20baad49e1b1d446bac947bae3aca35a1d621', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251110/yandex/s3/aws4_request, SignedHeaders=content-md5;host;x-amz-content-sha256;x-amz-date, Signature=ded1cbb6b6c91213356f81383ae867b2a34d0bb124ae4d4b711737e9d5c06a86', 'amz-sdk-invocation-id': b'48750511-ab7f-4f60-8f57-0451d8583468', 'amz-sdk-request': b'attempt=1', 'Content-Length': '103'}>
2025-11-10 09:37:56,243 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.12', 'Date': 'Mon, 10 Nov 2025 09:37:56 GMT', 'x-amzn-requestid': 'VydHAlhcZyS32vG14NWhaqEazFBmkCUvNPiwspZAEkwoZU9gumsE', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '154', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-10 09:37:56,243 - 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-10 09:37:56,245 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.DeleteObjects: calling handler <botocore.retryhandler.RetryHandler object at 0x7f2253a6d5f0>
2025-11-10 09:37:56,245 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-10 09:37:56,245 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.DeleteObjects: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7f2253a6eb30>>
2025-11-10 09:37:56,246 - DEBUG - boto3.resources.action - __call__: Response: {'ResponseMetadata': {'RequestId': 'VydHAlhcZyS32vG14NWhaqEazFBmkCUvNPiwspZAEkwoZU9gumsE', 'HTTPStatusCode': 200, 'HTTPHeaders': {'server': 'Werkzeug/2.2.3 Python/3.12.12', 'date': 'Mon, 10 Nov 2025 09:37:56 GMT', 'x-amzn-requestid': 'VydHAlhcZyS32vG14NWhaqEazFBmkCUvNPiwspZAEkwoZU9gumsE', 'content-type': 'text/html; charset=utf-8', 'content-length': '154', 'access-control-allow-origin': '*', 'connection': 'close'}, 'RetryAttempts': 0}, 'Deleted': [{'Key': 'fruits.csv'}]}
2025-11-10 09:37:56,266 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7f2270349910>
2025-11-10 09:37:56,276 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7f227046d0d0>
2025-11-10 09:37:56,303 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7f226f497290>
2025-11-10 09:37:56,314 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7f227046cdd0>
2025-11-10 09:37:56,330 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-10 09:37:56,338 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-10 09:37:56,345 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-10 09:37:56,347 - DEBUG - botocore.utils - register: Registering S3Express Identity Resolver
2025-11-10 09:37:56,351 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function validate_ascii_metadata at 0x7f2270378890>
2025-11-10 09:37:56,352 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function sse_md5 at 0x7f227034b890>
2025-11-10 09:37:56,352 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function convert_body_to_file_like_object at 0x7f2270379550>
2025-11-10 09:37:56,352 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function validate_bucket_name at 0x7f227034b7d0>
2025-11-10 09:37:56,353 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function remove_bucket_from_url_paths_from_model at 0x7f227037a090>
2025-11-10 09:37:56,353 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7f226f747990>>
2025-11-10 09:37:56,353 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <bound method ClientCreator._inject_s3_input_parameters of <botocore.client.ClientCreator object at 0x7f225376ad10>>
2025-11-10 09:37:56,353 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function generate_idempotent_uuid at 0x7f227034b590>
2025-11-10 09:37:56,354 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7f227037a2d0>
2025-11-10 09:37:56,354 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7f226f747990>>
2025-11-10 09:37:56,355 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:17161', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'Key': 'fruits.csv', 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-10 09:37:56,356 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:17161/fbucket
2025-11-10 09:37:56,380 - 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-10 09:37:56,391 - 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-10 09:37:56,393 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function conditionally_calculate_checksum at 0x7f227057b410>
2025-11-10 09:37:56,414 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function add_expect_header at 0x7f227034bc50>
2025-11-10 09:37:56,415 - DEBUG - botocore.handlers - add_expect_header: Adding expect 100 continue header to request.
2025-11-10 09:37:56,426 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <bound method S3ExpressIdentityResolver.apply_signing_cache_key of <botocore.utils.S3ExpressIdentityResolver object at 0x7f2253791870>>
2025-11-10 09:37:56,427 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function add_recursion_detection_header at 0x7f2270349b50>
2025-11-10 09:37:56,427 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function inject_api_version_header_if_needed at 0x7f22703796d0>
2025-11-10 09:37:56,447 - 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.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89', 'Content-MD5': 'syluNHiGATUP+5/OfJsmcg==', 'Expect': '100-continue'}, 'body': <_io.BytesIO object at 0x7f2248039a90>, 'auth_path': '/fbucket/fruits.csv', 'url': 'http://localhost:17161/fbucket/fruits.csv', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7f2253df4060>, 'has_streaming_input': True, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Body': <_io.BytesIO object at 0x7f2248039a90>, 'Bucket': 'fbucket', 'Key': 'fruits.csv', 'ContentType': 'text/plain'}}, 'input_params': {'Bucket': 'fbucket', 'Key': 'fruits.csv'}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}, 'endpoint_properties': {'authSchemes': [{'disableDoubleEncoding': True, 'name': 'sigv4', 'signingName': 's3', 'signingRegion': 'yandex'}]}}}
2025-11-10 09:37:56,448 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7f2253df4ba0>>
2025-11-10 09:37:56,450 - 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 0x7f225376ad10>>
2025-11-10 09:37:56,450 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.PutObject: calling handler <function set_operation_specific_signer at 0x7f227034b410>
2025-11-10 09:37:56,450 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.PutObject: calling handler <function remove_arn_from_signing_path at 0x7f227037a210>
2025-11-10 09:37:56,451 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.PutObject: calling handler <bound method S3ExpressIdentityResolver.resolve_s3express_identity of <botocore.utils.S3ExpressIdentityResolver object at 0x7f2253791870>>
2025-11-10 09:37:56,452 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-10 09:37:56,452 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
PUT
/fbucket/fruits.csv

content-md5:syluNHiGATUP+5/OfJsmcg==
content-type:text/plain
host:localhost:17161
x-amz-content-sha256:be1c1c4a83c407c74f1385ef6ac12822772856cf51582ff70769720688989a00
x-amz-date:20251110T093756Z

content-md5;content-type;host;x-amz-content-sha256;x-amz-date
be1c1c4a83c407c74f1385ef6ac12822772856cf51582ff70769720688989a00
2025-11-10 09:37:56,452 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251110T093756Z
20251110/yandex/s3/aws4_request
9c150f095d17fc961aa8ed4acb75a0bd7978afaa0b9df8d308ca2fc820a1c8d0
2025-11-10 09:37:56,452 - DEBUG - botocore.auth - add_auth: Signature:
611cf2861871b5d21513f47d0b45bfc2038e61453d8f1fc3094159c767f22301
2025-11-10 09:37:56,453 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <function add_retry_headers at 0x7f2270379fd0>
2025-11-10 09:37:56,453 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=http://localhost:17161/fbucket/fruits.csv, headers={'Content-Type': b'text/plain', 'User-Agent': b'Boto3/1.34.89 md/Botocore#1.34.89 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.12 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.34.89', 'Content-MD5': b'syluNHiGATUP+5/OfJsmcg==', 'Expect': b'100-continue', 'X-Amz-Date': b'20251110T093756Z', 'X-Amz-Content-SHA256': b'be1c1c4a83c407c74f1385ef6ac12822772856cf51582ff70769720688989a00', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251110/yandex/s3/aws4_request, SignedHeaders=content-md5;content-type;host;x-amz-content-sha256;x-amz-date, Signature=611cf2861871b5d21513f47d0b45bfc2038e61453d8f1fc3094159c767f22301', 'amz-sdk-invocation-id': b'7d18eef9-41ca-42f6-8425-8f359f2c76fd', 'amz-sdk-request': b'attempt=1', 'Content-Length': '53'}>
2025-11-10 09:37:56,461 - DEBUG - botocore.awsrequest - _send_output: Waiting for 100 Continue response.
2025-11-10 09:37:56,479 - DEBUG - botocore.awsrequest - _handle_expect_response: 100 Continue response seen, now sending request body.
2025-11-10 09:37:56,510 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.12', 'Date': 'Mon, 10 Nov 2025 09:37:56 GMT', 'ETag': '"b3296e34788601350ffb9fce7c9b2672"', 'last-modified': 'Mon, 10 Nov 2025 09:37:56 GMT', 'x-amzn-requestid': '98qAn1Pm2rMVjxVvoJCU2LO0Ig1l6LfTmKkfx4C2O92yM96Eyljl', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '0', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-10 09:37:56,511 - DEBUG - botocore.parsers - parse: Response body:
b''
2025-11-10 09:37:56,512 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.PutObject: calling handler <botocore.retryhandler.RetryHandler object at 0x7f22537912d0>
2025-11-10 09:37:56,513 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-10 09:37:56,513 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7f226f747990>>
2025-11-10 09:37:56,513 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-10 09:37:56,545 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-10 09:37:57,192 - DEBUG - root - create_query_impl: Request: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "\n            SELECT *\n            FROM `h8377671970929237663_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: "98c75d85-bbdb-46c6-9db8-16668c696643"

2025-11-10 09:37:57,362 - DEBUG - root - create_query_impl: Result: query_id: "ptqudsomrb5prf49okl7"

2025-11-10 09:37:57,448 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsomrb5prf49okl7"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1762767477
        nanos: 217351000
      }
      modified_at {
        seconds: 1762767477
        nanos: 217351000
      }
      revision: 1
    }
    execute_mode: RUN
    status: STARTING
    last_job_query_revision: 1
    last_job_id: "ptjudsomrb5pqkfno665-ptqudsomrb5prf49okl7"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1762767477
      nanos: 217351000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM `h8377671970929237663_test_double_optional_types_validationfruitbucket`.`fruits.csv`\n            WITH (format=\'csv_with_names\', SCHEMA (\n                Name Int32??,\n            ));\n            "
    syntax: YQL_V1
  }
}

2025-11-10 09:37:57,955 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-10 09:37:58,038 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 09:37:58,041 - 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'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 09:37:58,048 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database local
2025-11-10 09:37:58,050 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 176ed292-9286-49e4-a470-93c871af1399, localhost:12880): creating call state
2025-11-10 09:37:58,050 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 176ed292-9286-49e4-a470-93c871af1399, localhost:12880): request = { database: "local" }
2025-11-10 09:37:58,054 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 176ed292-9286-49e4-a470-93c871af1399, localhost:12880): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kipqlopw2q.auto.internal" port: 12880 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-10 09:37:58,054 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database local: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-kipqlopw2q.auto.internal:12880, location 1, ssl: False>]>
2025-11-10 09:37:58,055 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:12880
2025-11-10 09:37:58,463 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsomrb5prf49okl7"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1762767477
        nanos: 217351000
      }
      modified_at {
        seconds: 1762767477
        nanos: 217351000
      }
      revision: 1
    }
    started_at {
      seconds: 1762767477
      nanos: 792000000
    }
    execute_mode: RUN
    status: RUNNING
    last_job_query_revision: 1
    last_job_id: "ptjudsomrb5pqkfno665-ptqudsomrb5prf49okl7"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1762767477
      nanos: 217351000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM `h8377671970929237663_test_double_optional_types_validationfruitbucket`.`fruits.csv`\n            WITH (format=\'csv_with_names\', SCHEMA (\n                Name Int32??,\n            ));\n            "
    syntax: YQL_V1
  }
  plan {
    json: "{}"
  }
  statistics {
    json: "{}"
  }
  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  <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_ingress\'>\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_egress\'>\n    <path d=\'M13 9H10V16H6V9L3 9V8L8 3L13 8V9Z\' />\n    <path d=\'M14 2H2V0H14V2Z\' />\n  </g>\n\n  <g id=\'icon_input\'>\n    <path d=\"M456.49,264.459L295.206,103.183l56.572-56.572c1.084-1.08,1.456-2.696,0.948-4.14c-0.504-1.448-1.792-2.48-3.316-2.656\n        L4.654,0.027c-1.22-0.144-2.428,0.284-3.292,1.148c-0.864,0.86-1.284,2.068-1.144,3.28L40.01,349.208\n        c0.176,1.52,1.208,2.812,2.656,3.316c1.448,0.508,3.068,0.14,4.144-0.948l56.18-56.18L264.274,456.68\n        c0.748,0.748,1.764,1.172,2.828,1.172c1.06,0,2.076-0.424,2.828-1.172l186.556-186.568\n        C458.05,268.555,458.05,266.027,456.49,264.459z\"/>\n  </g>\n\n  <g id=\'icon_output\'>\n\t<path d=\"M456.494,1.168c-0.864-0.86-2.064-1.284-3.288-1.14l-344.76,39.78c-1.524,0.18-2.812,1.216-3.32,2.656\n        c-0.504,1.456-0.132,3.068,0.948,4.148l56.572,56.576L1.362,264.464c-1.564,1.564-1.564,4.092,0,5.656l186.56,186.556\n        c0.78,0.784,1.804,1.172,2.828,1.172c1.024,0,2.048-0.388,2.828-1.172l161.28-161.28l56.18,56.18\n        c1.084,1.088,2.7,1.452,4.144,0.952c1.448-0.504,2.48-1.796,2.656-3.32L457.63,4.456C457.774,3.248,457.354,2.036,456.494,1.168z\"/>\n  </g>\n\n</defs>\n<style type=\'text/css\'>\n  rect.stage { stroke-width:0; fill:var(--stage-main, #F2F2F2); }\n  rect.clone { stroke-width:0; fill:var(--stage-clone, #D9D9D9); }\n  rect.blocks { stroke-width:0; fill:var(--block-medium, #EACB68); }\n  .texts { text-anchor:start; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .textm { text-anchor:middle; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .texte { text-anchor:end; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  circle.stage { stroke:var(--stage-main, #F2F2F2); stroke-width:1; fill:var(--stage-clone, #D9D9D9); }\n  line.opdiv { stroke-width:1; stroke:var(--stage-grid, #B2B2B2); stroke-dasharray:1,2; }\n  text.clipped { clip-path:url(#clipTextPath); }\n  polygon.conn { stroke-width:0; fill:var(--conn-fill, #BFBFBF); }\n  path.conn { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--conn-fill, #BFBFBF); }\n  path.conn.blocks { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--block-medium, #EACB68); }\n  text.conn { text-anchor:middle; font-family:Verdana; font-size:6px; fill:var(--conn-text, #393939); }\n  rect.background { stroke-width:0; fill:#33FFFF; opacity:0; }\n  g.selected circle.stage { fill:#33FFFF; }\n  g.selected polygon.conn { fill:#33FFFF; }\n  g.selected path.conn { stroke:#33FFFF; fill:#33FFFF; }\n  g.selected rect.stage { fill:#33FFFF; }\n  g.selected rect.clone { fill:#33FFFF; }\n  g.selected rect.background { opacity:1; }\n  g.selected path.blocks { stroke:#33FFFF; }\n</style>\n\n<script type=\"text/ecmascript\">\n<![CDATA[\n    var selectedNode;\n\n    function find_selectable(node) {\n\t\tvar parent = node.parentElement;\n\t\tif (!parent) return;\n\t\tif (parent.classList.contains(\"selectable\")) return parent;\n\t\treturn find_selectable(parent);\n\t}\n\n    window.onload = function() {\n        selectedNode = document.querySelector(\".selected\");\n    }\n\n    window.addEventListener(\"click\", function(e) {\n\t\tvar target = find_selectable(e.target);\n        if (target) {\n            if (selectedNode) {\n                selectedNode.classList.remove(\"selected\");\n            }\n            if (target == selectedNode) {\n                selectedNode = null;\n            } else {\n                selectedNode = target;\n                selectedNode.classList.add(\"selected\");\n            }\n        }\n\t}, false)\n]]>\n</script>\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-10 09:37:58,995 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsomrb5prf49okl7"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1762767477
        nanos: 217351000
      }
      modified_at {
        seconds: 1762767477
        nanos: 217351000
      }
      revision: 1
    }
    started_at {
      seconds: 1762767477
      nanos: 792000000
    }
    execute_mode: RUN
    status: FAILING
    last_job_query_revision: 1
    last_job_id: "ptjudsomrb5pqkfno665-ptqudsomrb5prf49okl7"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1762767477
      nanos: 217351000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM `h8377671970929237663_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-10T09:37:58.899890Z"
    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/h8377671970929237663_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  <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_ingress\'>\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_egress\'>\n    <path d=\'M13 9H10V16H6V9L3 9V8L8 3L13 8V9Z\' />\n    <path d=\'M14 2H2V0H14V2Z\' />\n  </g>\n\n  <g id=\'icon_input\'>\n    <path d=\"M456.49,264.459L295.206,103.183l56.572-56.572c1.084-1.08,1.456-2.696,0.948-4.14c-0.504-1.448-1.792-2.48-3.316-2.656\n        L4.654,0.027c-1.22-0.144-2.428,0.284-3.292,1.148c-0.864,0.86-1.284,2.068-1.144,3.28L40.01,349.208\n        c0.176,1.52,1.208,2.812,2.656,3.316c1.448,0.508,3.068,0.14,4.144-0.948l56.18-56.18L264.274,456.68\n        c0.748,0.748,1.764,1.172,2.828,1.172c1.06,0,2.076-0.424,2.828-1.172l186.556-186.568\n        C458.05,268.555,458.05,266.027,456.49,264.459z\"/>\n  </g>\n\n  <g id=\'icon_output\'>\n\t<path d=\"M456.494,1.168c-0.864-0.86-2.064-1.284-3.288-1.14l-344.76,39.78c-1.524,0.18-2.812,1.216-3.32,2.656\n        c-0.504,1.456-0.132,3.068,0.948,4.148l56.572,56.576L1.362,264.464c-1.564,1.564-1.564,4.092,0,5.656l186.56,186.556\n        c0.78,0.784,1.804,1.172,2.828,1.172c1.024,0,2.048-0.388,2.828-1.172l161.28-161.28l56.18,56.18\n        c1.084,1.088,2.7,1.452,4.144,0.952c1.448-0.504,2.48-1.796,2.656-3.32L457.63,4.456C457.774,3.248,457.354,2.036,456.494,1.168z\"/>\n  </g>\n\n</defs>\n<style type=\'text/css\'>\n  rect.stage { stroke-width:0; fill:var(--stage-main, #F2F2F2); }\n  rect.clone { stroke-width:0; fill:var(--stage-clone, #D9D9D9); }\n  rect.blocks { stroke-width:0; fill:var(--block-medium, #EACB68); }\n  .texts { text-anchor:start; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .textm { text-anchor:middle; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .texte { text-anchor:end; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  circle.stage { stroke:var(--stage-main, #F2F2F2); stroke-width:1; fill:var(--stage-clone, #D9D9D9); }\n  line.opdiv { stroke-width:1; stroke:var(--stage-grid, #B2B2B2); stroke-dasharray:1,2; }\n  text.clipped { clip-path:url(#clipTextPath); }\n  polygon.conn { stroke-width:0; fill:var(--conn-fill, #BFBFBF); }\n  path.conn { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--conn-fill, #BFBFBF); }\n  path.conn.blocks { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--block-medium, #EACB68); }\n  text.conn { text-anchor:middle; font-family:Verdana; font-size:6px; fill:var(--conn-text, #393939); }\n  rect.background { stroke-width:0; fill:#33FFFF; opacity:0; }\n  g.selected circle.stage { fill:#33FFFF; }\n  g.selected polygon.conn { fill:#33FFFF; }\n  g.selected path.conn { stroke:#33FFFF; fill:#33FFFF; }\n  g.selected rect.stage { fill:#33FFFF; }\n  g.selected rect.clone { fill:#33FFFF; }\n  g.selected rect.background { opacity:1; }\n  g.selected path.blocks { stroke:#33FFFF; }\n</style>\n\n<script type=\"text/ecmascript\">\n<![CDATA[\n    var selectedNode;\n\n    function find_selectable(node) {\n\t\tvar parent = node.parentElement;\n\t\tif (!parent) return;\n\t\tif (parent.classList.contains(\"selectable\")) return parent;\n\t\treturn find_selectable(parent);\n\t}\n\n    window.onload = function() {\n        selectedNode = document.querySelector(\".selected\");\n    }\n\n    window.addEventListener(\"click\", function(e) {\n\t\tvar target = find_selectable(e.target);\n        if (target) {\n            if (selectedNode) {\n                selectedNode.classList.remove(\"selected\");\n            }\n            if (target == selectedNode) {\n                selectedNode = null;\n            } else {\n                selectedNode = target;\n                selectedNode.classList.add(\"selected\");\n            }\n        }\n\t}, false)\n]]>\n</script>\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-10 09:37:59,510 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-10 09:38:00,041 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsomrb5prf49okl7"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1762767477
        nanos: 217351000
      }
      modified_at {
        seconds: 1762767477
        nanos: 217351000
      }
      revision: 1
    }
    started_at {
      seconds: 1762767477
      nanos: 792000000
    }
    finished_at {
      seconds: 1762767479
      nanos: 687000000
    }
    execute_mode: RUN
    status: FAILED
    last_job_query_revision: 1
    last_job_id: "ptjudsomrb5pqkfno665-ptqudsomrb5prf49okl7"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1762767477
      nanos: 217351000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            SELECT *\n            FROM `h8377671970929237663_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-10T09:37:58.899890Z"
    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/h8377671970929237663_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  <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_ingress\'>\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_egress\'>\n    <path d=\'M13 9H10V16H6V9L3 9V8L8 3L13 8V9Z\' />\n    <path d=\'M14 2H2V0H14V2Z\' />\n  </g>\n\n  <g id=\'icon_input\'>\n    <path d=\"M456.49,264.459L295.206,103.183l56.572-56.572c1.084-1.08,1.456-2.696,0.948-4.14c-0.504-1.448-1.792-2.48-3.316-2.656\n        L4.654,0.027c-1.22-0.144-2.428,0.284-3.292,1.148c-0.864,0.86-1.284,2.068-1.144,3.28L40.01,349.208\n        c0.176,1.52,1.208,2.812,2.656,3.316c1.448,0.508,3.068,0.14,4.144-0.948l56.18-56.18L264.274,456.68\n        c0.748,0.748,1.764,1.172,2.828,1.172c1.06,0,2.076-0.424,2.828-1.172l186.556-186.568\n        C458.05,268.555,458.05,266.027,456.49,264.459z\"/>\n  </g>\n\n  <g id=\'icon_output\'>\n\t<path d=\"M456.494,1.168c-0.864-0.86-2.064-1.284-3.288-1.14l-344.76,39.78c-1.524,0.18-2.812,1.216-3.32,2.656\n        c-0.504,1.456-0.132,3.068,0.948,4.148l56.572,56.576L1.362,264.464c-1.564,1.564-1.564,4.092,0,5.656l186.56,186.556\n        c0.78,0.784,1.804,1.172,2.828,1.172c1.024,0,2.048-0.388,2.828-1.172l161.28-161.28l56.18,56.18\n        c1.084,1.088,2.7,1.452,4.144,0.952c1.448-0.504,2.48-1.796,2.656-3.32L457.63,4.456C457.774,3.248,457.354,2.036,456.494,1.168z\"/>\n  </g>\n\n</defs>\n<style type=\'text/css\'>\n  rect.stage { stroke-width:0; fill:var(--stage-main, #F2F2F2); }\n  rect.clone { stroke-width:0; fill:var(--stage-clone, #D9D9D9); }\n  rect.blocks { stroke-width:0; fill:var(--block-medium, #EACB68); }\n  .texts { text-anchor:start; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .textm { text-anchor:middle; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .texte { text-anchor:end; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  circle.stage { stroke:var(--stage-main, #F2F2F2); stroke-width:1; fill:var(--stage-clone, #D9D9D9); }\n  line.opdiv { stroke-width:1; stroke:var(--stage-grid, #B2B2B2); stroke-dasharray:1,2; }\n  text.clipped { clip-path:url(#clipTextPath); }\n  polygon.conn { stroke-width:0; fill:var(--conn-fill, #BFBFBF); }\n  path.conn { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--conn-fill, #BFBFBF); }\n  path.conn.blocks { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--block-medium, #EACB68); }\n  text.conn { text-anchor:middle; font-family:Verdana; font-size:6px; fill:var(--conn-text, #393939); }\n  rect.background { stroke-width:0; fill:#33FFFF; opacity:0; }\n  g.selected circle.stage { fill:#33FFFF; }\n  g.selected polygon.conn { fill:#33FFFF; }\n  g.selected path.conn { stroke:#33FFFF; fill:#33FFFF; }\n  g.selected rect.stage { fill:#33FFFF; }\n  g.selected rect.clone { fill:#33FFFF; }\n  g.selected rect.background { opacity:1; }\n  g.selected path.blocks { stroke:#33FFFF; }\n</style>\n\n<script type=\"text/ecmascript\">\n<![CDATA[\n    var selectedNode;\n\n    function find_selectable(node) {\n\t\tvar parent = node.parentElement;\n\t\tif (!parent) return;\n\t\tif (parent.classList.contains(\"selectable\")) return parent;\n\t\treturn find_selectable(parent);\n\t}\n\n    window.onload = function() {\n        selectedNode = document.querySelector(\".selected\");\n    }\n\n    window.addEventListener(\"click\", function(e) {\n\t\tvar target = find_selectable(e.target);\n        if (target) {\n            if (selectedNode) {\n                selectedNode.classList.remove(\"selected\");\n            }\n            if (target == selectedNode) {\n                selectedNode = null;\n            } else {\n                selectedNode = target;\n                selectedNode.classList.add(\"selected\");\n            }\n        }\n\t}, false)\n]]>\n</script>\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-10 09:38:00,048 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-10 09:38:00,049 - DEBUG - root - create_query_impl: Request: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "\n            INSERT INTO `h8377671970929237663_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: "e00b0d76-45d9-4ea9-a2ec-363271e76176"

2025-11-10 09:38:00,064 - DEBUG - root - create_query_impl: Result: query_id: "ptqudsomr8facg89c3v6"

2025-11-10 09:38:00,105 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsomr8facg89c3v6"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1762767480
        nanos: 51269000
      }
      modified_at {
        seconds: 1762767480
        nanos: 51269000
      }
      revision: 1
    }
    execute_mode: RUN
    status: STARTING
    last_job_query_revision: 1
    last_job_id: "ptjudsomr8facb06hg3b-ptqudsomr8facg89c3v6"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1762767480
      nanos: 51269000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            INSERT INTO `h8377671970929237663_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-10 09:38:00,613 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-10 09:38:01,145 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsomr8facg89c3v6"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1762767480
        nanos: 51269000
      }
      modified_at {
        seconds: 1762767480
        nanos: 51269000
      }
      revision: 1
    }
    started_at {
      seconds: 1762767480
      nanos: 684000000
    }
    execute_mode: RUN
    status: FAILING
    last_job_query_revision: 1
    last_job_id: "ptjudsomr8facb06hg3b-ptqudsomr8facg89c3v6"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1762767480
      nanos: 51269000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            INSERT INTO `h8377671970929237663_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-10T09:38:00.760277Z"
    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/h8377671970929237663_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  <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_ingress\'>\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_egress\'>\n    <path d=\'M13 9H10V16H6V9L3 9V8L8 3L13 8V9Z\' />\n    <path d=\'M14 2H2V0H14V2Z\' />\n  </g>\n\n  <g id=\'icon_input\'>\n    <path d=\"M456.49,264.459L295.206,103.183l56.572-56.572c1.084-1.08,1.456-2.696,0.948-4.14c-0.504-1.448-1.792-2.48-3.316-2.656\n        L4.654,0.027c-1.22-0.144-2.428,0.284-3.292,1.148c-0.864,0.86-1.284,2.068-1.144,3.28L40.01,349.208\n        c0.176,1.52,1.208,2.812,2.656,3.316c1.448,0.508,3.068,0.14,4.144-0.948l56.18-56.18L264.274,456.68\n        c0.748,0.748,1.764,1.172,2.828,1.172c1.06,0,2.076-0.424,2.828-1.172l186.556-186.568\n        C458.05,268.555,458.05,266.027,456.49,264.459z\"/>\n  </g>\n\n  <g id=\'icon_output\'>\n\t<path d=\"M456.494,1.168c-0.864-0.86-2.064-1.284-3.288-1.14l-344.76,39.78c-1.524,0.18-2.812,1.216-3.32,2.656\n        c-0.504,1.456-0.132,3.068,0.948,4.148l56.572,56.576L1.362,264.464c-1.564,1.564-1.564,4.092,0,5.656l186.56,186.556\n        c0.78,0.784,1.804,1.172,2.828,1.172c1.024,0,2.048-0.388,2.828-1.172l161.28-161.28l56.18,56.18\n        c1.084,1.088,2.7,1.452,4.144,0.952c1.448-0.504,2.48-1.796,2.656-3.32L457.63,4.456C457.774,3.248,457.354,2.036,456.494,1.168z\"/>\n  </g>\n\n</defs>\n<style type=\'text/css\'>\n  rect.stage { stroke-width:0; fill:var(--stage-main, #F2F2F2); }\n  rect.clone { stroke-width:0; fill:var(--stage-clone, #D9D9D9); }\n  rect.blocks { stroke-width:0; fill:var(--block-medium, #EACB68); }\n  .texts { text-anchor:start; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .textm { text-anchor:middle; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .texte { text-anchor:end; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  circle.stage { stroke:var(--stage-main, #F2F2F2); stroke-width:1; fill:var(--stage-clone, #D9D9D9); }\n  line.opdiv { stroke-width:1; stroke:var(--stage-grid, #B2B2B2); stroke-dasharray:1,2; }\n  text.clipped { clip-path:url(#clipTextPath); }\n  polygon.conn { stroke-width:0; fill:var(--conn-fill, #BFBFBF); }\n  path.conn { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--conn-fill, #BFBFBF); }\n  path.conn.blocks { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--block-medium, #EACB68); }\n  text.conn { text-anchor:middle; font-family:Verdana; font-size:6px; fill:var(--conn-text, #393939); }\n  rect.background { stroke-width:0; fill:#33FFFF; opacity:0; }\n  g.selected circle.stage { fill:#33FFFF; }\n  g.selected polygon.conn { fill:#33FFFF; }\n  g.selected path.conn { stroke:#33FFFF; fill:#33FFFF; }\n  g.selected rect.stage { fill:#33FFFF; }\n  g.selected rect.clone { fill:#33FFFF; }\n  g.selected rect.background { opacity:1; }\n  g.selected path.blocks { stroke:#33FFFF; }\n</style>\n\n<script type=\"text/ecmascript\">\n<![CDATA[\n    var selectedNode;\n\n    function find_selectable(node) {\n\t\tvar parent = node.parentElement;\n\t\tif (!parent) return;\n\t\tif (parent.classList.contains(\"selectable\")) return parent;\n\t\treturn find_selectable(parent);\n\t}\n\n    window.onload = function() {\n        selectedNode = document.querySelector(\".selected\");\n    }\n\n    window.addEventListener(\"click\", function(e) {\n\t\tvar target = find_selectable(e.target);\n        if (target) {\n            if (selectedNode) {\n                selectedNode.classList.remove(\"selected\");\n            }\n            if (target == selectedNode) {\n                selectedNode = null;\n            } else {\n                selectedNode = target;\n                selectedNode.classList.add(\"selected\");\n            }\n        }\n\t}, false)\n]]>\n</script>\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-10 09:38:01,657 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-10 09:38:02,195 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudsomr8facg89c3v6"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1762767480
        nanos: 51269000
      }
      modified_at {
        seconds: 1762767480
        nanos: 51269000
      }
      revision: 1
    }
    started_at {
      seconds: 1762767480
      nanos: 684000000
    }
    finished_at {
      seconds: 1762767481
      nanos: 683000000
    }
    execute_mode: RUN
    status: FAILED
    last_job_query_revision: 1
    last_job_id: "ptjudsomr8facb06hg3b-ptqudsomr8facg89c3v6"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1762767480
      nanos: 51269000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            INSERT INTO `h8377671970929237663_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-10T09:38:00.760277Z"
    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/h8377671970929237663_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  <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_ingress\'>\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_egress\'>\n    <path d=\'M13 9H10V16H6V9L3 9V8L8 3L13 8V9Z\' />\n    <path d=\'M14 2H2V0H14V2Z\' />\n  </g>\n\n  <g id=\'icon_input\'>\n    <path d=\"M456.49,264.459L295.206,103.183l56.572-56.572c1.084-1.08,1.456-2.696,0.948-4.14c-0.504-1.448-1.792-2.48-3.316-2.656\n        L4.654,0.027c-1.22-0.144-2.428,0.284-3.292,1.148c-0.864,0.86-1.284,2.068-1.144,3.28L40.01,349.208\n        c0.176,1.52,1.208,2.812,2.656,3.316c1.448,0.508,3.068,0.14,4.144-0.948l56.18-56.18L264.274,456.68\n        c0.748,0.748,1.764,1.172,2.828,1.172c1.06,0,2.076-0.424,2.828-1.172l186.556-186.568\n        C458.05,268.555,458.05,266.027,456.49,264.459z\"/>\n  </g>\n\n  <g id=\'icon_output\'>\n\t<path d=\"M456.494,1.168c-0.864-0.86-2.064-1.284-3.288-1.14l-344.76,39.78c-1.524,0.18-2.812,1.216-3.32,2.656\n        c-0.504,1.456-0.132,3.068,0.948,4.148l56.572,56.576L1.362,264.464c-1.564,1.564-1.564,4.092,0,5.656l186.56,186.556\n        c0.78,0.784,1.804,1.172,2.828,1.172c1.024,0,2.048-0.388,2.828-1.172l161.28-161.28l56.18,56.18\n        c1.084,1.088,2.7,1.452,4.144,0.952c1.448-0.504,2.48-1.796,2.656-3.32L457.63,4.456C457.774,3.248,457.354,2.036,456.494,1.168z\"/>\n  </g>\n\n</defs>\n<style type=\'text/css\'>\n  rect.stage { stroke-width:0; fill:var(--stage-main, #F2F2F2); }\n  rect.clone { stroke-width:0; fill:var(--stage-clone, #D9D9D9); }\n  rect.blocks { stroke-width:0; fill:var(--block-medium, #EACB68); }\n  .texts { text-anchor:start; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .textm { text-anchor:middle; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  .texte { text-anchor:end; font-family:Verdana; font-size:8px; fill:var(--stage-text, #262626); }\n  circle.stage { stroke:var(--stage-main, #F2F2F2); stroke-width:1; fill:var(--stage-clone, #D9D9D9); }\n  line.opdiv { stroke-width:1; stroke:var(--stage-grid, #B2B2B2); stroke-dasharray:1,2; }\n  text.clipped { clip-path:url(#clipTextPath); }\n  polygon.conn { stroke-width:0; fill:var(--conn-fill, #BFBFBF); }\n  path.conn { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--conn-fill, #BFBFBF); }\n  path.conn.blocks { stroke-width:1; stroke:var(--conn-fill, #BFBFBF); fill:var(--block-medium, #EACB68); }\n  text.conn { text-anchor:middle; font-family:Verdana; font-size:6px; fill:var(--conn-text, #393939); }\n  rect.background { stroke-width:0; fill:#33FFFF; opacity:0; }\n  g.selected circle.stage { fill:#33FFFF; }\n  g.selected polygon.conn { fill:#33FFFF; }\n  g.selected path.conn { stroke:#33FFFF; fill:#33FFFF; }\n  g.selected rect.stage { fill:#33FFFF; }\n  g.selected rect.clone { fill:#33FFFF; }\n  g.selected rect.background { opacity:1; }\n  g.selected path.blocks { stroke:#33FFFF; }\n</style>\n\n<script type=\"text/ecmascript\">\n<![CDATA[\n    var selectedNode;\n\n    function find_selectable(node) {\n\t\tvar parent = node.parentElement;\n\t\tif (!parent) return;\n\t\tif (parent.classList.contains(\"selectable\")) return parent;\n\t\treturn find_selectable(parent);\n\t}\n\n    window.onload = function() {\n        selectedNode = document.querySelector(\".selected\");\n    }\n\n    window.addEventListener(\"click\", function(e) {\n\t\tvar target = find_selectable(e.target);\n        if (target) {\n            if (selectedNode) {\n                selectedNode.classList.remove(\"selected\");\n            }\n            if (target == selectedNode) {\n                selectedNode = null;\n            } else {\n                selectedNode = target;\n                selectedNode.classList.add(\"selected\");\n            }\n        }\n\t}, false)\n]]>\n</script>\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-10 09:38:02,202 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-10 09:38:02,217 - INFO - ya.test - pytest_runtest_teardown: Test teardown
