2025-11-14 10:13:35,973 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 10:13:35,974 - INFO - ya.test - pytest_runtest_setup: test_date_time_simple_format_insert[v2-common/simple_format/test.json-json_each_row]
2025-11-14 10:13:35,974 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 10:13:35,979 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 10:13:36,150 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_format_setting.py::TestS3, test_name: test_date_time_simple_format_insert[v2-common/simple_format/test.json-json_each_row])
2025-11-14 10:13:36,151 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7facac218dd0>
2025-11-14 10:13:36,156 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7facac324ad0>
2025-11-14 10:13:36,157 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7facaab71250>
2025-11-14 10:13:36,157 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7facac3247d0>
2025-11-14 10:13:36,160 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-14 10:13:36,165 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-14 10:13:36,166 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-14 10:13:36,168 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:s3
2025-11-14 10:13:36,171 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:Bucket
2025-11-14 10:13:36,172 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming Bucket attribute name
2025-11-14 10:13:36,174 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.Bucket: calling handler <function lazy_call.<locals>._handler at 0x7facaab71cd0>
2025-11-14 10:13:36,174 - DEBUG - boto3.resources.action - __call__: Calling s3:create_bucket with {'Bucket': 'fbucket', 'ACL': 'public-read'}
2025-11-14 10:13:36,178 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:13:36,179 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:13:36,179 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,180 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.CreateBucket: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:13:36,181 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:13:36,182 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,183 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableAccessPoints': True, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:13:36,185 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:13:36,185 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:13:36,185 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:13:36,186 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:13:36,187 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:13:36,187 - DEBUG - botocore.hooks - _emit: Event before-call.s3.CreateBucket: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:13:36,188 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=CreateBucket) with params: {'url_path': '', 'query_string': {}, 'method': 'PUT', 'headers': {'x-amz-acl': 'public-read', 'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource'}, 'body': b'', 'auth_path': '/fbucket/', 'url': 'http://localhost:20364/fbucket', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7fac8d4ad050>, 'has_streaming_input': False, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'ACL': 'public-read'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:13:36,192 - DEBUG - botocore.hooks - _emit: Event request-created.s3.CreateBucket: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7fac8d4aee00>>
2025-11-14 10:13:36,192 - 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 0x7fac8d5fe590>>
2025-11-14 10:13:36,193 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.CreateBucket: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:13:36,193 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.CreateBucket: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:13:36,195 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:13:36,198 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
PUT
/fbucket

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

host;x-amz-acl;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-14 10:13:36,198 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101336Z
20251114/yandex/s3/aws4_request
3eeea8dd80b2092afa34b7e31b7532b9130b13e912c6bf86bce4a472ff4346c0
2025-11-14 10:13:36,203 - DEBUG - botocore.auth - add_auth: Signature:
0cc9eee431509194e2dfdd555a693d9571c1e4e794e51f90c8904ad29f81eaed
2025-11-14 10:13:36,204 - DEBUG - botocore.hooks - _emit: Event request-created.s3.CreateBucket: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:13:36,204 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=http://localhost:20364/fbucket, headers={'x-amz-acl': b'public-read', 'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource', 'X-Amz-Date': b'20251114T101336Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=host;x-amz-acl;x-amz-content-sha256;x-amz-date, Signature=0cc9eee431509194e2dfdd555a693d9571c1e4e794e51f90c8904ad29f81eaed', 'amz-sdk-invocation-id': b'817b476c-fd64-45d8-a689-07e7feaf6eab', 'amz-sdk-request': b'attempt=1', 'Content-Length': '0'}>
2025-11-14 10:13:36,223 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:13:36 GMT', 'x-amzn-requestid': 'eI1zVVDEPxm9ZEqcX6TAb9vEYcNjc8RZ06uvLPYDnh7A0W3AFcPc', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '161', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:13:36,227 - DEBUG - botocore.parsers - parse: Response body:
b'<CreateBucketResponse xmlns="http://s3.amazonaws.com/doc/2006-03-01"><CreateBucketResponse><Bucket>fbucket</Bucket></CreateBucketResponse></CreateBucketResponse>'
2025-11-14 10:13:36,228 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.CreateBucket: calling handler <botocore.retryhandler.RetryHandler object at 0x7fac8d760880>
2025-11-14 10:13:36,238 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:13:36,249 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.CreateBucket: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,250 - DEBUG - boto3.resources.action - __call__: Response: {'ResponseMetadata': {'RequestId': 'eI1zVVDEPxm9ZEqcX6TAb9vEYcNjc8RZ06uvLPYDnh7A0W3AFcPc', 'HTTPStatusCode': 200, 'HTTPHeaders': {'server': 'Werkzeug/2.2.3 Python/3.12.9', 'date': 'Fri, 14 Nov 2025 10:13:36 GMT', 'x-amzn-requestid': 'eI1zVVDEPxm9ZEqcX6TAb9vEYcNjc8RZ06uvLPYDnh7A0W3AFcPc', 'content-type': 'text/html; charset=utf-8', 'content-length': '161', 'access-control-allow-origin': '*', 'connection': 'close'}, 'RetryAttempts': 0}}
2025-11-14 10:13:36,251 - DEBUG - boto3.resources.collection - pages: Calling paginated s3:list_objects with {'Bucket': 'fbucket'}
2025-11-14 10:13:36,253 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function set_list_objects_encoding_type_url at 0x7facac23c590>
2025-11-14 10:13:36,253 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:13:36,254 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:13:36,254 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,254 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:13:36,255 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:13:36,255 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,255 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:13:36,257 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:13:36,257 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:13:36,257 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:13:36,258 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:13:36,259 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:13:36,259 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:13:36,259 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=ListObjects) with params: {'url_path': '', 'query_string': {'encoding-type': 'url'}, 'method': 'GET', 'headers': {'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource'}, 'body': b'', 'auth_path': '/fbucket/', 'url': 'http://localhost:20364/fbucket?encoding-type=url', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7fac8d4ad050>, 'has_streaming_input': False, 'auth_type': 'v4', 'encoding_type_auto_set': True, 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'EncodingType': 'url'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:13:36,261 - DEBUG - botocore.hooks - _emit: Event request-created.s3.ListObjects: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7fac8d4aee00>>
2025-11-14 10:13:36,262 - 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 0x7fac8d5fe590>>
2025-11-14 10:13:36,262 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.ListObjects: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:13:36,264 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.ListObjects: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:13:36,266 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:13:36,267 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
GET
/fbucket
encoding-type=url
host:localhost:20364
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251114T101336Z

host;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-14 10:13:36,267 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101336Z
20251114/yandex/s3/aws4_request
4cc62cedd42e0c52b7dea60d4b9787b983878fdf8c45e7432b49aac7729a2f2d
2025-11-14 10:13:36,269 - DEBUG - botocore.auth - add_auth: Signature:
12fa286ba192d3d324af4cedd0a1517871a81237f9efd8907d3bd9e923c2a11e
2025-11-14 10:13:36,270 - DEBUG - botocore.hooks - _emit: Event request-created.s3.ListObjects: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:13:36,272 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=GET, url=http://localhost:20364/fbucket?encoding-type=url, headers={'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource', 'X-Amz-Date': b'20251114T101336Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=host;x-amz-content-sha256;x-amz-date, Signature=12fa286ba192d3d324af4cedd0a1517871a81237f9efd8907d3bd9e923c2a11e', 'amz-sdk-invocation-id': b'8dd266fa-7f1b-48c3-bda7-7a63763be85c', 'amz-sdk-request': b'attempt=1'}>
2025-11-14 10:13:36,288 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:13:36 GMT', 'x-amzn-requestid': 'NxZm7pbrwpOWa0gs1RlESzgvsv6w1kMyuVSF2IUsnTxLqeGKPjiI', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '982', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:13:36,289 - 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>common/simple_format/test.tsv</Key><LastModified>2025-11-14T10:13:34.000Z</LastModified><ETag>&#34;5dfe23d100f998bdec629d1974ff770d&#34;</ETag><Size>70</Size><StorageClass>STANDARD</StorageClass><Owner><ID>75aa57f09aa0c8caeab4f8c24e99d10f8e7faeebf76c078efc7c6caea54ba06a</ID><DisplayName>webfile</DisplayName></Owner></Contents><Contents><Key>common/simple_format/tsv_with_names/ptjudseivuga96atf09s_0_E80kPUypZUShj4QixHZ7eyD6cXCKLi6B.tsv</Key><LastModified>2025-11-14T10:13:35.000Z</LastModified><ETag>&#34;67db5f2090a0a3cf03a868ea174eadd9&#34;</ETag><Size>70</Size><StorageClass>STANDARD</StorageClass><Owner><ID>75aa57f09aa0c8caeab4f8c24e99d10f8e7faeebf76c078efc7c6caea54ba06a</ID><DisplayName>webfile</DisplayName></Owner></Contents></ListBucketResult>'
2025-11-14 10:13:36,298 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.ListObjects: calling handler <botocore.retryhandler.RetryHandler object at 0x7fac8d760880>
2025-11-14 10:13:36,299 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:13:36,299 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.ListObjects: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,301 - DEBUG - botocore.hooks - _emit: Event after-call.s3.ListObjects: calling handler <function decode_list_object at 0x7facac23c650>
2025-11-14 10:13:36,302 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:ObjectSummary
2025-11-14 10:13:36,303 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming ObjectSummary attribute key
2025-11-14 10:13:36,313 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.ObjectSummary: calling handler <function lazy_call.<locals>._handler at 0x7facaab71e50>
2025-11-14 10:13:36,314 - DEBUG - boto3.resources.action - __call__: Calling s3:delete_objects with {'Bucket': 'fbucket', 'Delete': {'Objects': [{'Key': 'common/simple_format/test.tsv'}, {'Key': 'common/simple_format/tsv_with_names/ptjudseivuga96atf09s_0_E80kPUypZUShj4QixHZ7eyD6cXCKLi6B.tsv'}]}}
2025-11-14 10:13:36,317 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:13:36,318 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:13:36,319 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,319 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.DeleteObjects: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:13:36,320 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:13:36,321 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,326 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:13:36,327 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:13:36,328 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:13:36,329 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:13:36,332 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function escape_xml_payload at 0x7facac21a690>
2025-11-14 10:13:36,332 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:13:36,332 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:13:36,332 - DEBUG - botocore.hooks - _emit: Event before-call.s3.DeleteObjects: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:13:36,333 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=DeleteObjects) with params: {'url_path': '?delete', 'query_string': {}, 'method': 'POST', 'headers': {'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource', 'Content-MD5': '8CCm27GP1nN6cdeTsNUNUQ=='}, 'body': b'<Delete xmlns="http://s3.amazonaws.com/doc/2006-03-01/"><Object><Key>common/simple_format/test.tsv</Key></Object><Object><Key>common/simple_format/tsv_with_names/ptjudseivuga96atf09s_0_E80kPUypZUShj4QixHZ7eyD6cXCKLi6B.tsv</Key></Object></Delete>', 'auth_path': '/fbucket/', 'url': 'http://localhost:20364/fbucket?delete', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7fac8d4ad050>, 'has_streaming_input': False, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'Delete': {'Objects': [{'Key': 'common/simple_format/test.tsv'}, {'Key': 'common/simple_format/tsv_with_names/ptjudseivuga96atf09s_0_E80kPUypZUShj4QixHZ7eyD6cXCKLi6B.tsv'}]}}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}, 'checksum': {'request_algorithm': 'conditional-md5'}}}
2025-11-14 10:13:36,346 - DEBUG - botocore.hooks - _emit: Event request-created.s3.DeleteObjects: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7fac8d4aee00>>
2025-11-14 10:13:36,347 - 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 0x7fac8d5fe590>>
2025-11-14 10:13:36,347 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.DeleteObjects: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:13:36,348 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.DeleteObjects: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:13:36,349 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:13:36,355 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
POST
/fbucket
delete=
content-md5:8CCm27GP1nN6cdeTsNUNUQ==
host:localhost:20364
x-amz-content-sha256:269eb7a55d67965fc904303a9592d997b6e9af95aef76dfce5ffdeb15bd1b52d
x-amz-date:20251114T101336Z

content-md5;host;x-amz-content-sha256;x-amz-date
269eb7a55d67965fc904303a9592d997b6e9af95aef76dfce5ffdeb15bd1b52d
2025-11-14 10:13:36,355 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101336Z
20251114/yandex/s3/aws4_request
c6a723f38f44bfcd2f5702d675df20c09cfc8d9acb0f16a2639d6fcbf28f2512
2025-11-14 10:13:36,356 - DEBUG - botocore.auth - add_auth: Signature:
de12a1072bc5333485932aa2c3de5c29e5ba049d1a2d97e5ec605bce014111af
2025-11-14 10:13:36,358 - DEBUG - botocore.hooks - _emit: Event request-created.s3.DeleteObjects: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:13:36,358 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=POST, url=http://localhost:20364/fbucket?delete, headers={'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource', 'Content-MD5': b'8CCm27GP1nN6cdeTsNUNUQ==', 'X-Amz-Date': b'20251114T101336Z', 'X-Amz-Content-SHA256': b'269eb7a55d67965fc904303a9592d997b6e9af95aef76dfce5ffdeb15bd1b52d', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=content-md5;host;x-amz-content-sha256;x-amz-date, Signature=de12a1072bc5333485932aa2c3de5c29e5ba049d1a2d97e5ec605bce014111af', 'amz-sdk-invocation-id': b'35b620e0-801e-4035-8204-0465ddfd5c05', 'amz-sdk-request': b'attempt=1', 'Content-Length': '245'}>
2025-11-14 10:13:36,391 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:13:36 GMT', 'x-amzn-requestid': 'oSmM9HGBY9fEELnTgOod5Gd7fRXMb3dEqVv83bUPMa9izMko6pBT', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '298', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:13:36,395 - 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>common/simple_format/test.tsv</Key></Deleted><Deleted><Key>common/simple_format/tsv_with_names/ptjudseivuga96atf09s_0_E80kPUypZUShj4QixHZ7eyD6cXCKLi6B.tsv</Key></Deleted></DeleteResult>'
2025-11-14 10:13:36,396 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.DeleteObjects: calling handler <botocore.retryhandler.RetryHandler object at 0x7fac8d760880>
2025-11-14 10:13:36,399 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:13:36,399 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.DeleteObjects: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d7621d0>>
2025-11-14 10:13:36,401 - DEBUG - boto3.resources.action - __call__: Response: {'ResponseMetadata': {'RequestId': 'oSmM9HGBY9fEELnTgOod5Gd7fRXMb3dEqVv83bUPMa9izMko6pBT', 'HTTPStatusCode': 200, 'HTTPHeaders': {'server': 'Werkzeug/2.2.3 Python/3.12.9', 'date': 'Fri, 14 Nov 2025 10:13:36 GMT', 'x-amzn-requestid': 'oSmM9HGBY9fEELnTgOod5Gd7fRXMb3dEqVv83bUPMa9izMko6pBT', 'content-type': 'text/html; charset=utf-8', 'content-length': '298', 'access-control-allow-origin': '*', 'connection': 'close'}, 'RetryAttempts': 0}, 'Deleted': [{'Key': 'common/simple_format/test.tsv'}, {'Key': 'common/simple_format/tsv_with_names/ptjudseivuga96atf09s_0_E80kPUypZUShj4QixHZ7eyD6cXCKLi6B.tsv'}]}
2025-11-14 10:13:36,404 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7facac218dd0>
2025-11-14 10:13:36,414 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7facac324ad0>
2025-11-14 10:13:36,420 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7facaab71250>
2025-11-14 10:13:36,420 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7facac3247d0>
2025-11-14 10:13:36,424 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-14 10:13:36,432 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-14 10:13:36,433 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-14 10:13:36,435 - DEBUG - s3transfer.utils - acquire: Acquiring 0
2025-11-14 10:13:36,436 - DEBUG - s3transfer.tasks - _wait_until_all_complete: UploadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x7fac8e433120>}) about to wait for the following futures []
2025-11-14 10:13:36,437 - DEBUG - s3transfer.tasks - _wait_until_all_complete: UploadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x7fac8e433120>}) done waiting for dependent futures
2025-11-14 10:13:36,438 - DEBUG - s3transfer.tasks - _execute_main: Executing task UploadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x7fac8e433120>}) with kwargs {'client': <botocore.client.S3 object at 0x7facab174f60>, 'config': <boto3.s3.transfer.TransferConfig object at 0x7fac8d5257d0>, 'osutil': <s3transfer.utils.OSUtils object at 0x7fac8e430830>, 'request_executor': <s3transfer.futures.BoundedExecutor object at 0x7fac8e433670>, 'transfer_future': <s3transfer.futures.TransferFuture object at 0x7fac8e433120>}
2025-11-14 10:13:36,439 - DEBUG - s3transfer.futures - submit: Submitting task PutObjectTask(transfer_id=0, {'bucket': 'fbucket', 'key': 'common/simple_format/test.json', 'extra_args': {}}) to executor <s3transfer.futures.BoundedExecutor object at 0x7fac8e433670> for transfer request: 0.
2025-11-14 10:13:36,441 - DEBUG - s3transfer.utils - acquire: Acquiring 0
2025-11-14 10:13:36,442 - DEBUG - s3transfer.tasks - _wait_until_all_complete: PutObjectTask(transfer_id=0, {'bucket': 'fbucket', 'key': 'common/simple_format/test.json', 'extra_args': {}}) about to wait for the following futures []
2025-11-14 10:13:36,443 - DEBUG - s3transfer.utils - release: Releasing acquire 0/None
2025-11-14 10:13:36,444 - DEBUG - s3transfer.tasks - _wait_until_all_complete: PutObjectTask(transfer_id=0, {'bucket': 'fbucket', 'key': 'common/simple_format/test.json', 'extra_args': {}}) done waiting for dependent futures
2025-11-14 10:13:36,445 - DEBUG - s3transfer.tasks - _execute_main: Executing task PutObjectTask(transfer_id=0, {'bucket': 'fbucket', 'key': 'common/simple_format/test.json', 'extra_args': {}}) with kwargs {'client': <botocore.client.S3 object at 0x7facab174f60>, 'fileobj': <s3transfer.utils.ReadFileChunk object at 0x7fac8e432e00>, 'bucket': 'fbucket', 'key': 'common/simple_format/test.json', 'extra_args': {}}
2025-11-14 10:13:36,447 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function validate_ascii_metadata at 0x7facac21bd10>
2025-11-14 10:13:36,448 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function sse_md5 at 0x7facac21ad50>
2025-11-14 10:13:36,448 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function convert_body_to_file_like_object at 0x7facac23ca10>
2025-11-14 10:13:36,448 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:13:36,448 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:13:36,449 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d411eb0>>
2025-11-14 10:13:36,450 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.PutObject: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:13:36,450 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:13:36,451 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d411eb0>>
2025-11-14 10:13:36,451 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:13:36,453 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:13:36,454 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:13:36,454 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:13:36,455 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function conditionally_calculate_md5 at 0x7facac42fc50>
2025-11-14 10:13:36,456 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:13:36,457 - DEBUG - botocore.handlers - add_expect_header: Adding expect 100 continue header to request.
2025-11-14 10:13:36,457 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:13:36,457 - DEBUG - botocore.hooks - _emit: Event before-call.s3.PutObject: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:13:36,458 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=PutObject) with params: {'url_path': '/common/simple_format/test.json', 'query_string': {}, 'method': 'PUT', 'headers': {'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80', 'Content-MD5': 'WjOpzKzOnkp/4X+Ut17gcg==', 'Expect': '100-continue'}, 'body': <s3transfer.utils.ReadFileChunk object at 0x7fac8e432e00>, 'auth_path': '/fbucket/common/simple_format/test.json', 'url': 'http://localhost:20364/fbucket/common/simple_format/test.json', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7fac8d7fd640>, 'has_streaming_input': True, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'Key': 'common/simple_format/test.json', 'Body': <s3transfer.utils.ReadFileChunk object at 0x7fac8e432e00>}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:13:36,460 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <function signal_not_transferring at 0x7facab1e13d0>
2025-11-14 10:13:36,461 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7fac8d7ff580>>
2025-11-14 10:13:36,462 - 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 0x7fac8e425870>>
2025-11-14 10:13:36,462 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.PutObject: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:13:36,463 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.PutObject: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:13:36,464 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:13:36,465 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
PUT
/fbucket/common/simple_format/test.json

content-md5:WjOpzKzOnkp/4X+Ut17gcg==
host:localhost:20364
x-amz-content-sha256:270909b8e898ef13499538fdfcdcf9605247e9c410f0375e6748f4b01bfdd4c6
x-amz-date:20251114T101336Z

content-md5;host;x-amz-content-sha256;x-amz-date
270909b8e898ef13499538fdfcdcf9605247e9c410f0375e6748f4b01bfdd4c6
2025-11-14 10:13:36,465 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101336Z
20251114/yandex/s3/aws4_request
2ac6f59ea3d6f71e83ff7d899508913881034b1b276c1d506c81e9d08f35dbc1
2025-11-14 10:13:36,466 - DEBUG - botocore.auth - add_auth: Signature:
e389bd54c0426945849e32b2eb1620d3ddf34088e272dc745d15ebe04a0c91cc
2025-11-14 10:13:36,467 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <function signal_transferring at 0x7facab1e1490>
2025-11-14 10:13:36,468 - DEBUG - botocore.hooks - _emit: Event request-created.s3.PutObject: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:13:36,469 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=http://localhost:20364/fbucket/common/simple_format/test.json, headers={'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80', 'Content-MD5': b'WjOpzKzOnkp/4X+Ut17gcg==', 'Expect': b'100-continue', 'X-Amz-Date': b'20251114T101336Z', 'X-Amz-Content-SHA256': b'270909b8e898ef13499538fdfcdcf9605247e9c410f0375e6748f4b01bfdd4c6', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=content-md5;host;x-amz-content-sha256;x-amz-date, Signature=e389bd54c0426945849e32b2eb1620d3ddf34088e272dc745d15ebe04a0c91cc', 'amz-sdk-invocation-id': b'87665c8f-da95-44dd-98f3-77fc6de5a126', 'amz-sdk-request': b'attempt=1', 'Content-Length': '185'}>
2025-11-14 10:13:36,471 - DEBUG - botocore.awsrequest - _send_output: Waiting for 100 Continue response.
2025-11-14 10:13:36,473 - DEBUG - botocore.awsrequest - _handle_expect_response: 100 Continue response seen, now sending request body.
2025-11-14 10:13:36,478 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:13:36 GMT', 'ETag': '"5a33a9ccacce9e4a7fe17f94b75ee072"', 'last-modified': 'Fri, 14 Nov 2025 10:13:36 GMT', 'Content-Length': '0', 'x-amzn-requestid': 'BQk2DeQzqp3RVCcT8YQUbZmERMIusRY5Y3s7STFUasuiOkLxQN16', 'Content-Type': 'text/html; charset=utf-8', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:13:36,478 - DEBUG - botocore.parsers - parse: Response body:
b''
2025-11-14 10:13:36,485 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.PutObject: calling handler <botocore.retryhandler.RetryHandler object at 0x7fac8e430f60>
2025-11-14 10:13:36,485 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:13:36,485 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7fac8d411eb0>>
2025-11-14 10:13:36,487 - DEBUG - s3transfer.utils - release: Releasing acquire 0/None
2025-11-14 10:13:36,489 - DEBUG - root - wait_bootstrap: Wait for node 1 to bootstrap
2025-11-14 10:13:36,545 - DEBUG - root - wait_bootstrap: Node 1 has been bootstrapped
2025-11-14 10:13:36,783 - DEBUG - root - create_query_impl: Request: content {
  type: ANALYTICS
  name: "simple"
  acl {
    visibility: PRIVATE
  }
  limits {
    execution_timeout {
    }
  }
  text: "\n            INSERT INTO bindings.`h300656110150876855_test_date_time_simple_format_insertinsert_my_binding`\n            SELECT Unwrap(Time + Interval(\"P1D\")) as Time, Fruit, Price, Weight\n            FROM bindings.`h300656110150876855_test_date_time_simple_format_insertmy_binding`\n            "
  syntax: YQL_V1
}
execute_mode: RUN
idempotency_key: "d03b3b8e-d572-4762-903b-d90fcf371108"

2025-11-14 10:13:36,821 - DEBUG - root - create_query_impl: Result: query_id: "ptqudseivss72jb07p3s"

2025-11-14 10:13:36,839 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudseivss72jb07p3s"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763115216
        nanos: 790431000
      }
      modified_at {
        seconds: 1763115216
        nanos: 790431000
      }
      revision: 1
    }
    execute_mode: RUN
    status: STARTING
    last_job_query_revision: 1
    last_job_id: "ptjudseivss714bvtm9h-ptqudseivss72jb07p3s"
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763115216
      nanos: 790431000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            INSERT INTO bindings.`h300656110150876855_test_date_time_simple_format_insertinsert_my_binding`\n            SELECT Unwrap(Time + Interval(\"P1D\")) as Time, Fruit, Price, Weight\n            FROM bindings.`h300656110150876855_test_date_time_simple_format_insertmy_binding`\n            "
    syntax: YQL_V1
  }
}

2025-11-14 10:13:37,372 - DEBUG - root - describe_query: Result: ... (repeated, see above) ...
2025-11-14 10:13:37,895 - DEBUG - root - describe_query: Result: query {
  meta {
    common {
      id: "ptqudseivss72jb07p3s"
      created_by: "root@builtin"
      modified_by: "root@builtin"
      created_at {
        seconds: 1763115216
        nanos: 790431000
      }
      modified_at {
        seconds: 1763115216
        nanos: 790431000
      }
      revision: 1
    }
    started_at {
      seconds: 1763115217
      nanos: 370000000
    }
    finished_at {
      seconds: 1763115217
      nanos: 637000000
    }
    execute_mode: RUN
    status: COMPLETED
    last_job_query_revision: 1
    last_job_id: "ptjudseivss714bvtm9h-ptqudseivss72jb07p3s"
    result_expire_at {
      seconds: 1763201617
      nanos: 346275000
    }
    started_by: "root@builtin"
    submitted_at {
      seconds: 1763115216
      nanos: 790431000
    }
  }
  content {
    type: ANALYTICS
    name: "simple"
    acl {
      visibility: PRIVATE
    }
    limits {
      execution_timeout {
      }
    }
    text: "\n            INSERT INTO bindings.`h300656110150876855_test_date_time_simple_format_insertinsert_my_binding`\n            SELECT Unwrap(Time + Interval(\"P1D\")) as Time, Fruit, Price, Weight\n            FROM bindings.`h300656110150876855_test_date_time_simple_format_insertmy_binding`\n            "
    syntax: YQL_V1
  }
  plan {
    json: "{\"Plan\":{\"Plans\":[{\"PlanNodeId\":5,\"Plans\":[{\"PlanNodeId\":4,\"Plans\":[{\"PlanNodeId\":3,\"Plans\":[{\"PlanNodeId\":2,\"Plans\":[{\"PlanNodeId\":1,\"Operators\":[{\"E-Size\":\"34.25925926\",\"Format\":\"json_each_row\",\"Name\":\"h300656110150876855_test_date_time_simple_format_insertmy_binding\",\"Inputs\":[],\"Path\":\"common/simple_format/test.json \",\"E-Rows\":\"0.7786195286\",\"ExternalDataSource\":\"h300656110150876855_test_date_time_simple_format_insertfruitbucket\",\"ReadColumns\":[\"Fruit\",\"Price\",\"Time\",\"Weight\"],\"SourceType\":\"s3\",\"E-Cost\":\"185\"}],\"Node Type\":\"Source\"}],\"Node Type\":\"Stage\"}],\"Node Type\":\"Map\",\"PlanNodeType\":\"Connection\"}],\"Node Type\":\"Stage\"}],\"Operators\":[{\"Inputs\":[],\"ExternalDataSource\":\"h300656110150876855_test_date_time_simple_format_insertfruitbucket\",\"Extension\":\".json\",\"Name\":\"Write h300656110150876855_test_date_time_simple_format_insertfruitbucket\",\"SinkType\":\"s3\"}],\"Node Type\":\"Sink\"}],\"Node Type\":\"Query\",\"PlanNodeType\":\"Query\"},\"meta\":{\"version\":\"0.2\",\"type\":\"query\"}}"
  }
  statistics {
    json: "{\"Sink\":{\"Tasks\":{\"sum\":2,\"count\":2,\"avg\":1,\"max\":1,\"min\":1},\"CpuTimeUs\":{\"sum\":\"598us\",\"count\":2,\"avg\":\"299us\",\"max\":\"359us\",\"min\":\"239us\"},\"IngressBytes\":{\"sum\":185,\"count\":1,\"avg\":185,\"max\":185,\"min\":185},\"IngressRows\":{\"sum\":2,\"count\":1,\"avg\":2,\"max\":2,\"min\":2},\"InputBytes\":{\"sum\":33,\"count\":1,\"avg\":33,\"max\":33,\"min\":33},\"InputRows\":{\"sum\":2,\"count\":1,\"avg\":2,\"max\":2,\"min\":2},\"OutputBytes\":{\"sum\":33,\"count\":1,\"avg\":33,\"max\":33,\"min\":33},\"OutputRows\":{\"sum\":2,\"count\":1,\"avg\":2,\"max\":2,\"min\":2},\"EgressBytes\":{\"sum\":122,\"count\":1,\"avg\":122,\"max\":122,\"min\":122},\"EgressRows\":{\"sum\":2,\"count\":1,\"avg\":2,\"max\":2,\"min\":2},\"MaxMemoryUsage\":{\"sum\":211812352,\"count\":2,\"avg\":105906176,\"max\":210763776,\"min\":1048576}}}"
  }
  ast {
    data: "(\n(let $1 \'\"/local/h300656110150876855_test_date_time_simple_format_insertfruitbucket\")\n(let $2 \'\"/local/h300656110150876855_test_date_time_simple_format_insertmy_binding\")\n(let $3 (DataSource \'\"s3\" $1 $2))\n(let $4 \'\"Paths {\\n  Name: \\\"common\\\"\\n  Children {\\n    Name: \\\"simple_format\\\"\\n    Children {\\n      Name: \\\"test.json\\\"\\n      Size: 185\\n      Read: true\\n    }\\n    IsDirectory: true\\n  }\\n  IsDirectory: true\\n}\\n\")\n(let $5 (Bool \'\"true\"))\n(let $6 \'\"cluster:default_/local/h300656110150876855_test_date_time_simple_format_insertfruitbucket\")\n(let $7 (SecureParam $6))\n(let $8 (DataType \'Int32))\n(let $9 (StructType \'(\'\"Fruit\" (DataType \'String)) \'(\'\"Price\" $8) \'(\'\"Time\" (DataType \'Datetime)) \'(\'\"Weight\" $8)))\n(let $10 \'(\'\"data.datetime.format\" \'\"%Y-%m-%d\"))\n(let $11 (S3ParseSettings world \'(\'((String $4) $5 (AsStruct))) $7 \'\"\" \'\"common/simple_format/test.json \" \'\"json_each_row\" $9 (lambda \'($17) $5) \'($10)))\n(let $12 \'(\'(\'\"_logical_id\" \'579) \'(\'\"_id\" \'\"f3a91899-7c11e42c-f403937d-9cf6fec2\") \'(\'\"_wide_channels\" $9)))\n(let $13 (DqPhyStage \'((DqSource $3 $11)) (lambda \'($18) (block \'(\n  (let $19 (DqSourceWideWrap $18 $3 $9 \'(\'(\'format \'\"json_each_row\"))))\n  (let $20 (lambda \'($21 $22 $23 $24) $21 $22 (Unwrap (+ $23 (Interval \'\"86400000000\"))) $24))\n  (return (FromFlow (WideMap $19 $20)))\n))) $12))\n(let $14 (DqCnMap (TDqOutput $13 \'\"0\")))\n(let $15 (S3SinkSettings \'\"common/simple_format/json_each_row/\" \'(\'(\'\"multipart\" \'\"true\")) $7 \'\".json\" $9))\n(let $16 (DqPhyStage \'($14) (lambda \'($25) (block \'(\n  (let $26 \'($10 \'(\'\"data.timestamp.formatname\" \'\"POSIX\") \'(\'\"file_size_limit\" \'\"52428800\")))\n  (return (FromFlow (S3SinkOutput (NarrowMap (ToFlow $25) (lambda \'($27 $28 $29 $30) (AsStruct \'(\'\"Fruit\" $27) \'(\'\"Price\" $28) \'(\'\"Time\" $29) \'(\'\"Weight\" $30)))) \'\"json_each_row\" \'() $26)))\n))) \'(\'(\'\"_logical_id\" \'659) \'(\'\"_id\" \'\"abb3c81b-ca239eae-19d6ccee-5b3a08ff\")) \'((DqSink \'\"0\" (DataSink \'\"s3\" $1) $15))))\n(return (KqpPhysicalQuery \'((KqpPhysicalTx \'($13 $16) \'() \'() \'(\'(\'\"type\" \'\"generic\") \'(\'\"with_effects\")))) \'() \'(\'(\'\"type\" \'\"script\"))))\n)\n"
  }
  timeline {
    svg: "<svg width=\'1280\' height=\'146\' xmlns=\'http://www.w3.org/2000/svg\'>\n<clipPath id=\'clipTextPath\'><rect x=\'0\' y=\'0\' width=\'298\' height=\'146\'/></clipPath>\n\n<defs>\n\n<g id=\'icon_memory\'>\n  <path fill-rule=\'evenodd\' clip-rule=\'evenodd\' d=\'M5 3C3.89543 3 3 3.89543 3 5H1V7H3V9H1V11H3V13H1V15H3V17H1V19H3C3 20.1046 3.89543 21 5 21H9C10.1046 21 11 20.1046 11 19H13C13 20.1046 13.8954 21 15 21H19C20.1046 21 21 20.1046 21 19H23V17H21V15H23V13H21V11H23V9H21V7H23V5H21C21 3.89543 20.1046 3 19 3H15C13.8954 3 13 3.89543 13 5H11C11 3.89543 10.1046 3 9 3H5ZM11 7V9H13V7H11ZM11 11V13H13V11H11ZM11 15V17H13V15H11ZM5 5H9V19H5V5ZM15 5H19V19H15V5Z\' />\n</g>\n\n<g id=\'icon_cpu\'>\n  <path d=\'M13.9802 7.75H10.0102C8.76023 7.75 7.74023 8.76 7.74023 10.02V13.99C7.74023 15.24 8.75023 16.26 10.0102 16.26H13.9802C15.2302 16.26 16.2502 15.25 16.2502 13.99V10.02C16.2502 8.76 15.2402 7.75 13.9802 7.75ZM13.5002 12.98L12.6102 14.53C12.4802 14.76 12.2402 14.88 12.0002 14.88C11.8802 14.88 11.7502 14.85 11.6502 14.79C11.3102 14.6 11.1902 14.17 11.3902 13.83L12.0302 12.72H11.4702C11.0202 12.72 10.6502 12.52 10.4502 12.18C10.2502 11.84 10.2702 11.42 10.5002 11.03L11.3902 9.48C11.5902 9.14 12.0202 9.03 12.3502 9.22C12.6902 9.41 12.8102 9.84 12.6102 10.18L11.9702 11.29H12.5302C12.9802 11.29 13.3502 11.49 13.5502 11.83C13.7502 12.17 13.7302 12.59 13.5002 12.98Z\' />\n  <path d=\'M21.25 12.75C21.67 12.75 22 12.41 22 12C22 11.58 21.67 11.25 21.25 11.25H20V9.05H21.25C21.67 9.05 22 8.72 22 8.3C22 7.89 21.67 7.55 21.25 7.55H19.77C19.29 5.96 18.04 4.71 16.45 4.23V2.75C16.45 2.34 16.11 2 15.7 2C15.29 2 14.95 2.34 14.95 2.75V4H12.75V2.75C12.75 2.34 12.41 2 12 2C11.59 2 11.25 2.34 11.25 2.75V4H9.06V2.75C9.06 2.34 8.72 2 8.31 2C7.89 2 7.56 2.34 7.56 2.75V4.23C5.96 4.71 4.71 5.96 4.23 7.55H2.75C2.34 7.55 2 7.89 2 8.3C2 8.72 2.34 9.05 2.75 9.05H4V11.25H2.75C2.34 11.25 2 11.58 2 12C2 12.41 2.34 12.75 2.75 12.75H4V14.95H2.75C2.34 14.95 2 15.28 2 15.7C2 16.11 2.34 16.45 2.75 16.45H4.23C4.7 18.04 5.96 19.29 7.56 19.77V21.25C7.56 21.66 7.89 22 8.31 22C8.72 22 9.06 21.66 9.06 21.25V20H11.26V21.25C11.26 21.66 11.59 22 12.01 22C12.42 22 12.76 21.66 12.76 21.25V20H14.95V21.25C14.95 21.66 15.29 22 15.7 22C16.11 22 16.45 21.66 16.45 21.25V19.77C18.04 19.29 19.29 18.04 19.77 16.45H21.25C21.67 16.45 22 16.11 22 15.7C22 15.28 21.67 14.95 21.25 14.95H20V12.75H21.25ZM17.26 14.26C17.26 15.91 15.91 17.26 14.26 17.26H9.74C8.09 17.26 6.74 15.91 6.74 14.26V9.74C6.74 8.09 8.09 6.74 9.74 6.74H14.26C15.91 6.74 17.26 8.09 17.26 9.74V14.26Z\' />\n</g>\n\n<g id=\'icon_input\'>\n  <path d=\'M3 6L6 6L6 12L10 12L10 6L13 6V5L8 0L3 5L3 6Z\' />\n  <path d=\'M2 16L14 16V14L2 14V16Z\' />\n</g>\n\n<g id=\'icon_output\'>\n  <path d=\'M13 9H10V16H6V9L3 9V8L8 3L13 8V9Z\' />\n  <path d=\'M14 2H2V0H14V2Z\' />\n</g>\n\n</defs>\n<rect x=\'0\' y=\'27\' width=\'298\' height=\'50\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'301\' y=\'27\' width=\'64\' height=\'50\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'395\' y=\'27\' width=\'200\' height=\'50\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'368\' y=\'27\' width=\'24\' height=\'50\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'598\' y=\'27\' width=\'682\' height=\'50\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<circle cx=\'8\' cy=\'52\' r=\'7\' stroke=\'var(--stage-main, #F2F2F2)\' stroke-width=\'1\' fill=\'var(--stage-clone, #D9D9D9)\' />\n<text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'8\' y=\'56\'>1</text>\n<g><title>Memory 0%, 1.00MB</title>\n<use href=\'#icon_memory\' transform=\'translate(395 29) scale(0.6 0.6)\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <rect x=\'413\' y=\'29\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--mem-light, #AA7785)\'/>  <polygon points=\'413,29 413,29 413,29 413,43 413,43\' stroke=\'none\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n<rect x=\'413\' y=\'32\' width=\'33\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'40\'>1.00MB</text>\n</g>\n<g><title>CPU Usage 60%, 359us</title>\n<use href=\'#icon_cpu\' transform=\'translate(395 45) scale(0.6 0.6)\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <rect x=\'413\' y=\'45\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--cpu-light, #B78C98)\'/>  <polygon points=\'413,45 503,45 593,45 593,59 413,59\' stroke=\'none\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n<rect x=\'413\' y=\'48\' width=\'28\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'56\'>359us</text>\n</g>\n<g><title>Input 100%, 33B, Rows 100%, 2, Width 16B</title>\n<use href=\'#icon_input\' transform=\'translate(395 61) scale(0.9 0.9)\' fill=\'var(--input-medium, #5A8183)\'/>\n  <rect x=\'413\' y=\'61\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--input-light, #7CA3A5)\'/>  <polygon points=\'413,61 503,61 593,61 593,75 413,75\' stroke=\'none\' fill=\'var(--input-medium, #5A8183)\'/>\n<rect x=\'413\' y=\'64\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'72\'>33B</text>\n</g>\n<g><title>Input, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'602\' y=\'61\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--input-medium, #5A8183)\'/>\n</g>\n<rect x=\'0\' y=\'80\' width=\'298\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'301\' y=\'80\' width=\'64\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'395\' y=\'80\' width=\'200\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'368\' y=\'80\' width=\'24\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<rect x=\'598\' y=\'80\' width=\'682\' height=\'66\' stroke-width=\'0\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<circle cx=\'8\' cy=\'113\' r=\'7\' stroke=\'var(--stage-main, #F2F2F2)\' stroke-width=\'1\' fill=\'var(--stage-clone, #D9D9D9)\' />\n<text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'8\' y=\'117\'>0</text>\n<g><title>Output Rows 50%, 2</title>\n  <rect x=\'303\' y=\'86\' width=\'60\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'303,86 333,86 363,86 363,100 303,100\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'303\' y=\'89\' width=\'5\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'303\' y=\'97\'>2</text>\n</g>\n<g><title>Output Rows 50%, 2, Est: 185 Rows: 0 Size: 34B</title>\n  <rect x=\'303\' y=\'106\' width=\'60\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'303,106 333,106 363,106 363,120 303,120\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'303\' y=\'109\' width=\'5\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'303\' y=\'117\'>2</text>\n</g>\n<g><title>Output 100%, 33B, Rows 100%, 2</title>\n<use href=\'#icon_output\' transform=\'translate(395 82) scale(0.9 0.9)\' fill=\'var(--output-medium, #5781B9)\'/>\n  <rect x=\'413\' y=\'82\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-light, #6F93C3)\'/>  <polygon points=\'413,82 503,82 593,82 593,96 413,96\' stroke=\'none\' fill=\'var(--output-medium, #5781B9)\'/>\n<rect x=\'413\' y=\'85\' width=\'16\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'93\'>33B</text>\n</g>\n<g><title>Output, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'602\' y=\'82\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--output-medium, #5781B9)\'/>\n</g>\n<g><title>Memory 99%, 201.00MB</title>\n<use href=\'#icon_memory\' transform=\'translate(395 98) scale(0.6 0.6)\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <rect x=\'413\' y=\'98\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--mem-light, #AA7785)\'/>  <polygon points=\'413,98 503,98 593,98 593,112 413,112\' stroke=\'none\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n<rect x=\'413\' y=\'101\' width=\'44\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'109\'>201.00MB</text>\n</g>\n<g><title>CPU Usage 39%, 239us</title>\n<use href=\'#icon_cpu\' transform=\'translate(395 114) scale(0.6 0.6)\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <rect x=\'413\' y=\'114\' width=\'119\' height=\'14\' stroke-width=\'0\' fill=\'var(--cpu-light, #B78C98)\'/>  <polygon points=\'413,114 472,114 532,114 532,128 413,128\' stroke=\'none\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n<rect x=\'413\' y=\'117\' width=\'28\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'125\'>239us</text>\n</g>\n<g><title>Ingress 100%, 185B, Rows 100%, 2, Width 92B</title>\n<use href=\'#icon_input\' transform=\'translate(395 130) scale(0.9 0.9)\' fill=\'var(--ingress-medium, #466364)\'/>\n  <rect x=\'413\' y=\'130\' width=\'180\' height=\'14\' stroke-width=\'0\' fill=\'var(--ingress-light, #5A8183)\'/>  <polygon points=\'413,130 503,130 593,130 593,144 413,144\' stroke=\'none\' fill=\'var(--ingress-medium, #466364)\'/>\n<rect x=\'413\' y=\'133\' width=\'22\' height=\'9\' stroke-width=\'0\' opacity=\'0.5\' fill=\'var(--stage-main, #F2F2F2)\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-summary, #262626)\' x=\'413\' y=\'141\'>185B</text>\n</g>\n<g><title>Ingress, Duration: 0.00 (0.00 | 0.00 | 0.00 - 0.00 | 0.00 | 0.00)</title><rect x=\'602\' y=\'130\' width=\'0\' height=\'14\' stroke-width=\'0\' fill=\'var(--ingress-medium, #466364)\'/>\n</g>\n\n<line x1=\'600\' y1=\'0\' x2=\'600\' y2=\'146\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'602\' y=\'11\'>0:00</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'0\' y=\'11\'>Sink</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'303\' y=\'11\'>Operators</text>\n<text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'397\' y=\'11\'>Stages</text>\n<text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'11\'>Tasks</text>\n<text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'22\'>2</text>\n<g><title>Ingress 185B, Rows 2, Width 92B, Avg 185B/s</title>\n  <rect x=\'395\' y=\'14\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--ingress-medium, #466364)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'397\' y=\'22\'>185B</text>\n</g>\n<g><title>CPU Usage 598us, Avg 0.00 CPU/s</title>\n  <rect x=\'462\' y=\'14\' width=\'66\' height=\'10\' stroke-width=\'0\' fill=\'var(--cpu-medium, #A36D7B)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'464\' y=\'22\'>598us</text>\n</g>\n<g><title>Memory 202.00MB</title>\n  <rect x=\'530\' y=\'14\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--mem-medium, #7E4E5B)\'/>\n  <text font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-light, #FFFFFF)\' x=\'532\' y=\'22\'>202.00MB</text>\n</g>\n<g><title>Duration: 1.00, Total 1.00</title>\n  <rect x=\'1215\' y=\'3\' width=\'65\' height=\'10\' stroke-width=\'0\' fill=\'var(--stage-grid, #B2B2B2)\'/>\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--text-inv, #FFFFFF)\' x=\'1278\' y=\'11\'>1.00</text>\n</g>\n<g><title>Max CPU 0.30</title>\n<polygon points=\'598,26 598,26 598,26 598,26 598,26 598,26 599,26 599,26 600,26 600,26 600,26 600,26 601,26 601,26 602,26 602,13 602,25 602,26 602,26 603,26 603,26 604,26 604,26 604,26 604,26 605,26 605,26 606,26 606,26 606,26 606,26 607,26 607,18 608,26 608,26 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n<g><title>Stage: </title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'38\'>Stage</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'48\'></text></g>\n<g><title>Stage 1, tasks: 1, finished: 1</title>\n<line x1=\'371\' y1=\'27\' x2=\'371\' y2=\'77\' stroke-width=\'6\' stroke=\'var(--stage-text, #262626)\' stroke-dasharray=\'1,1\' />\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'56\'>1</text>\n</g>\n<g><title>Max MEM 1.00MB</title>\n<polygon points=\'598,42 598,29 608,29 608,42 \' stroke-width=\'1\' stroke=\'var(--mem-medium, #7E4E5B)\' fill=\'var(--mem-medium, #7E4E5B)\' />\n</g>\n<g><title>Max CPU 0.21</title>\n<polygon points=\'598,58 598,58 598,58 598,58 598,58 598,58 599,58 599,58 600,58 600,58 600,58 600,58 601,58 601,58 602,58 602,58 602,58 602,58 602,58 603,58 603,58 604,58 604,58 604,58 604,58 605,58 605,58 606,58 606,58 606,58 606,58 607,58 607,45 608,58 608,58 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n<g><title>Connection: Map</title>\n  <polygon points=\'16,94 0,94 0,80 8,76 16,80\' stroke-width=\'1\' stroke=\'var(--conn-line, #BFBFBF)\' fill=\'var(--conn-fill, #BFBFBF)\'/>\n  <text text-anchor=\'middle\' font-family=\'Verdana\' font-size=\'6px\' fill=\'var(--conn-text, #393939)\' x=\'8\' y=\'90\'>Ma</text>\n</g>\n<g><title>Stage: </title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'91\'>Stage</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'101\'></text></g>\n<line x1=\'18\' y1=\'103\' x2=\'298\' y2=\'103\' stroke-width=\'1\' stroke=\'var(--stage-grid, #B2B2B2)\' stroke-dasharray=\'1,2\'/>\n<g><title>Source s3: h300656110150876855_test_date_time_simple_format_insertmy_binding(Fruit, Price, Time, Weight)</title>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'18\' y=\'111\'>Source s3</text>  <text clip-path=\'url(#clipTextPath)\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'22\' y=\'121\'>h300656110150876855_test_date_time_simple_format_insertmy_binding(Fruit, Price, Time, Weight)</text></g>\n<g><title>Stage 0, tasks: 1, finished: 1</title>\n<line x1=\'371\' y1=\'80\' x2=\'371\' y2=\'146\' stroke-width=\'6\' stroke=\'var(--stage-text, #262626)\' stroke-dasharray=\'1,1\' />\n  <text text-anchor=\'end\' font-family=\'Verdana\' font-size=\'8px\' fill=\'var(--stage-text, #262626)\' x=\'390\' y=\'117\'>1</text>\n</g>\n<g><title>Max MEM 201.00MB</title>\n<polygon points=\'598,111 598,98 602,98 602,111 \' stroke-width=\'1\' stroke=\'var(--mem-medium, #7E4E5B)\' fill=\'var(--mem-medium, #7E4E5B)\' />\n</g>\n<g><title>Max CPU 0.65</title>\n<polygon points=\'598,127 598,127 598,127 598,127 598,127 598,127 598,127 598,127 598,127 598,127 598,127 599,127 599,127 599,127 599,127 600,127 600,127 600,127 600,127 600,127 600,127 600,127 600,127 600,127 601,127 601,127 601,127 601,127 601,127 602,127 602,127 602,127 602,114 602,126 602,127 \' stroke-width=\'1\' stroke=\'var(--cpu-medium, #A36D7B)\' fill=\'var(--cpu-light, #B78C98)\' />\n</g>\n\n</svg>\n"
  }
}

2025-11-14 10:13:37,896 - DEBUG - botocore.hooks - _emit: Event choose-service-name: calling handler <function handle_service_name_alias at 0x7facac218dd0>
2025-11-14 10:13:37,898 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x7facac324ad0>
2025-11-14 10:13:37,899 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function lazy_call.<locals>._handler at 0x7facaab71250>
2025-11-14 10:13:37,899 - DEBUG - botocore.hooks - _emit: Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x7facac3247d0>
2025-11-14 10:13:37,903 - DEBUG - botocore.endpoint - create_endpoint: Setting s3 timeout as (60, 60)
2025-11-14 10:13:37,913 - DEBUG - botocore.client - _register_legacy_retries: Registering retry handlers for service: s3
2025-11-14 10:13:37,914 - DEBUG - botocore.utils - register: Registering S3 region redirector handler
2025-11-14 10:13:37,916 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:s3
2025-11-14 10:13:37,919 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:Bucket
2025-11-14 10:13:37,922 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming Bucket attribute name
2025-11-14 10:13:37,924 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.Bucket: calling handler <function lazy_call.<locals>._handler at 0x7facaab71cd0>
2025-11-14 10:13:37,925 - DEBUG - boto3.resources.collection - pages: Calling paginated s3:list_objects with {'Bucket': 'fbucket'}
2025-11-14 10:13:37,926 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function set_list_objects_encoding_type_url at 0x7facac23c590>
2025-11-14 10:13:37,926 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:13:37,927 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:13:37,927 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:37,927 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.ListObjects: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:13:37,927 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:13:37,928 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:37,928 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:13:37,930 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:13:37,931 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:13:37,932 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:13:37,932 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:13:37,933 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:13:37,933 - DEBUG - botocore.hooks - _emit: Event before-call.s3.ListObjects: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:13:37,933 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=ListObjects) with params: {'url_path': '', 'query_string': {'encoding-type': 'url'}, 'method': 'GET', 'headers': {'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource'}, 'body': b'', 'auth_path': '/fbucket/', 'url': 'http://localhost:20364/fbucket?encoding-type=url', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7facab0931c0>, 'has_streaming_input': False, 'auth_type': 'v4', 'encoding_type_auto_set': True, 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'EncodingType': 'url'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:13:37,934 - DEBUG - botocore.hooks - _emit: Event request-created.s3.ListObjects: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7facab091820>>
2025-11-14 10:13:37,934 - 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 0x7fac8d762bd0>>
2025-11-14 10:13:37,934 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.ListObjects: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:13:37,935 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.ListObjects: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:13:37,935 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:13:37,935 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
GET
/fbucket
encoding-type=url
host:localhost:20364
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251114T101337Z

host;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-14 10:13:37,936 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101337Z
20251114/yandex/s3/aws4_request
5be2e45da77f2a68b0d7a6479bf016024e4a7b1e7a71a8994ee00d8cb55d6519
2025-11-14 10:13:37,936 - DEBUG - botocore.auth - add_auth: Signature:
33507d93a8c7bec7a1787dc339437edbfa10e9c8bab7c1139bd90f0422e9a6da
2025-11-14 10:13:37,936 - DEBUG - botocore.hooks - _emit: Event request-created.s3.ListObjects: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:13:37,936 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=GET, url=http://localhost:20364/fbucket?encoding-type=url, headers={'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource', 'X-Amz-Date': b'20251114T101337Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=host;x-amz-content-sha256;x-amz-date, Signature=33507d93a8c7bec7a1787dc339437edbfa10e9c8bab7c1139bd90f0422e9a6da', 'amz-sdk-invocation-id': b'58b12f3f-3e32-4ce1-bf8d-a77b9608167e', 'amz-sdk-request': b'attempt=1'}>
2025-11-14 10:13:37,945 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:13:37 GMT', 'x-amzn-requestid': 'NmWnwbrbXFuyM525JDsjLwj2aS7u4rqZdjYw0qY0VWEXW2YTLyYm', 'Content-Type': 'text/html; charset=utf-8', 'Content-Length': '985', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:13:37,945 - 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>common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json</Key><LastModified>2025-11-14T10:13:37.000Z</LastModified><ETag>&#34;12d351f710dc1c1177b3b8e30a7ee5ab&#34;</ETag><Size>122</Size><StorageClass>STANDARD</StorageClass><Owner><ID>75aa57f09aa0c8caeab4f8c24e99d10f8e7faeebf76c078efc7c6caea54ba06a</ID><DisplayName>webfile</DisplayName></Owner></Contents><Contents><Key>common/simple_format/test.json</Key><LastModified>2025-11-14T10:13:36.000Z</LastModified><ETag>&#34;5a33a9ccacce9e4a7fe17f94b75ee072&#34;</ETag><Size>185</Size><StorageClass>STANDARD</StorageClass><Owner><ID>75aa57f09aa0c8caeab4f8c24e99d10f8e7faeebf76c078efc7c6caea54ba06a</ID><DisplayName>webfile</DisplayName></Owner></Contents></ListBucketResult>'
2025-11-14 10:13:37,948 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.ListObjects: calling handler <botocore.retryhandler.RetryHandler object at 0x7fac8d9676c0>
2025-11-14 10:13:37,948 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:13:37,948 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.ListObjects: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:37,949 - DEBUG - botocore.hooks - _emit: Event after-call.s3.ListObjects: calling handler <function decode_list_object at 0x7facac23c650>
2025-11-14 10:13:37,949 - DEBUG - boto3.resources.factory - load_from_definition: Loading s3:ObjectSummary
2025-11-14 10:13:37,950 - DEBUG - boto3.resources.model - _load_name_with_category: Renaming ObjectSummary attribute key
2025-11-14 10:13:37,951 - DEBUG - botocore.hooks - _emit: Event creating-resource-class.s3.ObjectSummary: calling handler <function lazy_call.<locals>._handler at 0x7facaab71e50>
2025-11-14 10:13:37,954 - DEBUG - s3transfer.utils - acquire: Acquiring 0
2025-11-14 10:13:37,957 - DEBUG - s3transfer.tasks - _wait_until_all_complete: DownloadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x7fac8da0db90>}) about to wait for the following futures []
2025-11-14 10:13:37,959 - DEBUG - s3transfer.tasks - _wait_until_all_complete: DownloadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x7fac8da0db90>}) done waiting for dependent futures
2025-11-14 10:13:37,960 - DEBUG - s3transfer.tasks - _execute_main: Executing task DownloadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x7fac8da0db90>}) with kwargs {'client': <botocore.client.S3 object at 0x7fac8d210330>, 'config': <boto3.s3.transfer.TransferConfig object at 0x7fac8da0f4e0>, 'osutil': <s3transfer.utils.OSUtils object at 0x7fac8da0eea0>, 'request_executor': <s3transfer.futures.BoundedExecutor object at 0x7fac8da0f300>, 'transfer_future': <s3transfer.futures.TransferFuture object at 0x7fac8da0db90>, 'io_executor': <s3transfer.futures.BoundedExecutor object at 0x7fac8e3f6db0>}
2025-11-14 10:13:37,961 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.HeadObject: calling handler <function sse_md5 at 0x7facac21ad50>
2025-11-14 10:13:37,961 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.HeadObject: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:13:37,961 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.HeadObject: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:13:37,961 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.HeadObject: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:37,962 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.HeadObject: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:13:37,963 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:13:37,963 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:37,963 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:13:37,964 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:13:37,964 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:13:37,965 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:13:37,965 - DEBUG - botocore.hooks - _emit: Event before-call.s3.HeadObject: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:13:37,966 - DEBUG - botocore.hooks - _emit: Event before-call.s3.HeadObject: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:13:37,967 - DEBUG - botocore.hooks - _emit: Event before-call.s3.HeadObject: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:13:37,967 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=HeadObject) with params: {'url_path': '/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'query_string': {}, 'method': 'HEAD', 'headers': {'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource'}, 'body': b'', 'auth_path': '/fbucket/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'url': 'http://localhost:20364/fbucket/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7facab0931c0>, 'has_streaming_input': False, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'Key': 'common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:13:37,969 - DEBUG - botocore.hooks - _emit: Event request-created.s3.HeadObject: calling handler <function signal_not_transferring at 0x7facab1e13d0>
2025-11-14 10:13:37,969 - DEBUG - botocore.hooks - _emit: Event request-created.s3.HeadObject: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7facab091820>>
2025-11-14 10:13:37,970 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.HeadObject: calling handler <bound method ClientCreator._default_s3_presign_to_sigv2 of <botocore.client.ClientCreator object at 0x7fac8d762bd0>>
2025-11-14 10:13:37,973 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.HeadObject: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:13:37,973 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.HeadObject: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:13:37,976 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:13:37,977 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
HEAD
/fbucket/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json

host:localhost:20364
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251114T101337Z

host;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-14 10:13:37,978 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101337Z
20251114/yandex/s3/aws4_request
be480e6b13201b1f33e61a5917bd8851eab6402688cd8854d87d38a506a32145
2025-11-14 10:13:37,978 - DEBUG - botocore.auth - add_auth: Signature:
06a6dbebea12c4fcca87d7877627423d3875ea29825b421b69819ba90e7935f7
2025-11-14 10:13:37,979 - DEBUG - botocore.hooks - _emit: Event request-created.s3.HeadObject: calling handler <function signal_transferring at 0x7facab1e1490>
2025-11-14 10:13:37,979 - DEBUG - botocore.hooks - _emit: Event request-created.s3.HeadObject: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:13:37,980 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=False, method=HEAD, url=http://localhost:20364/fbucket/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json, headers={'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource', 'X-Amz-Date': b'20251114T101337Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=host;x-amz-content-sha256;x-amz-date, Signature=06a6dbebea12c4fcca87d7877627423d3875ea29825b421b69819ba90e7935f7', 'amz-sdk-invocation-id': b'06098b83-4f11-4f43-9cfd-d625ce72f9f5', 'amz-sdk-request': b'attempt=1'}>
2025-11-14 10:13:37,989 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:13:37 GMT', 'content-type': 'binary/octet-stream', 'ETag': '"12d351f710dc1c1177b3b8e30a7ee5ab"', 'last-modified': 'Fri, 14 Nov 2025 10:13:37 GMT', 'Content-Length': '122', 'x-amzn-requestid': 'PBdDRMt1qFJAqiLtMEtDi534MPyldh9Eyn6QZUM5U2R2Pr9DyQYJ', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:13:37,989 - DEBUG - botocore.parsers - parse: Response body:
b''
2025-11-14 10:13:37,991 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.HeadObject: calling handler <botocore.retryhandler.RetryHandler object at 0x7fac8d9676c0>
2025-11-14 10:13:37,992 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:13:37,992 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.HeadObject: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:37,992 - DEBUG - s3transfer.futures - submit: Submitting task ImmediatelyWriteIOGetObjectTask(transfer_id=0, {'bucket': 'fbucket', 'key': 'common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'extra_args': {}}) to executor <s3transfer.futures.BoundedExecutor object at 0x7fac8da0f300> for transfer request: 0.
2025-11-14 10:13:37,993 - DEBUG - s3transfer.utils - acquire: Acquiring 0
2025-11-14 10:13:37,993 - DEBUG - s3transfer.tasks - _wait_until_all_complete: ImmediatelyWriteIOGetObjectTask(transfer_id=0, {'bucket': 'fbucket', 'key': 'common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'extra_args': {}}) about to wait for the following futures []
2025-11-14 10:13:37,994 - DEBUG - s3transfer.utils - release: Releasing acquire 0/None
2025-11-14 10:13:37,994 - DEBUG - s3transfer.tasks - _wait_until_all_complete: ImmediatelyWriteIOGetObjectTask(transfer_id=0, {'bucket': 'fbucket', 'key': 'common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'extra_args': {}}) done waiting for dependent futures
2025-11-14 10:13:37,997 - DEBUG - s3transfer.tasks - _execute_main: Executing task ImmediatelyWriteIOGetObjectTask(transfer_id=0, {'bucket': 'fbucket', 'key': 'common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'extra_args': {}}) with kwargs {'client': <botocore.client.S3 object at 0x7fac8d210330>, 'bucket': 'fbucket', 'key': 'common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'fileobj': <_io.BytesIO object at 0x7fac8e31cde0>, 'extra_args': {}, 'callbacks': [], 'max_attempts': 5, 'download_output_manager': <s3transfer.download.DownloadSeekableOutputManager object at 0x7facab6c2fe0>, 'io_chunksize': 262144, 'bandwidth_limiter': None}
2025-11-14 10:13:37,999 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.GetObject: calling handler <function sse_md5 at 0x7facac21ad50>
2025-11-14 10:13:37,999 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.GetObject: calling handler <function validate_bucket_name at 0x7facac21ac90>
2025-11-14 10:13:38,000 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.GetObject: calling handler <function remove_bucket_from_url_paths_from_model at 0x7facac23d550>
2025-11-14 10:13:38,000 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.GetObject: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:38,000 - DEBUG - botocore.hooks - _emit: Event before-parameter-build.s3.GetObject: calling handler <function generate_idempotent_uuid at 0x7facac21aa50>
2025-11-14 10:13:38,002 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x7facac23d790>
2025-11-14 10:13:38,002 - DEBUG - botocore.hooks - _emit: Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:38,007 - DEBUG - botocore.regions - construct_endpoint: Calling endpoint provider with parameters: {'Bucket': 'fbucket', 'Region': 'yandex', 'UseFIPS': False, 'UseDualStack': False, 'Endpoint': 'http://localhost:20364', 'ForcePathStyle': True, 'Accelerate': False, 'UseGlobalEndpoint': False, 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2025-11-14 10:13:38,008 - DEBUG - botocore.regions - construct_endpoint: Endpoint provider result: http://localhost:20364/fbucket
2025-11-14 10:13:38,009 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2025-11-14 10:13:38,010 - DEBUG - botocore.regions - auth_schemes_to_signing_ctx: Selected auth type "v4" as "v4" with signing context params: {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}
2025-11-14 10:13:38,012 - DEBUG - botocore.hooks - _emit: Event before-call.s3.GetObject: calling handler <function add_expect_header at 0x7facac21b110>
2025-11-14 10:13:38,012 - DEBUG - botocore.hooks - _emit: Event before-call.s3.GetObject: calling handler <function add_recursion_detection_header at 0x7facac219010>
2025-11-14 10:13:38,013 - DEBUG - botocore.hooks - _emit: Event before-call.s3.GetObject: calling handler <function inject_api_version_header_if_needed at 0x7facac23cb90>
2025-11-14 10:13:38,016 - DEBUG - botocore.endpoint - make_request: Making request for OperationModel(name=GetObject) with params: {'url_path': '/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'query_string': {}, 'method': 'GET', 'headers': {'User-Agent': 'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource'}, 'body': b'', 'auth_path': '/fbucket/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'url': 'http://localhost:20364/fbucket/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json', 'context': {'client_region': 'yandex', 'client_config': <botocore.config.Config object at 0x7facab0931c0>, 'has_streaming_input': False, 'auth_type': 'v4', 's3_redirect': {'redirected': False, 'bucket': 'fbucket', 'params': {'Bucket': 'fbucket', 'Key': 'common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json'}}, 'signing': {'region': 'yandex', 'signing_name': 's3', 'disableDoubleEncoding': True}}}
2025-11-14 10:13:38,019 - DEBUG - botocore.hooks - _emit: Event request-created.s3.GetObject: calling handler <function signal_not_transferring at 0x7facab1e13d0>
2025-11-14 10:13:38,019 - DEBUG - botocore.hooks - _emit: Event request-created.s3.GetObject: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x7facab091820>>
2025-11-14 10:13:38,020 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.GetObject: calling handler <bound method ClientCreator._default_s3_presign_to_sigv2 of <botocore.client.ClientCreator object at 0x7fac8d762bd0>>
2025-11-14 10:13:38,020 - DEBUG - botocore.hooks - _emit: Event choose-signer.s3.GetObject: calling handler <function set_operation_specific_signer at 0x7facac21a8d0>
2025-11-14 10:13:38,021 - DEBUG - botocore.hooks - _emit: Event before-sign.s3.GetObject: calling handler <function remove_arn_from_signing_path at 0x7facac23d6d0>
2025-11-14 10:13:38,021 - DEBUG - botocore.auth - add_auth: Calculating signature using v4 auth.
2025-11-14 10:13:38,021 - DEBUG - botocore.auth - add_auth: CanonicalRequest:
GET
/fbucket/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json

host:localhost:20364
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20251114T101338Z

host;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2025-11-14 10:13:38,021 - DEBUG - botocore.auth - add_auth: StringToSign:
AWS4-HMAC-SHA256
20251114T101338Z
20251114/yandex/s3/aws4_request
6881cc9a996ef22589e15f9ef6064954c2847dc723a322c5e661087584e08d73
2025-11-14 10:13:38,022 - DEBUG - botocore.auth - add_auth: Signature:
398dafc5fe0173416b9c6ebbc465630617ec01f47495adfc58443808ec18b151
2025-11-14 10:13:38,022 - DEBUG - botocore.hooks - _emit: Event request-created.s3.GetObject: calling handler <function signal_transferring at 0x7facab1e1490>
2025-11-14 10:13:38,022 - DEBUG - botocore.hooks - _emit: Event request-created.s3.GetObject: calling handler <function add_retry_headers at 0x7facac23d490>
2025-11-14 10:13:38,023 - DEBUG - botocore.endpoint - _do_get_response: Sending http request: <AWSPreparedRequest stream_output=True, method=GET, url=http://localhost:20364/fbucket/common/simple_format/json_each_row/ptjudseivss714bvtm9h_0_sItF-mTGOUSIepd0mdC1o_YzmQpMBbs-.json, headers={'User-Agent': b'Boto3/1.29.6 md/Botocore#1.31.80 ua/2.0 os/linux#5.15.0-157-generic md/arch#x86_64 lang/python#3.12.9 md/pyimpl#CPython cfg/retry-mode#legacy Botocore/1.31.80 Resource', 'X-Amz-Date': b'20251114T101338Z', 'X-Amz-Content-SHA256': b'e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855', 'Authorization': b'AWS4-HMAC-SHA256 Credential=key/20251114/yandex/s3/aws4_request, SignedHeaders=host;x-amz-content-sha256;x-amz-date, Signature=398dafc5fe0173416b9c6ebbc465630617ec01f47495adfc58443808ec18b151', 'amz-sdk-invocation-id': b'7a900118-7f32-4731-9f2f-89c053f68334', 'amz-sdk-request': b'attempt=1'}>
2025-11-14 10:13:38,030 - DEBUG - botocore.parsers - parse: Response headers: {'Server': 'Werkzeug/2.2.3 Python/3.12.9', 'Date': 'Fri, 14 Nov 2025 10:13:38 GMT', 'x-amz-version-id': 'null', 'content-type': 'binary/octet-stream', 'ETag': '"12d351f710dc1c1177b3b8e30a7ee5ab"', 'last-modified': 'Fri, 14 Nov 2025 10:13:37 GMT', 'Content-Length': '122', 'x-amzn-requestid': 'GAGX9d4hKTky1ncdQeSdWKfpbAukoBmV7WGW5SGmBNU6H8IhnxHd', 'Access-Control-Allow-Origin': '*', 'Connection': 'close'}
2025-11-14 10:13:38,031 - DEBUG - botocore.parsers - parse: Response body:
<botocore.response.StreamingBody object at 0x7fac8da5ceb0>
2025-11-14 10:13:38,032 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.GetObject: calling handler <botocore.retryhandler.RetryHandler object at 0x7fac8d9676c0>
2025-11-14 10:13:38,033 - DEBUG - botocore.retryhandler - __call__: No retry needed.
2025-11-14 10:13:38,033 - DEBUG - botocore.hooks - _emit: Event needs-retry.s3.GetObject: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x7facab5fcc90>>
2025-11-14 10:13:38,034 - DEBUG - s3transfer.tasks - _wait_until_all_complete: IOWriteTask(transfer_id=0, {'offset': 0}) about to wait for the following futures []
2025-11-14 10:13:38,035 - DEBUG - s3transfer.tasks - _wait_until_all_complete: IOWriteTask(transfer_id=0, {'offset': 0}) done waiting for dependent futures
2025-11-14 10:13:38,035 - DEBUG - s3transfer.tasks - _execute_main: Executing task IOWriteTask(transfer_id=0, {'offset': 0}) with kwargs {'fileobj': <_io.BytesIO object at 0x7fac8e31cde0>, 'offset': 0}
2025-11-14 10:13:38,035 - DEBUG - s3transfer.tasks - _wait_until_all_complete: CompleteDownloadNOOPTask(transfer_id=0, {}) about to wait for the following futures []
2025-11-14 10:13:38,035 - DEBUG - s3transfer.tasks - _wait_until_all_complete: CompleteDownloadNOOPTask(transfer_id=0, {}) done waiting for dependent futures
2025-11-14 10:13:38,036 - DEBUG - s3transfer.tasks - _execute_main: Executing task CompleteDownloadNOOPTask(transfer_id=0, {}) with kwargs {}
2025-11-14 10:13:38,036 - DEBUG - s3transfer.utils - release: Releasing acquire 0/None
2025-11-14 10:13:38,042 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-14 10:17:41,842 - DEBUG - ya.test - _verify_test: Verifying [test_format_setting.TestS3.test_date_time_simple_format_insert[v2-common/simple_format/test.json-json_each_row] in ydb/tests/fq/s3] canonical results
2025-11-14 10:17:41,843 - DEBUG - ya.test - verify: Test test_format_setting.TestS3.test_date_time_simple_format_insert[v2-common/simple_format/test.json-json_each_row] status: good
