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(MergeTreeMetadataCachePut, "Number of rocksdb puts(used for merge tree metadata cache)") \
    M(MergeTreeMetadataCacheDelete, "Number of rocksdb deletes(used for merge tree metadata cache)") \
    M(MergeTreeMetadataCacheSeek, "Number of rocksdb seeks(used for merge tree metadata cache)") \
    M(MergeTreeMetadataCacheHit, "Number of times the read of meta file was done from MergeTree metadata cache") \
    M(MergeTreeMetadataCacheMiss, "Number of times the read of meta file was not done from MergeTree metadata cache") \
    \
    M(KafkaRebalanceRevocations, "Number of partition revocations (the first stage of consumer group rebalance)") \
    M(KafkaRebalanceAssignments, "Number of partition assignments (the final stage of consumer group rebalance)") \
    M(KafkaRebalanceErrors, "Number of failed consumer group rebalances") \
    M(KafkaMessagesPolled, "Number of Kafka messages polled from librdkafka to ClickHouse") \
    M(KafkaMessagesRead, "Number of Kafka messages already processed by ClickHouse") \
    M(KafkaMessagesFailed, "Number of Kafka messages ClickHouse failed to parse") \
    M(KafkaRowsRead, "Number of rows parsed from Kafka messages") \
    M(KafkaRowsRejected, "Number of parsed rows which were later rejected (due to rebalances / errors or similar reasons). Those rows will be consumed again after the rebalance.") \
    M(KafkaDirectReads, "Number of direct selects from Kafka tables since server start") \
    M(KafkaBackgroundReads, "Number of background reads populating materialized views from Kafka since server start") \
    M(KafkaCommits, "Number of successful commits of consumed offsets to Kafka (normally should be the same as KafkaBackgroundReads)") \
    M(KafkaCommitFailures, "Number of failed commits of consumed offsets to Kafka (usually is a sign of some data duplication)") \
    M(KafkaConsumerErrors, "Number of errors reported by librdkafka during polls") \
    M(KafkaWrites, "Number of writes (inserts) to Kafka tables ") \
    M(KafkaRowsWritten, "Number of rows inserted into Kafka tables") \
    M(KafkaProducerFlushes, "Number of explicit flushes to Kafka producer") \
    M(KafkaMessagesProduced, "Number of messages produced to Kafka") \
    M(KafkaProducerErrors, "Number of errors during producing the messages to Kafka") \
    \
    M(ScalarSubqueriesGlobalCacheHit, "Number of times a read from a scalar subquery was done using the global cache") \
    M(ScalarSubqueriesLocalCacheHit, "Number of times a read from a scalar subquery was done using the local cache") \
    M(ScalarSubqueriesCacheMiss, "Number of times a read from a scalar subquery was not cached and had to be calculated completely")
namespace ProfileEvents
{