2023-12-07 09:14:30,149 - MainThread - awscli.clidriver - DEBUG - CLI version: aws-cli/2.14.1 Python/3.11.6 Windows/10 exe/AMD64
2023-12-07 09:14:30,150 - MainThread - awscli.clidriver - DEBUG - Arguments entered to CLI: ['s3', 'cp', 'D:\\Data\\Test\\Outbox\\testfromaccserver.txt', 's3://BucketName/BucketFolderName/test.txt', '--profile', 'KPMG2Test', '--debug']
2023-12-07 09:14:30,238 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <function add_s3 at 0x0000026F195D44A0>
2023-12-07 09:14:30,238 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <function add_ddb at 0x0000026F194105E0>
2023-12-07 09:14:30,239 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <bound method BasicCommand.add_command of <class 'awscli.customizations.configure.configure.ConfigureCommand'>>
2023-12-07 09:14:30,239 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <function change_name at 0x0000026F19374400>
2023-12-07 09:14:30,239 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <function change_name at 0x0000026F19377EC0>
2023-12-07 09:14:30,239 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <function alias_opsworks_cm at 0x0000026F195D6F20>
2023-12-07 09:14:30,240 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <function add_history_commands at 0x0000026F19452FC0>
2023-12-07 09:14:30,240 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <bound method BasicCommand.add_command of <class 'awscli.customizations.devcommands.CLIDevCommand'>>
2023-12-07 09:14:30,240 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <function add_waiters at 0x0000026F195D6DE0>
2023-12-07 09:14:30,240 - MainThread - botocore.hooks - DEBUG - Event building-command-table.main: calling handler <bound method AliasSubCommandInjector.on_building_command_table of <awscli.alias.AliasSubCommandInjector object at 0x0000026F19696910>>
2023-12-07 09:14:30,241 - MainThread - botocore.loaders - DEBUG - Loading JSON file: C:\Program Files\Amazon\AWSCLIV2\awscli\data\cli.json
2023-12-07 09:14:30,244 - MainThread - botocore.hooks - DEBUG - Event top-level-args-parsed: calling handler <function resolve_types at 0x0000026F195189A0>
2023-12-07 09:14:30,244 - MainThread - botocore.hooks - DEBUG - Event top-level-args-parsed: calling handler <function no_sign_request at 0x0000026F19518CC0>
2023-12-07 09:14:30,245 - MainThread - botocore.hooks - DEBUG - Event top-level-args-parsed: calling handler <function resolve_verify_ssl at 0x0000026F19518C20>
2023-12-07 09:14:30,245 - MainThread - botocore.hooks - DEBUG - Event top-level-args-parsed: calling handler <function resolve_cli_read_timeout at 0x0000026F19518E00>
2023-12-07 09:14:30,246 - MainThread - botocore.hooks - DEBUG - Event top-level-args-parsed: calling handler <function resolve_cli_connect_timeout at 0x0000026F19518D60>
2023-12-07 09:14:30,246 - MainThread - botocore.hooks - DEBUG - Event top-level-args-parsed: calling handler <built-in method update of dict object at 0x0000026F19694140>
2023-12-07 09:14:30,246 - MainThread - botocore.session - DEBUG - Setting config variable for profile to 'KPMG2Test'
2023-12-07 09:14:30,247 - MainThread - awscli.clidriver - DEBUG - CLI version: aws-cli/2.14.1 Python/3.11.6 Windows/10 exe/AMD64 prompt/off
2023-12-07 09:14:30,247 - MainThread - awscli.clidriver - DEBUG - Arguments entered to CLI: ['s3', 'cp', 'D:\\Data\\Test\\Outbox\\testfromaccserver.txt', 's3://BucketName/BucketFolderName/test.txt', '--profile', 'KPMG2Test', '--debug']
2023-12-07 09:14:30,248 - MainThread - botocore.hooks - DEBUG - Event session-initialized: calling handler <function add_timestamp_parser at 0x0000026F195D4E00>
2023-12-07 09:14:30,251 - MainThread - botocore.hooks - DEBUG - Event session-initialized: calling handler <function register_uri_param_handler at 0x0000026F191CD760>
2023-12-07 09:14:30,251 - MainThread - botocore.hooks - DEBUG - Event session-initialized: calling handler <function add_binary_formatter at 0x0000026F19660720>
2023-12-07 09:14:30,252 - MainThread - botocore.hooks - DEBUG - Event session-initialized: calling handler <function no_pager_handler at 0x0000026F19085800>
2023-12-07 09:14:30,252 - MainThread - botocore.hooks - DEBUG - Event session-initialized: calling handler <function inject_assume_role_provider_cache at 0x0000026F191E5080>
2023-12-07 09:14:30,255 - MainThread - botocore.utils - DEBUG - IMDS ENDPOINT: http://169.254.169.254/
2023-12-07 09:14:30,257 - MainThread - botocore.credentials - DEBUG - Skipping environment variable credential check because profile name was explicitly set.
2023-12-07 09:14:30,257 - MainThread - botocore.hooks - DEBUG - Event session-initialized: calling handler <function attach_history_handler at 0x0000026F1943B880>
2023-12-07 09:14:30,257 - MainThread - botocore.hooks - DEBUG - Event session-initialized: calling handler <function inject_json_file_cache at 0x0000026F19400860>
2023-12-07 09:14:30,258 - MainThread - botocore.hooks - DEBUG - Event building-command-table.s3: calling handler <function add_waiters at 0x0000026F195D6DE0>
2023-12-07 09:14:30,258 - MainThread - botocore.hooks - DEBUG - Event building-command-table.s3: calling handler <bound method AliasSubCommandInjector.on_building_command_table of <awscli.alias.AliasSubCommandInjector object at 0x0000026F19696910>>
2023-12-07 09:14:30,261 - MainThread - botocore.hooks - DEBUG - Event building-command-table.s3_cp: calling handler <function add_waiters at 0x0000026F195D6DE0>
2023-12-07 09:14:30,261 - MainThread - botocore.hooks - DEBUG - Event building-command-table.s3_cp: calling handler <bound method AliasSubCommandInjector.on_building_command_table of <awscli.alias.AliasSubCommandInjector object at 0x0000026F19696910>>
2023-12-07 09:14:30,264 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.paths: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,264 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.dryrun: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,266 - MainThread - botocore.hooks - DEBUG - Event process-cli-arg.custom.cp: calling handler <awscli.argprocess.ParamShorthandParser object at 0x0000026F190AFF90>
2023-12-07 09:14:30,266 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.quiet: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,266 - MainThread - botocore.hooks - DEBUG - Event process-cli-arg.custom.cp: calling handler <awscli.argprocess.ParamShorthandParser object at 0x0000026F190AFF90>
2023-12-07 09:14:30,267 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.anonymous: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,267 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.acl: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,268 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.follow-symlinks: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,268 - MainThread - botocore.hooks - DEBUG - Event process-cli-arg.custom.cp: calling handler <awscli.argprocess.ParamShorthandParser object at 0x0000026F190AFF90>
2023-12-07 09:14:30,269 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.anonymous: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,271 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.sse: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,271 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.sse-c: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,272 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.sse-c-key: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,272 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.sse-kms-key-id: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,273 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.sse-c-copy-source: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,273 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.sse-c-copy-source-key: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,274 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.storage-class: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,274 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.grants: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,275 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.website-redirect: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,275 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.content-type: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,275 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.cache-control: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,276 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.content-disposition: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,276 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.content-encoding: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,277 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.content-language: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,277 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.expires: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,278 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.source-region: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,278 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.only-show-errors: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,279 - MainThread - botocore.hooks - DEBUG - Event process-cli-arg.custom.cp: calling handler <awscli.argprocess.ParamShorthandParser object at 0x0000026F190AFF90>
2023-12-07 09:14:30,279 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.anonymous: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,280 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.page-size: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,282 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.ignore-glacier-warnings: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,282 - MainThread - botocore.hooks - DEBUG - Event process-cli-arg.custom.cp: calling handler <awscli.argprocess.ParamShorthandParser object at 0x0000026F190AFF90>
2023-12-07 09:14:30,283 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.force-glacier-transfer: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,283 - MainThread - botocore.hooks - DEBUG - Event process-cli-arg.custom.cp: calling handler <awscli.argprocess.ParamShorthandParser object at 0x0000026F190AFF90>
2023-12-07 09:14:30,284 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.request-payer: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,284 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.metadata: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,284 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.copy-props: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,285 - MainThread - botocore.hooks - DEBUG - Event process-cli-arg.custom.cp: calling handler <awscli.argprocess.ParamShorthandParser object at 0x0000026F190AFF90>
2023-12-07 09:14:30,285 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.metadata-directive: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,286 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.expected-size: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,286 - MainThread - botocore.hooks - DEBUG - Event load-cli-arg.custom.cp.anonymous: calling handler <awscli.paramfile.URIArgumentHandler object at 0x0000026F19131E10>
2023-12-07 09:14:30,287 - MainThread - botocore.credentials - DEBUG - Looking for credentials via: assume-role
2023-12-07 09:14:30,287 - MainThread - botocore.credentials - DEBUG - Looking for credentials via: assume-role-with-web-identity
2023-12-07 09:14:30,287 - MainThread - botocore.credentials - DEBUG - Looking for credentials via: sso
2023-12-07 09:14:30,288 - MainThread - botocore.credentials - DEBUG - Looking for credentials via: shared-credentials-file
2023-12-07 09:14:30,289 - MainThread - botocore.credentials - INFO - Found credentials in shared credentials file: ~/.aws/credentials
2023-12-07 09:14:30,291 - MainThread - botocore.loaders - DEBUG - Loading JSON file: C:\Program Files\Amazon\AWSCLIV2\awscli\botocore\data\endpoints.json
2023-12-07 09:14:30,307 - MainThread - botocore.hooks - DEBUG - Event choose-service-name: calling handler <function handle_service_name_alias at 0x0000026F17F5DF80>
2023-12-07 09:14:30,397 - MainThread - botocore.loaders - DEBUG - Loading JSON file: C:\Program Files\Amazon\AWSCLIV2\awscli\botocore\data\s3\2006-03-01\service-2.json
2023-12-07 09:14:30,498 - MainThread - botocore.loaders - DEBUG - Loading JSON file: C:\Program Files\Amazon\AWSCLIV2\awscli\botocore\data\s3\2006-03-01\endpoint-rule-set-1.json
2023-12-07 09:14:30,504 - MainThread - botocore.loaders - DEBUG - Loading JSON file: C:\Program Files\Amazon\AWSCLIV2\awscli\botocore\data\partitions.json
2023-12-07 09:14:30,506 - MainThread - botocore.hooks - DEBUG - Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x0000026F17EA8360>
2023-12-07 09:14:30,506 - MainThread - botocore.hooks - DEBUG - Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x0000026F17EA80E0>
2023-12-07 09:14:30,507 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: environment_service
2023-12-07 09:14:30,507 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: environment_global
2023-12-07 09:14:30,508 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: config_service
2023-12-07 09:14:30,508 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: config_global
2023-12-07 09:14:30,509 - MainThread - botocore.configprovider - DEBUG - No configured endpoint found.
2023-12-07 09:14:30,512 - MainThread - botocore.endpoint - DEBUG - Setting s3 timeout as (60, 60)
2023-12-07 09:14:30,514 - MainThread - botocore.utils - DEBUG - Registering S3 region redirector handler
2023-12-07 09:14:30,515 - MainThread - botocore.hooks - DEBUG - Event choose-service-name: calling handler <function handle_service_name_alias at 0x0000026F17F5DF80>
2023-12-07 09:14:30,516 - MainThread - botocore.hooks - DEBUG - Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x0000026F17EA8360>
2023-12-07 09:14:30,516 - MainThread - botocore.hooks - DEBUG - Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x0000026F17EA80E0>
2023-12-07 09:14:30,517 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: environment_service
2023-12-07 09:14:30,517 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: environment_global
2023-12-07 09:14:30,518 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: config_service
2023-12-07 09:14:30,518 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: config_global
2023-12-07 09:14:30,518 - MainThread - botocore.configprovider - DEBUG - No configured endpoint found.
2023-12-07 09:14:30,522 - MainThread - botocore.endpoint - DEBUG - Setting s3 timeout as (60, 60)
2023-12-07 09:14:30,525 - MainThread - botocore.utils - DEBUG - Registering S3 region redirector handler
2023-12-07 09:14:30,525 - MainThread - botocore.hooks - DEBUG - Event choose-service-name: calling handler <function handle_service_name_alias at 0x0000026F17F5DF80>
2023-12-07 09:14:30,529 - MainThread - botocore.hooks - DEBUG - Event creating-client-class.s3: calling handler <function add_generate_presigned_post at 0x0000026F17EA8360>
2023-12-07 09:14:30,530 - MainThread - botocore.hooks - DEBUG - Event creating-client-class.s3: calling handler <function add_generate_presigned_url at 0x0000026F17EA80E0>
2023-12-07 09:14:30,530 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: environment_service
2023-12-07 09:14:30,530 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: environment_global
2023-12-07 09:14:30,531 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: config_service
2023-12-07 09:14:30,531 - MainThread - botocore.configprovider - DEBUG - Looking for endpoint for s3 via: config_global
2023-12-07 09:14:30,532 - MainThread - botocore.configprovider - DEBUG - No configured endpoint found.
2023-12-07 09:14:30,534 - MainThread - botocore.endpoint - DEBUG - Setting s3 timeout as (60, 60)
2023-12-07 09:14:30,536 - MainThread - botocore.utils - DEBUG - Registering S3 region redirector handler
2023-12-07 09:14:30,539 - MainThread - awscli.customizations.s3.factory - DEBUG - S3 CRT client optimized for system: False
2023-12-07 09:14:30,539 - MainThread - awscli.customizations.s3.factory - DEBUG - Auto resolved s3 transfer client to: classic
2023-12-07 09:14:30,540 - MainThread - awscli.customizations.s3.factory - DEBUG - Using a multipart threshold of 8388608 and a part size of 8388608
2023-12-07 09:14:30,543 - MainThread - s3transfer.utils - DEBUG - Acquiring 0
2023-12-07 09:14:30,543 - ThreadPoolExecutor-1_0 - s3transfer.tasks - DEBUG - UploadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x0000026F19173E50>}) about to wait for the following futures []
2023-12-07 09:14:30,544 - ThreadPoolExecutor-1_0 - s3transfer.tasks - DEBUG - UploadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x0000026F19173E50>}) done waiting for dependent futures
2023-12-07 09:14:30,555 - ThreadPoolExecutor-1_0 - s3transfer.tasks - DEBUG - Executing task UploadSubmissionTask(transfer_id=0, {'transfer_future': <s3transfer.futures.TransferFuture object at 0x0000026F19173E50>}) with kwargs {'client': <botocore.client.S3 object at 0x0000026F1AF581D0>, 'config': <s3transfer.manager.TransferConfig object at 0x0000026F1ABFAE90>, 'osutil': <s3transfer.utils.OSUtils object at 0x0000026F1ABA5A10>, 'request_executor': <s3transfer.futures.BoundedExecutor object at 0x0000026F1AF93B10>, 'transfer_future': <s3transfer.futures.TransferFuture object at 0x0000026F19173E50>}
2023-12-07 09:14:30,578 - ThreadPoolExecutor-1_0 - s3transfer.futures - DEBUG - Submitting task PutObjectTask(transfer_id=0, {'bucket': 'BucketName', 'key': 'BucketFolderName/test.txt', 'extra_args': {'ContentType': 'text/plain'}}) to executor <s3transfer.futures.BoundedExecutor object at 0x0000026F1AF93B10> for transfer request: 0.
2023-12-07 09:14:30,579 - ThreadPoolExecutor-1_0 - s3transfer.utils - DEBUG - Acquiring 0
2023-12-07 09:14:30,581 - ThreadPoolExecutor-0_0 - s3transfer.tasks - DEBUG - PutObjectTask(transfer_id=0, {'bucket': 'BucketName', 'key': 'BucketFolderName/test.txt', 'extra_args': {'ContentType': 'text/plain'}}) about to wait for the following futures []
2023-12-07 09:14:30,581 - ThreadPoolExecutor-1_0 - s3transfer.utils - DEBUG - Releasing acquire 0/None
2023-12-07 09:14:30,581 - ThreadPoolExecutor-0_0 - s3transfer.tasks - DEBUG - PutObjectTask(transfer_id=0, {'bucket': 'BucketName', 'key': 'BucketFolderName/test.txt', 'extra_args': {'ContentType': 'text/plain'}}) done waiting for dependent futures
2023-12-07 09:14:30,582 - ThreadPoolExecutor-0_0 - s3transfer.tasks - DEBUG - Executing task PutObjectTask(transfer_id=0, {'bucket': 'BucketName', 'key': 'BucketFolderName/test.txt', 'extra_args': {'ContentType': 'text/plain'}}) with kwargs {'client': <botocore.client.S3 object at 0x0000026F1AF581D0>, 'fileobj': <s3transfer.utils.ReadFileChunk object at 0x0000026F1AFAE050>, 'bucket': 'BucketName', 'key': 'BucketFolderName/test.txt', 'extra_args': {'ContentType': 'text/plain'}}
2023-12-07 09:14:30,583 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-endpoint-resolution.s3: calling handler <function customize_endpoint_resolver_builtins at 0x0000026F17F8A700>
2023-12-07 09:14:30,583 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-endpoint-resolution.s3: calling handler <bound method S3RegionRedirectorv2.redirect_from_cache of <botocore.utils.S3RegionRedirectorv2 object at 0x0000026F1AF92C10>>
2023-12-07 09:14:30,584 - ThreadPoolExecutor-0_0 - botocore.regions - DEBUG - Calling endpoint provider with parameters: {'Bucket': 'BucketName', 'Region': 'eu-central-1', 'UseFIPS': False, 'UseDualStack': False, 'ForcePathStyle': False, 'Accelerate': False, 'UseGlobalEndpoint': False, 'Key': 'BucketFolderName/test.txt', 'DisableMultiRegionAccessPoints': False, 'UseArnRegion': True}
2023-12-07 09:14:30,585 - ThreadPoolExecutor-0_0 - botocore.regions - DEBUG - Endpoint provider result: https://BucketName.s3.eu-central-1.amazonaws.com
2023-12-07 09:14:30,585 - ThreadPoolExecutor-0_0 - botocore.regions - DEBUG - Selecting from endpoint provider's list of auth schemes: "sigv4". User selected auth scheme is: "None"
2023-12-07 09:14:30,585 - ThreadPoolExecutor-0_0 - botocore.regions - DEBUG - Selected auth type "v4" as "v4" with signing context params: {'region': 'eu-central-1', 'signing_name': 's3', 'disableDoubleEncoding': True}
2023-12-07 09:14:30,586 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event provide-client-params.s3.PutObject: calling handler <function base64_decode_input_blobs at 0x0000026F196607C0>
2023-12-07 09:14:30,586 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-parameter-build.s3.PutObject: calling handler <function validate_ascii_metadata at 0x0000026F17F89260>
2023-12-07 09:14:30,587 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-parameter-build.s3.PutObject: calling handler <function sse_md5 at 0x0000026F17F88540>
2023-12-07 09:14:30,587 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-parameter-build.s3.PutObject: calling handler <function convert_body_to_file_like_object at 0x0000026F17F89C60>
2023-12-07 09:14:30,588 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-parameter-build.s3.PutObject: calling handler <function validate_bucket_name at 0x0000026F17F884A0>
2023-12-07 09:14:30,588 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-parameter-build.s3.PutObject: calling handler <function remove_bucket_from_url_paths_from_model at 0x0000026F17F8A520>
2023-12-07 09:14:30,591 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-parameter-build.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.annotate_request_context of <botocore.utils.S3RegionRedirectorv2 object at 0x0000026F1AF92C10>>
2023-12-07 09:14:30,591 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-parameter-build.s3.PutObject: calling handler <function generate_idempotent_uuid at 0x0000026F17F882C0>
2023-12-07 09:14:30,592 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-call.s3.PutObject: calling handler <function conditionally_calculate_md5 at 0x0000026F17D94680>
2023-12-07 09:14:30,593 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-call.s3.PutObject: calling handler <function add_expect_header at 0x0000026F17F88860>
2023-12-07 09:14:30,593 - ThreadPoolExecutor-0_0 - botocore.handlers - DEBUG - Adding expect 100 continue header to request.
2023-12-07 09:14:30,593 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-call.s3.PutObject: calling handler <function inject_api_version_header_if_needed at 0x0000026F17F89DA0>
2023-12-07 09:14:30,594 - ThreadPoolExecutor-0_0 - botocore.endpoint - DEBUG - Making request for OperationModel(name=PutObject) with params: {'url_path': '/BucketFolderName/test.txt', 'query_string': {}, 'method': 'PUT', 'headers': {'Content-Type': 'text/plain', 'User-Agent': 'aws-cli/2.14.1 Python/3.11.6 Windows/10 exe/AMD64 prompt/off command/s3.cp', 'Content-MD5': 'xMpCOKC5I4INzFCab3WEmw==', 'Expect': '100-continue'}, 'body': <s3transfer.utils.ReadFileChunk object at 0x0000026F1AFAE050>, 'auth_path': '/BucketName/BucketFolderName/test.txt', 'url': 'https://BucketName.s3.eu-central-1.amazonaws.com/BucketFolderName/test.txt', 'context': {'client_region': 'eu-central-1', 'client_config': <botocore.config.Config object at 0x0000026F1AF83A90>, 'has_streaming_input': True, 'auth_type': 'v4', 'signing': {'region': 'eu-central-1', 'signing_name': 's3', 'disableDoubleEncoding': True}, 's3_redirect': {'redirected': False, 'bucket': 'BucketName', 'params': {'Bucket': 'BucketName', 'Key': 'BucketFolderName/test.txt', 'Body': <s3transfer.utils.ReadFileChunk object at 0x0000026F1AFAE050>, 'ContentType': 'text/plain'}}}}
2023-12-07 09:14:30,595 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event request-created.s3.PutObject: calling handler <function signal_not_transferring at 0x0000026F192B9300>
2023-12-07 09:14:30,595 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event request-created.s3.PutObject: calling handler <bound method RequestSigner.handler of <botocore.signers.RequestSigner object at 0x0000026F1AF583D0>>
2023-12-07 09:14:30,595 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event choose-signer.s3.PutObject: calling handler <function set_operation_specific_signer at 0x0000026F17F88180>
2023-12-07 09:14:30,596 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event before-sign.s3.PutObject: calling handler <function remove_arn_from_signing_path at 0x0000026F17F8A660>
2023-12-07 09:14:30,596 - ThreadPoolExecutor-0_0 - botocore.auth - DEBUG - Calculating signature using v4 auth.
2023-12-07 09:14:30,597 - ThreadPoolExecutor-0_0 - botocore.auth - DEBUG - CanonicalRequest:
PUT
/BucketFolderName/test.txt

content-md5:xMpCOKC5I4INzFCab3WEmw==
content-type:text/plain
host:BucketName.s3.eu-central-1.amazonaws.com
x-amz-content-sha256:UNSIGNED-PAYLOAD
x-amz-date:20231207T081430Z

content-md5;content-type;host;x-amz-content-sha256;x-amz-date
UNSIGNED-PAYLOAD
2023-12-07 09:14:30,597 - ThreadPoolExecutor-0_0 - botocore.auth - DEBUG - StringToSign:
AWS4-HMAC-SHA256
20231207T081430Z
20231207/eu-central-1/s3/aws4_request
aec36a0659629352ef7c537f2b6df22864a7ff414cdfb00b0f4c43a509b82243
2023-12-07 09:14:30,598 - ThreadPoolExecutor-0_0 - botocore.auth - DEBUG - Signature:
b181dd73dd4c94a217a1ea04edf41d5cb8cd294c870dc51d6418ce42cf340c86
2023-12-07 09:14:30,598 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event request-created.s3.PutObject: calling handler <function signal_transferring at 0x0000026F192B9620>
2023-12-07 09:14:30,598 - ThreadPoolExecutor-0_0 - botocore.endpoint - DEBUG - Sending http request: <AWSPreparedRequest stream_output=False, method=PUT, url=https://BucketName.s3.eu-central-1.amazonaws.com/BucketFolderName/test.txt, headers={'Content-Type': b'text/plain', 'User-Agent': b'aws-cli/2.14.1 Python/3.11.6 Windows/10 exe/AMD64 prompt/off command/s3.cp', 'Content-MD5': b'xMpCOKC5I4INzFCab3WEmw==', 'Expect': b'100-continue', 'X-Amz-Date': b'20231207T081430Z', 'X-Amz-Content-SHA256': b'UNSIGNED-PAYLOAD', 'Authorization': b'AWS4-HMAC-SHA256 Credential=**removed**/20231207/eu-central-1/s3/aws4_request, SignedHeaders=content-md5;content-type;host;x-amz-content-sha256;x-amz-date, Signature=b181dd73dd4c94a217a1ea04edf41d5cb8cd294c870dc51d6418ce42cf340c86', 'Content-Length': '1'}>
2023-12-07 09:14:30,599 - ThreadPoolExecutor-0_0 - botocore.httpsession - DEBUG - Certificate path: C:\Program Files\Amazon\AWSCLIV2\awscli\botocore\cacert.pem
2023-12-07 09:14:30,600 - ThreadPoolExecutor-0_0 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): BucketName.s3.eu-central-1.amazonaws.com:443
2023-12-07 09:14:33,991 - ThreadPoolExecutor-0_0 - botocore.awsrequest - DEBUG - Waiting for 100 Continue response.
2023-12-07 09:14:34,026 - ThreadPoolExecutor-0_0 - botocore.awsrequest - DEBUG - 100 Continue response seen, now sending request body.
2023-12-07 09:14:34,054 - ThreadPoolExecutor-0_0 - urllib3.connectionpool - DEBUG - https://BucketName.s3.eu-central-1.amazonaws.com:443 "PUT /BucketFolderName/test.txt HTTP/1.1" 200 0
2023-12-07 09:14:34,055 - ThreadPoolExecutor-0_0 - botocore.parsers - DEBUG - Response headers: {'x-amz-id-2': 'BnB7h84/J+XxmyYh67tUzDq3YfMrXSWnn49f8uELzqGUmYLSUjy3OWRSmATSVooGOdzBNhtElUw=', 'x-amz-request-id': 'WBK8VD26JYANWMJB', 'Date': 'Thu, 07 Dec 2023 08:14:34 GMT', 'x-amz-server-side-encryption': 'AES256', 'ETag': '"c4ca4238a0b923820dcc509a6f75849b"', 'Server': 'AmazonS3', 'Content-Length': '0'}
2023-12-07 09:14:34,056 - ThreadPoolExecutor-0_0 - botocore.parsers - DEBUG - Response body:
b''
2023-12-07 09:14:34,057 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event needs-retry.s3.PutObject: calling handler <bound method RetryHandler.needs_retry of <botocore.retries.standard.RetryHandler object at 0x0000026F1AF93290>>
2023-12-07 09:14:34,057 - ThreadPoolExecutor-0_0 - botocore.retries.standard - DEBUG - Not retrying request.
2023-12-07 09:14:34,057 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event needs-retry.s3.PutObject: calling handler <bound method S3RegionRedirectorv2.redirect_from_error of <botocore.utils.S3RegionRedirectorv2 object at 0x0000026F1AF92C10>>
2023-12-07 09:14:34,058 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event after-call.s3.PutObject: calling handler <function enhance_error_msg at 0x0000026F195D4A40>
2023-12-07 09:14:34,058 - ThreadPoolExecutor-0_0 - botocore.hooks - DEBUG - Event after-call.s3.PutObject: calling handler <bound method RetryQuotaChecker.release_retry_quota of <botocore.retries.standard.RetryQuotaChecker object at 0x0000026F1AF92D50>>
2023-12-07 09:14:34,059 - ThreadPoolExecutor-0_0 - s3transfer.utils - DEBUG - Releasing acquire 0/None
upload: ..\..\Data\Test\Outbox\testfromaccserver.txt to s3://BucketName/BucketFolderName/test.txt
2023-12-07 09:14:34,060 - Thread-1 - awscli.customizations.s3.results - DEBUG - Shutdown request received in result processing thread, shutting down result thread.

Can you post AWS CLI log too?

Now I use AWS CLI which works

Log.

@ACG: Ok, in general, do not modify the Host name.
The bucket name goes to the Remote directory.
See https://winscp.net/eng/docs/guide_amazon_s3#buckets

ic-integration is bucket name, kpmg is bucket subdirectory name. I forgot to hide these probably

I have tried a lot of different variants, also using remote directory, but none of these worked unfortunatly

The /KPMG should not be in the Host name. It should be in the Remote directory.
The next version will parse the path out of Host name box automatically:
Issue 2219 – Recognize path in Host name box on Login dialog

---

And what is ic-integrations?

No sorry not using accelerated endpoints but the error is similar. Here the log.

I have already installed AWS CLI and can continue with that but still curious how the get it working in WinSCP.

@ACG: Are you sure you have the same issue? I.e. with accelerated endpoints? Anyway, please attach a full session log file showing the problem (using the latest version of WinSCP).

A supplier gave me the S3 endpoint and I am trying to connect with WinSCP but getting the following error

The request signature we calculated does not match the signature you provided. Check your key and signing method.
Extra Details: AWSAccessKeyId: XXXXremovedXXXX, StringToSign: AWS4-HMAC-SHA256
20231128T115438Z
20231128/eu-central-1/s3/aws4_request
f066766405090062e0cf5e71c50e2bf81e2b4a49a2a0612b01cef68fb0cca78b,
SignatureProvided: 5bd7a1b4d8167fb94d62d1e4a08f37e937a26bf0fb56e99538a8f60281f2ce39,
StringToSignBytes: 41 57 53 34 2d 48 4d 41 43 2d 53 48 41 32 35 36 0a 32 30 32 33 31 31 32 38 54 31 31 35 34 33 38 5a 0a 32 30 32 33 31 31 32 38 2f 65 75 2d 63 65 6e 74 72 61 6c 2d 31 2f 73 33 2f 61 77 73 34 5f 72 65 71 75 65 73 74 0a 66 30 36 36 37 36 36 34 30 35 30 39 30 30 36 32 65 30 63 66 35 65 37 31 63 35 30 65 32 62 66 38 31 65 32 62 34 61 34 39 61 32 61 30 36 31 32 62 30 31 63 65 66 36 38 66 62 30 63 63 61 37 38 62,
CanonicalRequest: GET
/[bucket subdirectory name/
max-keys=1
host:[bucket name].s3.eu-central-1.amazonaws.com
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20231128T115438Z

host;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855,
CanonicalRequestBytes: 47 45 54 0a 2f 6b 70 6d 67 2f 0a 6d 61 78 2d 6b 65 79 73 3d 31 0a 68 6f 73 74 3a 69 63 2d 69 6e 74 65 67 72 61 74 69 6f 6e 73 2e 73 33 2e 65 75 2d 63 65 6e 74 72 61 6c 2d 31 2e 61 6d 61 7a 6f 6e 61 77 73 2e 63 6f 6d 0a 78 2d 61 6d 7a 2d 63 6f 6e 74 65 6e 74 2d 73 68 61 32 35 36 3a 65 33 62 30 63 34 34 32 39 38 66 63 31 63 31 34 39 61 66 62 66 34 63 38 39 39 36 66 62 39 32 34 32 37 61 65 34 31 65 34 36 34 39 62 39 33 34 63 61 34 39 35 39 39 31 62 37 38 35 32 62 38 35 35 0a 78 2d 61 6d 7a 2d 64 61 74 65 3a 32 30 32 33 31 31 32 38 54 31 31 35 34 33 38 5a 0a 0a 68 6f 73 74 3b 78 2d 61 6d 7a 2d 63 6f 6e 74 65 6e 74 2d 73 68 61 32 35 36 3b 78 2d 61 6d 7a 2d 64 61 74 65 0a 65 33 62 30 63 34 34 32 39 38 66 63 31 63 31 34 39 61 66 62 66 34 63 38 39 39 36 66 62 39 32 34 32 37 61 65 34 31 65 34 36 34 39 62 39 33 34 63 61 34 39 35 39 39 31 62 37 38 35 32 62 38 35 35,
RequestId: C8Q9PNXXHPQPEFC3,
HostId: K2MfMucDzIk9QXCnQivd6Lqb3Ssdoa4imfAFWMsggf4Rlbo/5Km2mnABYpvC9J4arLReUEr63Ag=
Connection failed.

I have checked the key and that is ok. Any idea?

I have create a new bucket, enabled S3 transfer acceleration on it.

I've configured WinSCP to connect via s3-accelerate.amazonaws.com (instead of the default s3.amazonaws.com). But I still can work with the bucket without any problem.

Where do we differ? Can you post a session log file?

I've didn't hear about accelerated endpoint until now. I'll check that.

Ok, after testing, only some buckets are not working, so we are double checking this.
We did realize that we were using an accelerated endpoint, not the default "s3.amazonaws.com"
We had accelerated a bucket, which provides a new bucket name.
Our other tools supported this, so we just assumed WinSCP did, but it apparently provides a 403 error in the logs using an accelerated path.
Is this a known issue?

mprewitt007 wrote:

Do you have a public test bucket in mind as none of our buckets are public.

I understand that. I was asking you would be able to create a test public bucket that would have the problem.

The ones we have working have an aws transfer in front of them, so are using ppks for authentication instead of accesskey & secrets.

So actually does any S3 bucket work for you, when you are connecting using S3 protocol? The buckets you are accessing via SFTP are irrelevant to this problem.

Do you have a public test bucket in mind as none of our buckets are public.
We are able to access some bucket.
We did isolate that accelerated endpoints are not working.
Normal s3 some work, some don't.
All our buckets are in east-1.
The ones we have working have an aws transfer in front of them, so are using ppks for authentication instead of accesskey & secrets.
I have a client who can access it, but it drops connection every so often without retrying and he's having to manually reconnect. He's using 5.20rc3

Thanks for your report. Would you able to reproduce the problem with some test public bucket that you can share with us?

Good Day,
We use S3 extensively and WinSCP is our preference.
Starting today we noticed that some of our buckets are getting this error:

The request signature we calculated does not match the signature you provided. Check your key and signing method.

I've posted the full error below and will upload a scrubbed log as well.
FileZilla Pro still works with no issue, and some S3 buckets work with no issue.
We've checked the IAM key and endpoints and tried this from other computers

The request signature we calculated does not match the signature you provided. Check your key and signing method.
Extra Details: AWSAccessKeyId: XXXXremovedXXXX, StringToSign: AWS4-HMAC-SHA256
20220602T163240Z
20220602/us-east-1/s3/aws4_request
98ae89429d771c6de6adac4db0063fb2a06be38c8ba0c36962586444e2b6f4ec, SignatureProvided: 51b7d1201f713d8cbf1ec3f586356b8a91da3960bda48ed1a2cb8a7fde3b4b92, StringToSignBytes: 41 57 53 34 2d 48 4d 41 43 2d 53 48 41 32 35 36 0a 32 30 32 32 30 36 30 32 54 31 36 33 32 34 30 5a 0a 32 30 32 32 30 36 30 32 2f 75 73 2d 65 61 73 74 2d 31 2f 73 33 2f 61 77 73 34 5f 72 65 71 75 65 73 74 0a 39 38 61 65 38 39 34 32 39 64 37 37 31 63 36 64 65 36 61 64 61 63 34 64 62 30 30 36 33 66 62 32 61 30 36 62 65 33 38 63 38 62 61 30 63 33 36 39 36 32 35 38 36 34 34 34 65 32 62 36 66 34 65 63, CanonicalRequest: GET
/XXXXX bucketname removed xxx/
delimiter=%2F&max-keys=1&prefix=ingest%2F
host:s3.amazonaws.com
x-amz-content-sha256:e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
x-amz-date:20220602T163240Z

host;x-amz-content-sha256;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855, CanonicalRequestBytes: 47 45 54 0a 2f 74 65 6e 61 6e 74 2d 64 72 6f 70 31 34 2d 6f 64 6f 6e 6e 65 6c 6c 63 68 72 69 73 74 6f 70 68 65 72 2d 65 64 64 6d 2f 0a 64 65 6c 69 6d 69 74 65 72 3d 25 32 46 26 6d 61 78 2d 6b 65 79 73 3d 31 26 70 72 65 66 69 78 3d 69 6e 67 65 73 74 25 32 46 0a 68 6f 73 74 3a 73 33 2e 61 6d 61 7a 6f 6e 61 77 73 2e 63 6f 6d 0a 78 2d 61 6d 7a 2d 63 6f 6e 74 65 6e 74 2d 73 68 61 32 35 36 3a 65 33 62 30 63 34 34 32 39 38 66 63 31 63 31 34 39 61 66 62 66 34 63 38 39 39 36 66 62 39 32 34 32 37 61 65 34 31 65 34 36 34 39 62 39 33 34 63 61 34 39 35 39 39 31 62 37 38 35 32 62 38 35 35 0a 78 2d 61 6d 7a 2d 64 61 74 65 3a 32 30 32 32 30 36 30 32 54 31 36 33 32 34 30 5a 0a 0a 68 6f 73 74 3b 78 2d 61 6d 7a 2d 63 6f 6e 74 65 6e 74 2d 73 68 61 32 35 36 3b 78 2d 61 6d 7a 2d 64 61 74 65 0a 65 33 62 30 63 34 34 32 39 38 66 63 31 63 31 34 39 61 66 62 66 34 63 38 39 39 36 66 62 39 32 34 32 37 61 65 34 31 65 34 36 34 39 62 39 33 34 63 61 34 39 35 39 39 31 62 37 38 35 32 62 38 35 35, RequestId: GCBFKH2SK2H5R9KG, HostId: P0k6v48phktgDYZSstp98aLtyfO4LXS6d7vp8tzaycDGgV6nnidsg0AzVrFrpqPIoIuNXDH0KgI=
Connection failed.