ProfileEvents S3ReadMicroseconds RealTimeMicroseconds looks inconsistent.

Elapsed: 0.193 sec. / query_duration_ms = 192  
VS   
S3ReadMicroseconds  = 350736 / RealTimeMicroseconds = 383210.


```sql
INSERT INTO FUNCTION s3(s3_mydata, url = 'https://s3.us-east-1.amazonaws.com/test..../test_file22222.tsv', structure = 'A Int64', format = 'TSV', compression_method = 'none') SELECT number AS A
FROM numbers(10000)

set max_threads=1;


SELECT count()
FROM s3(s3_mydata, url = 'https://s3.us-east-1.amazonaws.com/test..../test_file22222.tsv', structure = 'A Int64', format = 'TSV', compression_method = 'none')

Query id: 2f63b92a-eae3-45c5-a30c-2a8b7681147a

┌─count()─┐
│   10000 │
└─────────┘

1 rows in set. Elapsed: 0.193 sec. Processed 10.00 thousand rows, 80.00 KB (51.72 thousand rows/s., 413.74 KB/s.)



SELECT
    query_duration_ms,
    k,
    v
FROM system.query_log
ARRAY JOIN
    ProfileEvents.keys AS k,
    ProfileEvents.values AS v
WHERE (query_id = '2f63b92a-eae3-45c5-a30c-2a8b7681147a') AND (type = 2) AND (event_date = today())

┌─query_duration_ms─┬─k─────────────────────────────────┬───────v─┐
│               192 │ Query                             │       1 │
│               192 │ SelectQuery                       │       1 │
│               192 │ IOBufferAllocs                    │       1 │
│               192 │ IOBufferAllocBytes                │ 1048576 │
│               192 │ ArenaAllocChunks                  │       1 │
│               192 │ ArenaAllocBytes                   │    4096 │
│               192 │ TableFunctionExecute              │       1 │
│               192 │ NetworkReceiveElapsedMicroseconds │       7 │
│               192 │ NetworkSendElapsedMicroseconds    │     214 │
│               192 │ NetworkSendBytes                  │    3427 │
│               192 │ SelectedRows                      │   10000 │
│               192 │ SelectedBytes                     │   80000 │
│               192 │ ContextLock                       │      28 │
│               192 │ RWLockAcquiredReadLocks           │       1 │
│               192 │ RealTimeMicroseconds              │  383210 │  --- ? 
│               192 │ UserTimeMicroseconds              │    5004 │
│               192 │ SystemTimeMicroseconds            │     492 │
│               192 │ SoftPageFaults                    │      34 │
│               192 │ OSCPUWaitMicroseconds             │      37 │
│               192 │ OSCPUVirtualTimeMicroseconds      │    5494 │
│               192 │ OSWriteBytes                      │    4096 │
│               192 │ OSReadChars                       │   53248 │
│               192 │ OSWriteChars                      │    2048 │
│               192 │ CreatedHTTPConnections            │       1 │
│               192 │ S3ReadMicroseconds                │  350736 │  --- ?
│               192 │ S3ReadBytes                       │   48890 │
│               192 │ S3ReadRequestsCount               │       1 │
└───────────────────┴───────────────────────────────────┴─────────┘

M(DiskReadElapsedMicroseconds, "Total time spent waiting for read syscall. This include reads from page cache.") \
    M(DiskWriteElapsedMicroseconds, "Total time spent waiting for write syscall. This include writes to page cache.") \
    M(NetworkReceiveElapsedMicroseconds, "Total time spent waiting for data to receive or receiving data from network. Only ClickHouse-related network interaction is included, not by 3rd party libraries.") \
    M(NetworkSendElapsedMicroseconds, "Total time spent waiting for data to send to network or sending data to network. Only ClickHouse-related network interaction is included, not by 3rd party libraries..") \
    M(NetworkReceiveBytes, "Total number of bytes received from network. Only ClickHouse-related network interaction is included, not by 3rd party libraries.") \
    M(NetworkSendBytes, "Total number of bytes send to network. Only ClickHouse-related network interaction is included, not by 3rd party libraries.") \
    M(ThrottlerSleepMicroseconds, "Total time a query was sleeping to conform the 'max_network_bandwidth' setting.") \
    \
    M(QueryMaskingRulesMatch, "Number of times query masking rules was successfully matched.") \
    \
    M(ReplicatedPartFetches, "Number of times a data part was downloaded from replica of a ReplicatedMergeTree table.") \
    M(ReplicatedPartFailedFetches, "Number of times a data part was failed to download from replica of a ReplicatedMergeTree table.") \
    M(ObsoleteReplicatedParts, "") \
    M(ReplicatedPartMerges, "Number of times data parts of ReplicatedMergeTree tables were successfully merged.") \
    M(ReplicatedPartFetchesOfMerged, "Number of times we prefer to download already merged part from replica of ReplicatedMergeTree table instead of performing a merge ourself (usually we prefer doing a merge ourself to save network traffic). This happens when we have not all source parts to perform a merge or when the data part is old enough.") \
    M(ReplicatedPartMutations, "") \
    M(ReplicatedPartChecks, "") \
    M(ReplicatedPartChecksFailed, "") \
    M(ReplicatedDataLoss, "Number of times a data part that we wanted doesn't exist on any replica (even on replicas that are offline right now). That data parts are definitely lost. This is normal due to asynchronous replication (if quorum inserts were not enabled), when the replica on which the data part was written was failed and when it became online after fail it doesn't contain that data part.") \
    \
    M(InsertedRows, "Number of rows INSERTed to all tables.") \
    M(InsertedBytes, "Number of bytes (uncompressed; for columns as they stored in memory) INSERTed to all tables.") \
    M(DelayedInserts, "Number of times the INSERT of a block to a MergeTree table was throttled due to high number of active data parts for partition.") \
    M(RejectedInserts, "Number of times the INSERT of a block to a MergeTree table was rejected with 'Too many parts' exception due to high number of active data parts for partition.") \
    M(DelayedInsertsMilliseconds, "Total number of milliseconds spent while the INSERT of a block to a MergeTree table was throttled due to high number of active data parts for partition.") \
    M(DistributedDelayedInserts, "Number of times the INSERT of a block to a Distributed table was throttled due to high number of pending bytes.") \
    M(DistributedRejectedInserts, "Number of times the INSERT of a block to a Distributed table was rejected with 'Too many bytes' exception due to high number of pending bytes.") \
    M(DistributedDelayedInsertsMilliseconds, "Total number of milliseconds spent while the INSERT of a block to a Distributed table was throttled due to high number of pending bytes.") \
    M(DuplicatedInsertedBlocks, "Number of times the INSERTed block to a ReplicatedMergeTree table was deduplicated.") \
    \