Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[BUG] rdb_bgsave_in_progress since a long time, aof file growing non-stop #675

Closed
zas opened this issue Jun 15, 2023 · 23 comments
Closed

[BUG] rdb_bgsave_in_progress since a long time, aof file growing non-stop #675

zas opened this issue Jun 15, 2023 · 23 comments

Comments

@zas
Copy link

zas commented Jun 15, 2023

Describe the bug

We are running a master<->master keydb setup, using keydb docker images from eqalpha/keydb:x86_64_v6.3.3

After some time without any problem, AOF file starts to grow on ONE instance.

Currently that's the instance where most clients connect, but we had the issue with other some time ago.

image

Here is info the the instance with growing AOF (rex):

127.0.0.1:13062> info 
# Server
redis_version:6.3.3
redis_git_sha1:86ea5c7e
redis_git_dirty:1
redis_build_id:942bbf4eda83edde
redis_mode:standalone
os:Linux 5.15.0-73-generic x86_64
arch_bits:64
multiplexing_api:epoll
atomicvar_api:atomic-builtin
gcc_version:7.5.0
process_id:1
process_supervised:no
run_id:5071892d308a75b7d310416a50939c495573149a
tcp_port:6379
server_time_usec:1686815422514961
uptime_in_seconds:253525
uptime_in_days:2
hz:10
configured_hz:10
lru_clock:9093822
executable:/data/keydb-server
config_file:

# Clients
connected_clients:41
cluster_connections:0
maxclients:10000
client_recent_max_input_buffer:48
client_recent_max_output_buffer:0
blocked_clients:0
tracking_clients:0
clients_in_timeout_table:0
current_client_thread:1
thread_0_clients:19
thread_1_clients:20
thread_2_clients:3
thread_3_clients:0

# Memory
used_memory:7533296
used_memory_human:7.18M
used_memory_rss:20541440
used_memory_rss_human:19.59M
used_memory_peak:20649880
used_memory_peak_human:19.69M
used_memory_peak_perc:36.48%
used_memory_overhead:5439192
used_memory_startup:3216024
used_memory_dataset:2094104
used_memory_dataset_perc:48.51%
allocator_allocated:8318968
allocator_active:10436608
allocator_resident:15495168
total_system_memory:67353542656
total_system_memory_human:62.73G
used_memory_lua:37888
used_memory_lua_human:37.00K
used_memory_scripts:0
used_memory_scripts_human:0B
number_of_cached_scripts:0
maxmemory:0
maxmemory_human:0B
maxmemory_policy:noeviction
allocator_frag_ratio:1.25
allocator_frag_bytes:2117640
allocator_rss_ratio:1.48
allocator_rss_bytes:5058560
rss_overhead_ratio:1.33
rss_overhead_bytes:5046272
mem_fragmentation_ratio:2.74
mem_fragmentation_bytes:13050456
mem_not_counted_for_evict:3066
mem_replication_backlog:1048576
mem_clients_slaves:20512
mem_clients_normal:799672
mem_aof_buffer:3072
mem_allocator:jemalloc-5.2.1
active_defrag_running:0
lazyfree_pending_objects:0
lazyfreed_objects:0
storage_provider:none

# Persistence
loading:0
current_cow_size:0
current_cow_size_age:0
current_fork_perc:0.00
current_save_keys_processed:0
current_save_keys_total:3160
rdb_changes_since_last_save:14470141
rdb_bgsave_in_progress:1
rdb_last_save_time:1686789002
rdb_last_bgsave_status:ok
rdb_last_bgsave_time_sec:0
rdb_current_bgsave_time_sec:26359
rdb_last_cow_size:1380352
aof_enabled:1
aof_rewrite_in_progress:0
aof_rewrite_scheduled:0
aof_last_rewrite_time_sec:1
aof_current_rewrite_time_sec:-1
aof_last_bgrewrite_status:ok
aof_last_write_status:ok
aof_last_cow_size:3182592
module_fork_in_progress:0
module_fork_last_cow_size:0
aof_current_size:690452188
aof_base_size:511532
aof_pending_rewrite:0
aof_buffer_length:0
aof_rewrite_buffer_length:0
aof_pending_bio_fsync:0
aof_delayed_fsync:0

# Stats
total_connections_received:1354187
total_commands_processed:577223792
instantaneous_ops_per_sec:2118
total_net_input_bytes:50324881019
total_net_output_bytes:24729808346
instantaneous_input_kbps:180.95
instantaneous_output_kbps:89.12
rejected_connections:0
sync_full:1
sync_partial_ok:0
sync_partial_err:1
expired_keys:28064642
expired_stale_perc:0.00
expired_time_cap_reached_count:0
expire_cycle_cpu_milliseconds:0
evicted_keys:0
keyspace_hits:101900287
keyspace_misses:12669
pubsub_channels:0
pubsub_patterns:0
latest_fork_usec:1581
total_forks:3827
migrate_cached_sockets:0
slave_expires_tracked_keys:0
active_defrag_hits:0
active_defrag_misses:0
active_defrag_key_hits:0
active_defrag_key_misses:0
tracking_total_keys:0
tracking_total_items:0
tracking_total_prefixes:0
unexpected_error_replies:60
total_error_replies:61
dump_payload_sanitizations:0
total_reads_processed:415062907
total_writes_processed:419483413
instantaneous_lock_contention:1
avg_lock_contention:0.250000
storage_provider_read_hits:0
storage_provider_read_misses:0

# Replication
role:active-replica
master_global_link_status:up
connected_masters:1
master_host:10.2.2.30
master_port:13062
master_link_status:up
master_last_io_seconds_ago:0
master_sync_in_progress:0
slave_read_repl_offset:39291105223
slave_repl_offset:39291105223
slave_priority:100
slave_read_only:0
replica_announced:1
connected_slaves:1
slave0:ip=172.17.0.1,port=6379,state=online,offset=23217116899,lag=0
master_failover_state:no-failover
master_replid:d5c363b90cc35be7739e4522d023993b0867b7e7
master_replid2:0000000000000000000000000000000000000000
master_repl_offset:23217153834
second_repl_offset:-1
repl_backlog_active:1
repl_backlog_size:1048576
repl_backlog_first_byte_offset:23216105259
repl_backlog_histlen:1048576

# CPU
used_cpu_sys:16727.780803
used_cpu_user:12209.666885
used_cpu_sys_children:17.220899
used_cpu_user_children:35.933273
server_threads:4
long_lock_waits:66
used_cpu_sys_main_thread:4372.172020
used_cpu_user_main_thread:2987.077438

# Modules

# Errorstats
errorstat_ERR:count=60
errorstat_LOADING:count=1

# Cluster
cluster_enabled:0

# Keyspace
db0:keys=4010,expires=2494,avg_ttl=14,cached_keys=4010

# KeyDB
mvcc_depth:0

Here is the info output for the instance without issue (rudi):

127.0.0.1:13062> info 
# Server
redis_version:6.3.3
redis_git_sha1:86ea5c7e
redis_git_dirty:1
redis_build_id:942bbf4eda83edde
redis_mode:standalone
os:Linux 5.15.0-73-generic x86_64
arch_bits:64
multiplexing_api:epoll
atomicvar_api:atomic-builtin
gcc_version:7.5.0
process_id:1
process_supervised:no
run_id:6aa098fbd1e98ed7a14d6ceb3e9f4e6099427cf5
tcp_port:6379
server_time_usec:1686815685014798
uptime_in_seconds:254474
uptime_in_days:2
hz:10
configured_hz:10
lru_clock:9094084
executable:/data/keydb-server
config_file:

# Clients
connected_clients:3
cluster_connections:0
maxclients:10000
client_recent_max_input_buffer:181
client_recent_max_output_buffer:0
blocked_clients:0
tracking_clients:0
clients_in_timeout_table:0
current_client_thread:0
thread_0_clients:4
thread_1_clients:0
thread_2_clients:0
thread_3_clients:0

# Memory
used_memory:5563704
used_memory_human:5.31M
used_memory_rss:19890176
used_memory_rss_human:18.97M
used_memory_peak:20035016
used_memory_peak_human:19.11M
used_memory_peak_perc:27.77%
used_memory_overhead:4644571
used_memory_startup:3216024
used_memory_dataset:919133
used_memory_dataset_perc:39.15%
allocator_allocated:6434128
allocator_active:7786496
allocator_resident:12460032
total_system_memory:67353542656
total_system_memory_human:62.73G
used_memory_lua:37888
used_memory_lua_human:37.00K
used_memory_scripts:0
used_memory_scripts_human:0B
number_of_cached_scripts:0
maxmemory:0
maxmemory_human:0B
maxmemory_policy:noeviction
allocator_frag_ratio:1.21
allocator_frag_bytes:1352368
allocator_rss_ratio:1.60
allocator_rss_bytes:4673536
rss_overhead_ratio:1.60
rss_overhead_bytes:7430144
mem_fragmentation_ratio:3.61
mem_fragmentation_bytes:14378072
mem_not_counted_for_evict:10234
mem_replication_backlog:1048576
mem_clients_slaves:20512
mem_clients_normal:61635
mem_aof_buffer:10240
mem_allocator:jemalloc-5.2.1
active_defrag_running:0
lazyfree_pending_objects:0
lazyfreed_objects:0
storage_provider:none

# Persistence
loading:0
current_cow_size:0
current_cow_size_age:0
current_fork_perc:0.00
current_save_keys_processed:0
current_save_keys_total:0
rdb_changes_since_last_save:18307
rdb_bgsave_in_progress:0
rdb_last_save_time:1686815659
rdb_last_bgsave_status:ok
rdb_last_bgsave_time_sec:0
rdb_current_bgsave_time_sec:-1
rdb_last_cow_size:1335296
aof_enabled:1
aof_rewrite_in_progress:0
aof_rewrite_scheduled:0
aof_last_rewrite_time_sec:1
aof_current_rewrite_time_sec:-1
aof_last_bgrewrite_status:ok
aof_last_write_status:ok
aof_last_cow_size:3375104
module_fork_in_progress:0
module_fork_last_cow_size:0
aof_current_size:53503249
aof_base_size:829446
aof_pending_rewrite:0
aof_buffer_length:8258
aof_rewrite_buffer_length:0
aof_pending_bio_fsync:1
aof_delayed_fsync:0

# Stats
total_connections_received:1043044
total_commands_processed:318688689
instantaneous_ops_per_sec:2209
total_net_input_bytes:23340769424
total_net_output_bytes:39376336108
instantaneous_input_kbps:159.02
instantaneous_output_kbps:267.64
rejected_connections:0
sync_full:2
sync_partial_ok:0
sync_partial_err:2
expired_keys:28165470
expired_stale_perc:0.00
expired_time_cap_reached_count:0
expire_cycle_cpu_milliseconds:0
evicted_keys:0
keyspace_hits:37180
keyspace_misses:8
pubsub_channels:0
pubsub_patterns:0
latest_fork_usec:1029
total_forks:4874
migrate_cached_sockets:0
slave_expires_tracked_keys:0
active_defrag_hits:0
active_defrag_misses:0
active_defrag_key_hits:0
active_defrag_key_misses:0
tracking_total_keys:0
tracking_total_items:0
tracking_total_prefixes:0
unexpected_error_replies:362559
total_error_replies:362561
dump_payload_sanitizations:0
total_reads_processed:157252115
total_writes_processed:156200680
instantaneous_lock_contention:1
avg_lock_contention:0.187500
storage_provider_read_hits:0
storage_provider_read_misses:0

# Replication
role:active-replica
master_global_link_status:up
connected_masters:1
master_host:10.2.2.60
master_port:13062
master_link_status:up
master_last_io_seconds_ago:0
master_sync_in_progress:0
slave_read_repl_offset:23243729863
slave_repl_offset:23243729863
slave_priority:100
slave_read_only:0
replica_announced:1
connected_slaves:1
slave0:ip=172.17.0.1,port=6379,state=online,offset=39335645161,lag=1
master_failover_state:no-failover
master_replid:95198220ff1d5218e0d3ab4827eb4cafdfaf5606
master_replid2:0000000000000000000000000000000000000000
master_repl_offset:39335906500
second_repl_offset:-1
repl_backlog_active:1
repl_backlog_size:1048576
repl_backlog_first_byte_offset:39334857925
repl_backlog_histlen:1048576

# CPU
used_cpu_sys:8319.863656
used_cpu_user:6645.456295
used_cpu_sys_children:25.666492
used_cpu_user_children:48.426543
server_threads:4
long_lock_waits:6
used_cpu_sys_main_thread:6656.645504
used_cpu_user_main_thread:5406.061082

# Modules

# Errorstats
errorstat_ERR:count=362560
errorstat_LOADING:count=1

# Cluster
cluster_enabled:0

# Keyspace
db0:keys=3719,expires=2712,avg_ttl=289,cached_keys=3719

# KeyDB
mvcc_depth:0

It should be noted that rudi instance is less active, but we had the problem with it too a week ago, we upgraded from 6.3.1 to 6.3.3 in the hope it will fix the issue when it happened.

On rex, we can see:

rdb_changes_since_last_save:14470141
rdb_bgsave_in_progress:1
rdb_last_save_time:1686789002
rdb_last_bgsave_status:ok
rdb_last_bgsave_time_sec:0
rdb_current_bgsave_time_sec:26359
rdb_last_cow_size:1380352
root@rex ~ # ps aux|grep keydb
systemd+ 2419957 11.4  0.0 334556 20208 ?        Ssl  Jun12 483:21 keydb-server *:6379
systemd+ 2948749  0.0  0.0 334556 10640 ?        S    00:31   0:00 keydb-server *:6379

Killing the stuck process:

kill 2419957

After few seconds, on rex:

127.0.0.1:13062> info persistence
# Persistence
loading:0
current_cow_size:0
current_cow_size_age:0
current_fork_perc:0.00
current_save_keys_processed:0
current_save_keys_total:0
rdb_changes_since_last_save:32396
rdb_bgsave_in_progress:0
rdb_last_save_time:1686816870
rdb_last_bgsave_status:ok
rdb_last_bgsave_time_sec:0
rdb_current_bgsave_time_sec:-1
rdb_last_cow_size:1728512
aof_enabled:1
aof_rewrite_in_progress:0
aof_rewrite_scheduled:0
aof_last_rewrite_time_sec:0
aof_current_rewrite_time_sec:-1
aof_last_bgrewrite_status:ok
aof_last_write_status:ok
aof_last_cow_size:29544448
module_fork_in_progress:0
module_fork_last_cow_size:0
aof_current_size:10898869
aof_base_size:8063487
aof_pending_rewrite:0
aof_buffer_length:0
aof_rewrite_buffer_length:0
aof_pending_bio_fsync:0
aof_delayed_fsync:0

To reproduce

Steps to reproduce the behavior and/or a minimal code sample.

Expected behavior

A description of what you expected to happen.

Additional information

Any additional information that is relevant to the problem.

@zas
Copy link
Author

zas commented Jun 15, 2023

I have an strace (before the kill) if you need it, tell me where I can send it.

@zas
Copy link
Author

zas commented Jun 15, 2023

After stuck process was killed, everything is back to normal (interval between AOF rewrites is a bit shorter though):
image

@msotheeswaran-sc
Copy link
Collaborator

hi @zas the aof file growing is caused by the background save process hanging, if this happens again, sharing a stack trace of the background save process(it is a different process created with fork() named "keydb-rdb-bgsave"), or strace would be very helpful!

@zas
Copy link
Author

zas commented Jun 23, 2023

It happened again.

The log output stopped on:

1:27:S 23 Jun 2023 02:50:33.012 * 10000 changes in 60 seconds. Saving...
1:27:S 23 Jun 2023 02:50:33.013 * Background saving started by pid 11347
1:27:S 23 Jun 2023 02:50:33.013 * Background saving started
11347:27:C 23 Jun 2023 02:50:33.033 * DB saved on disk
11347:27:C 23 Jun 2023 02:50:33.034 * RDB: 1 MB of memory used by copy-on-write
1:27:S 23 Jun 2023 02:50:33.114 * Background saving terminated with success
1:27:S 23 Jun 2023 02:51:34.098 * 10000 changes in 60 seconds. Saving...
1:27:S 23 Jun 2023 02:51:34.100 * Background saving started by pid 11348
1:27:S 23 Jun 2023 02:51:34.100 * Background saving started
11348:27:C 23 Jun 2023 02:51:34.120 * DB saved on disk
11348:27:C 23 Jun 2023 02:51:34.122 * RDB: 1 MB of memory used by copy-on-write
1:27:S 23 Jun 2023 02:51:34.201 * Background saving terminated with success
1:27:S 23 Jun 2023 02:52:35.077 * 10000 changes in 60 seconds. Saving...
1:27:S 23 Jun 2023 02:52:35.078 * Background saving started by pid 11349
1:27:S 23 Jun 2023 02:52:35.078 * Background saving started
root@rex ~ # pgrep keydb-server
87359
3006484
root@rex ~ # ps -p 3006484 -p 87359 -o pid,lstart,cmd
    PID                  STARTED CMD
  87359 Fri Jun 23 02:52:34 2023 keydb-server *:6379
3006484 Thu Jun 15 08:14:13 2023 keydb-server *:6379

root@rex ~ # strace -f -t -p 87359
strace: Process 87359 attached
07:37:08 futex(0x560608aa2320, FUTEX_WAIT_BITSET_PRIVATE, 1486575769, NULL, 0x4000000

The strace doesn't show much, the process is stuck.

A simple kill has no effect, so:

kill -9 87359

After that, log resumed:

1:27:S 23 Jun 2023 02:52:35.078 * Background saving started by pid 11349
1:27:S 23 Jun 2023 02:52:35.078 * Background saving started
11349:signal-handler (1687505888) Received SIGTERM scheduling shutdown...
1:27:S 23 Jun 2023 07:38:32.185 # Background saving cancelled
1:27:S 23 Jun 2023 07:38:32.285 * 1 changes in 3600 seconds. Saving...
1:27:S 23 Jun 2023 07:38:32.287 * Background saving started by pid 11350
1:27:S 23 Jun 2023 07:38:32.287 * Background saving started
11350:27:C 23 Jun 2023 07:38:32.316 * DB saved on disk
11350:27:C 23 Jun 2023 07:38:32.318 * RDB: 1 MB of memory used by copy-on-write
1:27:S 23 Jun 2023 07:38:32.388 * Background saving terminated with success
1:27:S 23 Jun 2023 07:38:32.488 * Starting automatic rewriting of AOF on 90143% growth
1:27:S 23 Jun 2023 07:38:32.490 * Background append only file rewriting started by pid 11351
1:27:S 23 Jun 2023 07:38:33.515 * AOF rewrite child asks to stop sending diffs.
11351:27:C 23 Jun 2023 07:38:33.515 * Parent agreed to stop sending diffs. Finalizing AOF...
11351:27:C 23 Jun 2023 07:38:33.515 * Concatenating 0.05 MB of AOF diff received from parent.
11351:27:C 23 Jun 2023 07:38:33.529 * SYNC append only file rewrite performed
11351:27:C 23 Jun 2023 07:38:33.531 * AOF rewrite: 3 MB of memory used by copy-on-write
1:27:S 23 Jun 2023 07:38:33.600 * Background AOF rewrite terminated with success
1:27:S 23 Jun 2023 07:38:33.600 * Residual parent diff successfully flushed to the rewritten AOF (0.00 MB)
1:27:S 23 Jun 2023 07:38:33.600 * Background AOF rewrite finished successfully

image

@msotheeswaran-sc
Copy link
Collaborator

The issue is clearly that the rdb process is hanging but in order to fix it I need to understand why, if you can't get the stack trace, can you provide repro instructions(your config, what commands you ran in order to see this issue) so that I can repro it and fix it?

@zas
Copy link
Author

zas commented Jun 24, 2023

Strace shows the bgsave process gets stuck, but as shown on graphs above it only happens once every X days. Actually it happened 4 times in 20 days, at different times, so it is hard to correlate with anything. It happened once on one instance, and thre times on the other one (so it doesn't look to depend on the machine).

As explained above, we run keydb in a docker container, using eqalpha/keydb:x86_64_v6.3.3 image, listening on port 13062.

One instance (10.2.2.30) has following options:

--server-threads 4 --appendonly yes --protected-mode no --active-replica yes --replicaof 10.2.2.60 13062

The other (10.2.2.60):

--server-threads 4 --appendonly yes --protected-mode no --active-replica yes --replicaof 10.2.2.30 13062

It started to happen after we upgraded to 6.3.1 (and now 6.3.3). AFAIK we never had the issue with versions prior 6.3.x.

Here is the output of one instance (the other is the same but IP):

127.0.0.1:13062> CONFIG GET *
  1) "rdbchecksum"
  2) "yes"
  3) "daemonize"
  4) "no"
  5) "lua-replicate-commands"
  6) "yes"
  7) "always-show-logo"
  8) "no"
  9) "enable-motd"
 10) "yes"
 11) "protected-mode"
 12) "no"
 13) "rdbcompression"
 14) "yes"
 15) "rdb-del-sync-files"
 16) "no"
 17) "activerehashing"
 18) "yes"
 19) "stop-writes-on-bgsave-error"
 20) "yes"
 21) "set-proc-title"
 22) "yes"
 23) "dynamic-hz"
 24) "yes"
 25) "lazyfree-lazy-eviction"
 26) "no"
 27) "lazyfree-lazy-expire"
 28) "no"
 29) "lazyfree-lazy-server-del"
 30) "no"
 31) "lazyfree-lazy-user-del"
 32) "no"
 33) "lazyfree-lazy-user-flush"
 34) "no"
 35) "repl-disable-tcp-nodelay"
 36) "no"
 37) "repl-diskless-sync"
 38) "no"
 39) "aof-rewrite-incremental-fsync"
 40) "yes"
 41) "no-appendfsync-on-rewrite"
 42) "no"
 43) "cluster-require-full-coverage"
 44) "yes"
 45) "rdb-save-incremental-fsync"
 46) "yes"
 47) "aof-load-truncated"
 48) "yes"
 49) "aof-use-rdb-preamble"
 50) "yes"
 51) "cluster-replica-no-failover"
 52) "no"
 53) "cluster-slave-no-failover"
 54) "no"
 55) "replica-lazy-flush"
 56) "no"
 57) "slave-lazy-flush"
 58) "no"
 59) "replica-serve-stale-data"
 60) "yes"
 61) "slave-serve-stale-data"
 62) "yes"
 63) "replica-read-only"
 64) "no"
 65) "slave-read-only"
 66) "no"
 67) "replica-ignore-maxmemory"
 68) "yes"
 69) "slave-ignore-maxmemory"
 70) "yes"
 71) "multi-master"
 72) "no"
 73) "jemalloc-bg-thread"
 74) "yes"
 75) "activedefrag"
 76) "no"
 77) "syslog-enabled"
 78) "no"
 79) "cluster-enabled"
 80) "no"
 81) "appendonly"
 82) "yes"
 83) "cluster-allow-reads-when-down"
 84) "no"
 85) "delete-on-evict"
 86) "no"
 87) "use-fork"
 88) "yes"
 89) "io-threads-do-reads"
 90) "no"
 91) "time-thread-priority"
 92) "no"
 93) "prefetch-enabled"
 94) "yes"
 95) "allow-rdb-resize-op"
 96) "yes"
 97) "crash-log-enabled"
 98) "yes"
 99) "crash-memcheck-enabled"
100) "yes"
101) "use-exit-on-panic"
102) "no"
103) "disable-thp"
104) "yes"
105) "cluster-allow-replica-migration"
106) "yes"
107) "replica-announced"
108) "yes"
109) "enable-async-commands"
110) "no"
111) "multithread-load-enabled"
112) "no"
113) "active-client-balancing"
114) "yes"
115) "aclfile"
116) ""
117) "unixsocket"
118) ""
119) "pidfile"
120) ""
121) "replica-announce-ip"
122) ""
123) "slave-announce-ip"
124) ""
125) "masteruser"
126) ""
127) "cluster-announce-ip"
128) ""
129) "syslog-ident"
130) "redis"
131) "dbfilename"
132) "dump.rdb"
133) "db-s3-object"
134) ""
135) "appendfilename"
136) "appendonly.aof"
137) "server_cpulist"
138) ""
139) "bio_cpulist"
140) ""
141) "aof_rewrite_cpulist"
142) ""
143) "bgsave_cpulist"
144) ""
145) "storage-provider-options"
146) ""
147) "ignore-warnings"
148) ""
149) "proc-title-template"
150) "{title} {listen-addr} {server-mode}"
151) "masterauth"
152) ""
153) "requirepass"
154) ""
155) "supervised"
156) "no"
157) "syslog-facility"
158) "local0"
159) "repl-diskless-load"
160) "disabled"
161) "loglevel"
162) "notice"
163) "maxmemory-policy"
164) "noeviction"
165) "appendfsync"
166) "everysec"
167) "storage-cache-mode"
168) "writethrough"
169) "oom-score-adj"
170) "no"
171) "acl-pubsub-default"
172) "allchannels"
173) "sanitize-dump-payload"
174) "no"
175) "databases"
176) "16"
177) "port"
178) "6379"
179) "auto-aof-rewrite-percentage"
180) "100"
181) "cluster-replica-validity-factor"
182) "10"
183) "cluster-slave-validity-factor"
184) "10"
185) "list-max-ziplist-size"
186) "-2"
187) "tcp-keepalive"
188) "300"
189) "cluster-migration-barrier"
190) "1"
191) "active-defrag-cycle-min"
192) "1"
193) "active-defrag-cycle-max"
194) "25"
195) "active-defrag-threshold-lower"
196) "10"
197) "active-defrag-threshold-upper"
198) "100"
199) "lfu-log-factor"
200) "10"
201) "lfu-decay-time"
202) "1"
203) "replica-priority"
204) "100"
205) "slave-priority"
206) "100"
207) "repl-diskless-sync-delay"
208) "5"
209) "maxmemory-samples"
210) "16"
211) "maxmemory-eviction-tenacity"
212) "10"
213) "timeout"
214) "0"
215) "replica-announce-port"
216) "0"
217) "slave-announce-port"
218) "0"
219) "tcp-backlog"
220) "511"
221) "cluster-announce-bus-port"
222) "0"
223) "cluster-announce-port"
224) "0"
225) "cluster-announce-tls-port"
226) "0"
227) "repl-timeout"
228) "60"
229) "repl-ping-replica-period"
230) "10"
231) "repl-ping-slave-period"
232) "10"
233) "list-compress-depth"
234) "0"
235) "rdb-key-save-delay"
236) "0"
237) "key-load-delay"
238) "0"
239) "active-expire-effort"
240) "1"
241) "hz"
242) "10"
243) "min-replicas-to-write"
244) "0"
245) "min-slaves-to-write"
246) "0"
247) "min-replicas-max-lag"
248) "10"
249) "min-slaves-max-lag"
250) "10"
251) "min-clients-per-thread"
252) "20"
253) "storage-flush-period"
254) "500"
255) "replica-quorum"
256) "-1"
257) "replica-weighting-factor"
258) "2"
259) "maxclients"
260) "10000"
261) "loading-process-events-interval-keys"
262) "8192"
263) "active-defrag-max-scan-fields"
264) "1000"
265) "slowlog-max-len"
266) "128"
267) "acllog-max-len"
268) "128"
269) "lua-time-limit"
270) "5000"
271) "cluster-node-timeout"
272) "15000"
273) "slowlog-log-slower-than"
274) "10000"
275) "latency-monitor-threshold"
276) "0"
277) "proto-max-bulk-len"
278) "536870912"
279) "stream-node-max-entries"
280) "100"
281) "repl-backlog-size"
282) "1048576"
283) "repl-backlog-disk-reserve"
284) "0"
285) "max-snapshot-slip"
286) "400"
287) "max-rand-count"
288) "4611686018427387903"
289) "maxmemory"
290) "0"
291) "maxstorage"
292) "0"
293) "hash-max-ziplist-entries"
294) "512"
295) "set-max-intset-entries"
296) "512"
297) "zset-max-ziplist-entries"
298) "128"
299) "active-defrag-ignore-bytes"
300) "104857600"
301) "hash-max-ziplist-value"
302) "64"
303) "stream-node-max-bytes"
304) "4096"
305) "zset-max-ziplist-value"
306) "64"
307) "hll-sparse-max-bytes"
308) "3000"
309) "tracking-table-max-keys"
310) "1000000"
311) "client-query-buffer-limit"
312) "1073741824"
313) "repl-backlog-ttl"
314) "3600"
315) "auto-aof-rewrite-min-size"
316) "67108864"
317) "loading-process-events-interval-bytes"
318) "2097152"
319) "multi-master-no-forward"
320) "no"
321) "allow-write-during-load"
322) "no"
323) "force-backlog-disk-reserve"
324) "no"
325) "soft-shutdown"
326) "no"
327) "semi-ordered-set-bucket-size"
328) "0"
329) "tls-port"
330) "0"
331) "tls-session-cache-size"
332) "20480"
333) "tls-session-cache-timeout"
334) "300"
335) "tls-cluster"
336) "no"
337) "tls-replication"
338) "no"
339) "tls-auth-clients"
340) "yes"
341) "tls-prefer-server-ciphers"
342) "no"
343) "tls-session-caching"
344) "yes"
345) "tls-rotation"
346) "no"
347) "tls-cert-file"
348) ""
349) "tls-key-file"
350) ""
351) "tls-key-file-pass"
352) ""
353) "tls-client-cert-file"
354) ""
355) "tls-client-key-file"
356) ""
357) "tls-client-key-file-pass"
358) ""
359) "tls-dh-params-file"
360) ""
361) "tls-ca-cert-file"
362) ""
363) "tls-ca-cert-dir"
364) ""
365) "tls-protocols"
366) ""
367) "tls-ciphers"
368) ""
369) "tls-ciphersuites"
370) ""
371) "logfile"
372) ""
373) "watchdog-period"
374) "0"
375) "dir"
376) "/data"
377) "save"
378) "3600 1 300 100 60 10000"
379) "client-output-buffer-limit"
380) "normal 0 0 0 slave 268435456 67108864 60 pubsub 33554432 8388608 60"
381) "unixsocketperm"
382) "0"
383) "slaveof"
384) "10.2.2.30 13062"
385) "notify-keyspace-events"
386) ""
387) "bind"
388) ""
389) "oom-score-adj-values"
390) "0 200 800"
391) "active-replica"
392) "yes"
393) "tls-allowlist"
394) (empty list or set)

@msotheeswaran-sc
Copy link
Collaborator

@zas can you also share the output of info commandstats

@zas
Copy link
Author

zas commented Jun 26, 2023

On rex:

# Commandstats
cmdstat_expire:calls=26274067,usec=113300075,usec_per_call=4.31,rejected_calls=0,failed_calls=0
cmdstat_rreplay:calls=302310345,usec=1137827034,usec_per_call=3.76,rejected_calls=0,failed_calls=0
cmdstat_pexpireat:calls=257,usec=1081,usec_per_call=4.21,rejected_calls=0,failed_calls=0
cmdstat_KEYDB.MVCCRESTORE:calls=1816,usec=3950,usec_per_call=2.18,rejected_calls=0,failed_calls=0
cmdstat_set:calls=1086,usec=2957,usec_per_call=2.72,rejected_calls=0,failed_calls=0
cmdstat_get:calls=6006,usec=53639,usec_per_call=8.93,rejected_calls=0,failed_calls=0
cmdstat_pttl:calls=98572554,usec=223903406,usec_per_call=2.27,rejected_calls=0,failed_calls=0
cmdstat_psync:calls=1,usec=34,usec_per_call=34.00,rejected_calls=0,failed_calls=0
cmdstat_incr:calls=124840937,usec=557345040,usec_per_call=4.46,rejected_calls=38,failed_calls=0
cmdstat_keys:calls=46,usec=9520,usec_per_call=206.96,rejected_calls=0,failed_calls=0
cmdstat_ping:calls=1041499,usec=850071,usec_per_call=0.82,rejected_calls=0,failed_calls=0
cmdstat_replconf:calls=252224,usec=249965,usec_per_call=0.99,rejected_calls=0,failed_calls=0
cmdstat_config:calls=3,usec=193,usec_per_call=64.33,rejected_calls=0,failed_calls=1
cmdstat_replping:calls=1,usec=1,usec_per_call=1.00,rejected_calls=4,failed_calls=0
cmdstat_info:calls=4236,usec=773784,usec_per_call=182.67,rejected_calls=0,failed_calls=0
cmdstat_del:calls=1019,usec=3168,usec_per_call=3.11,rejected_calls=0,failed_calls=0
cmdstat_command:calls=2,usec=1577,usec_per_call=788.50,rejected_calls=0,failed_calls=0

On rudi:

# Commandstats
cmdstat_pexpireat:calls=37164385,usec=133032392,usec_per_call=3.58,rejected_calls=0,failed_calls=0
cmdstat_replping:calls=2,usec=1,usec_per_call=0.50,rejected_calls=0,failed_calls=0
cmdstat_keys:calls=1,usec=27,usec_per_call=27.00,rejected_calls=0,failed_calls=0
cmdstat_incr:calls=175188824,usec=624477071,usec_per_call=3.56,rejected_calls=0,failed_calls=0
cmdstat_del:calls=1464,usec=4830,usec_per_call=3.30,rejected_calls=0,failed_calls=0
cmdstat_ping:calls=1457426,usec=1476401,usec_per_call=1.01,rejected_calls=0,failed_calls=0
cmdstat_command:calls=2,usec=1651,usec_per_call=825.50,rejected_calls=0,failed_calls=0
cmdstat_pttl:calls=3469,usec=7675,usec_per_call=2.21,rejected_calls=0,failed_calls=0
cmdstat_psync:calls=2,usec=982,usec_per_call=491.00,rejected_calls=0,failed_calls=0
cmdstat_KEYDB.MVCCRESTORE:calls=3003,usec=3681,usec_per_call=1.23,rejected_calls=0,failed_calls=0
cmdstat_rreplay:calls=212466832,usec=1831690797,usec_per_call=8.62,rejected_calls=0,failed_calls=0
cmdstat_expire:calls=781,usec=3641,usec_per_call=4.66,rejected_calls=0,failed_calls=0
cmdstat_replconf:calls=352665,usec=363525,usec_per_call=1.03,rejected_calls=0,failed_calls=0
cmdstat_set:calls=1554,usec=3831,usec_per_call=2.47,rejected_calls=0,failed_calls=0
cmdstat_info:calls=5931,usec=984990,usec_per_call=166.07,rejected_calls=0,failed_calls=0

Note: that's after a restart because of stuck process; so normal operation atm.

EDIT: it happened again (rex bgsave stuck), here are info commandstats and info replication during the problem:

On rex;

# Commandstats
cmdstat_expire:calls=30992163,usec=133672803,usec_per_call=4.31,rejected_calls=0,failed_calls=0
cmdstat_rreplay:calls=359691689,usec=1354511775,usec_per_call=3.77,rejected_calls=0,failed_calls=0
cmdstat_pexpireat:calls=257,usec=1081,usec_per_call=4.21,rejected_calls=0,failed_calls=0
cmdstat_KEYDB.MVCCRESTORE:calls=1816,usec=3950,usec_per_call=2.18,rejected_calls=0,failed_calls=0
cmdstat_set:calls=1293,usec=3494,usec_per_call=2.70,rejected_calls=0,failed_calls=0
cmdstat_get:calls=7095,usec=63556,usec_per_call=8.96,rejected_calls=0,failed_calls=0
cmdstat_pttl:calls=117820476,usec=267721013,usec_per_call=2.27,rejected_calls=0,failed_calls=0
cmdstat_psync:calls=1,usec=34,usec_per_call=34.00,rejected_calls=0,failed_calls=0
cmdstat_incr:calls=148805480,usec=664188625,usec_per_call=4.46,rejected_calls=38,failed_calls=0
cmdstat_keys:calls=54,usec=11182,usec_per_call=207.07,rejected_calls=0,failed_calls=0
cmdstat_ping:calls=1251575,usec=1021732,usec_per_call=0.82,rejected_calls=0,failed_calls=0
cmdstat_replconf:calls=303097,usec=301231,usec_per_call=0.99,rejected_calls=0,failed_calls=0
cmdstat_config:calls=3,usec=193,usec_per_call=64.33,rejected_calls=0,failed_calls=1
cmdstat_replping:calls=1,usec=1,usec_per_call=1.00,rejected_calls=4,failed_calls=0
cmdstat_info:calls=5092,usec=932034,usec_per_call=183.04,rejected_calls=0,failed_calls=0
cmdstat_del:calls=1216,usec=3764,usec_per_call=3.10,rejected_calls=0,failed_calls=0
cmdstat_command:calls=3,usec=2348,usec_per_call=782.67,rejected_calls=0,failed_calls=0

# Replication
role:active-replica
master_global_link_status:up
connected_masters:1
master_host:10.2.2.30
master_port:13062
master_link_status:up
master_last_io_seconds_ago:0
master_sync_in_progress:0
slave_read_repl_offset:59794247294
slave_repl_offset:59794247294
slave_priority:100
slave_read_only:0
replica_announced:1
connected_slaves:1
slave0:ip=172.17.0.1,port=6379,state=online,offset=26446893231,lag=1
master_failover_state:no-failover
master_replid:4d3ac7ffaded868885573a0d26de4aaecacd2b55
master_replid2:0000000000000000000000000000000000000000
master_repl_offset:26447005901
second_repl_offset:-1
repl_backlog_active:1
repl_backlog_size:1048576
repl_backlog_first_byte_offset:26445957326
repl_backlog_histlen:1048576

On rudi:

# Commandstats
cmdstat_pexpireat:calls=41872956,usec=150219500,usec_per_call=3.59,rejected_calls=0,failed_calls=0
cmdstat_replping:calls=2,usec=1,usec_per_call=0.50,rejected_calls=0,failed_calls=0
cmdstat_keys:calls=1,usec=27,usec_per_call=27.00,rejected_calls=0,failed_calls=0
cmdstat_incr:calls=199109740,usec=711296427,usec_per_call=3.57,rejected_calls=0,failed_calls=0
cmdstat_del:calls=1661,usec=5480,usec_per_call=3.30,rejected_calls=0,failed_calls=0
cmdstat_ping:calls=1667183,usec=1691937,usec_per_call=1.01,rejected_calls=0,failed_calls=0
cmdstat_command:calls=3,usec=2172,usec_per_call=724.00,rejected_calls=0,failed_calls=0
cmdstat_pttl:calls=3469,usec=7675,usec_per_call=2.21,rejected_calls=0,failed_calls=0
cmdstat_psync:calls=2,usec=982,usec_per_call=491.00,rejected_calls=0,failed_calls=0
cmdstat_KEYDB.MVCCRESTORE:calls=3003,usec=3681,usec_per_call=1.23,rejected_calls=0,failed_calls=0
cmdstat_rreplay:calls=241111960,usec=2084740319,usec_per_call=8.65,rejected_calls=0,failed_calls=0
cmdstat_expire:calls=781,usec=3641,usec_per_call=4.66,rejected_calls=0,failed_calls=0
cmdstat_replconf:calls=403435,usec=416659,usec_per_call=1.03,rejected_calls=0,failed_calls=0
cmdstat_set:calls=1761,usec=4323,usec_per_call=2.45,rejected_calls=0,failed_calls=0
cmdstat_info:calls=6785,usec=1129325,usec_per_call=166.44,rejected_calls=0,failed_calls=0

# Replication
role:active-replica
master_global_link_status:up
connected_masters:1
master_host:10.2.2.60
master_port:13062
master_link_status:up
master_last_io_seconds_ago:0
master_sync_in_progress:0
slave_read_repl_offset:26443434392
slave_repl_offset:26443434392
slave_priority:100
slave_read_only:0
replica_announced:1
connected_slaves:1
slave0:ip=172.17.0.1,port=6379,state=online,offset=59788158543,lag=0
master_failover_state:no-failover
master_replid:b72468b46367bd82206f89f948e2cfc8e7c918a3
master_replid2:0000000000000000000000000000000000000000
master_repl_offset:59788225569
second_repl_offset:-1
repl_backlog_active:1
repl_backlog_size:1048576
repl_backlog_first_byte_offset:59787176994
repl_backlog_histlen:1048576

@kitobelix
Copy link

Hey guys, just had this issue. I'm also using Keydb 6.3.3 on docker (official image) and discovered this might be a bug. I will see what's going on as soon as I find some spare time to make something. Meanwhile here is the output of strace -i for some commands:


strace -i keydb-cli bgsave
[00007f26a389dae7] execve("/usr/local/bin/keydb-cli", ["keydb-cli", "bgsave"], 0x7ffe39470550 /* 10 vars */) = 0
[00007f0e3e61c0a9] brk(NULL)            = 0x55803df7f000
[00007f0e3e60f96e] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61d007] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=10904, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 10904, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f0e3e826000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\16\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=14560, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e824000
[00007f0e3e61d123] mmap(NULL, 2109712, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3e3fc000
[00007f0e3e61d1d7] mprotect(0x7f0e3e3ff000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3e5fe000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f0e3e5fe000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/librt.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\"\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=31680, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2128864, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3e1f4000
[00007f0e3e61d1d7] mprotect(0x7f0e3e1fb000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3e3fa000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7f0e3e3fa000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libcurl.so.4", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`\305\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=534984, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2631048, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3df71000
[00007f0e3e61d1d7] mprotect(0x7f0e3dff0000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3e1f0000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7f000) = 0x7f0e3e1f0000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libssl.so.1.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\325\1\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=577312, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2673024, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3dce4000
[00007f0e3e61d1d7] mprotect(0x7f0e3dd65000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3df64000, 53248, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x80000) = 0x7f0e3df64000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\200\7\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=2921312, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 5029728, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3d818000
[00007f0e3e61d1d7] mprotect(0x7f0e3dab4000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3dcb3000, 188416, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x29b000) = 0x7f0e3dcb3000
[00007f0e3e61d123] mmap(0x7f0e3dce1000, 12128, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3dce1000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libgcc_s.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300*\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=96616, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2192432, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3d600000
[00007f0e3e61d1d7] mprotect(0x7f0e3d617000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3d816000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000)= 0x7f0e3d816000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000b\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0755, st_size=144976, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e822000
[00007f0e3e61d123] mmap(NULL, 2221184, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3d3e1000
[00007f0e3e61d1d7] mprotect(0x7f0e3d3fb000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3d5fa000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19000)= 0x7f0e3d5fa000
[00007f0e3e61d123] mmap(0x7f0e3d5fc000, 13440, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3d5fc000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240\35\2\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0755, st_size=2030928, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 4131552, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3cff0000
[00007f0e3e61d1d7] mprotect(0x7f0e3d1d7000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3d3d7000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1e7000) = 0x7f0e3d3d7000
[00007f0e3e61d123] mmap(0x7f0e3d3dd000, 15072, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3d3dd000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libstdc++.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\304\10\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=1594864, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 3702848, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3cc67000
[00007f0e3e61d1d7] mprotect(0x7f0e3cde0000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3cfe0000, 49152, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x179000) = 0x7f0e3cfe0000
[00007f0e3e61d123] mmap(0x7f0e3cfec000, 12352, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3cfec000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libm.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\272\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=1700792, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 3789144, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3c8c9000
[00007f0e3e61d1d7] mprotect(0x7f0e3ca66000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3cc65000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19c000) = 0x7f0e3cc65000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libnghttp2.so.14", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200H\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=153352, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2248560, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3c6a4000
[00007f0e3e61d1d7] mprotect(0x7f0e3c6c7000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3c8c6000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x22000) = 0x7f0e3c8c6000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libidn2.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\360\24\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=116656, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2211864, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3c487000
[00007f0e3e61d1d7] mprotect(0x7f0e3c4a3000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3c6a2000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1b000)= 0x7f0e3c6a2000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/librtmp.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200J\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=113584, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e820000
[00007f0e3e61d123] mmap(NULL, 2208688, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3c26b000
[00007f0e3e61d1d7] mprotect(0x7f0e3c286000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3c485000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1a000)= 0x7f0e3c485000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libpsl.so.5", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\22\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=55136, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2150504, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3c05d000
[00007f0e3e61d1d7] mprotect(0x7f0e3c06a000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3c269000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xc000) = 0x7f0e3c269000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libgssapi_krb5.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\266\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=305456, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2401088, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3be12000
[00007f0e3e61d1d7] mprotect(0x7f0e3be5a000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3c059000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x47000) = 0x7f0e3c059000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libldap_r-2.4.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\321\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=327024, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2431720, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3bbc0000
[00007f0e3e61d1d7] mprotect(0x7f0e3bc0e000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3be0d000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4d000) = 0x7f0e3be0d000
[00007f0e3e61d123] mmap(0x7f0e3be10000, 6888, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3be10000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/liblber-2.4.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`*\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=55544, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2150888, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3b9b2000
[00007f0e3e61d1d7] mprotect(0x7f0e3b9bf000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3bbbe000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xc000) = 0x7f0e3bbbe000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libz.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\37\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=116960, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2212016, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3b795000
[00007f0e3e61d1d7] mprotect(0x7f0e3b7b1000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3b9b0000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1b000)= 0x7f0e3b9b0000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e81e000
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libunistring.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\365\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=1562664, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 3660040, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3b417000
[00007f0e3e61d1d7] mprotect(0x7f0e3b591000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3b791000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x17a000) = 0x7f0e3b791000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libgnutls.so.30", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@\210\2\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=1461856, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 3562312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3b0b1000
[00007f0e3e61d1d7] mprotect(0x7f0e3b209000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3b409000, 53248, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x158000) = 0x7f0e3b409000
[00007f0e3e61d123] mmap(0x7f0e3b416000, 2888, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3b416000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libhogweed.so.4", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220o\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=219976, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2315032, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3ae7b000
[00007f0e3e61d1d7] mprotect(0x7f0e3aeaf000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3b0af000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x34000)= 0x7f0e3b0af000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libnettle.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\201\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=219304, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2314384, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3ac45000
[00007f0e3e61d1d7] mprotect(0x7f0e3ac79000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3ae78000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x33000) = 0x7f0e3ae78000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libgmp.so.10", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\223\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=526688, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2621888, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3a9c4000
[00007f0e3e61d1d7] mprotect(0x7f0e3aa43000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3ac43000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7f000)= 0x7f0e3ac43000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libkrb5.so.3", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\27\2\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=877056, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2972896, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3a6ee000
[00007f0e3e61d1d7] mprotect(0x7f0e3a7b4000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3a9b4000, 65536, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xc6000) = 0x7f0e3a9b4000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e81c000
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libk5crypto.so.3", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0PC\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=199104, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2297976, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3a4bc000
[00007f0e3e61d1d7] mprotect(0x7f0e3a4ea000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3a6ea000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2e000) = 0x7f0e3a6ea000
[00007f0e3e61d123] mmap(0x7f0e3a6ed000, 120, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3a6ed000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libcom_err.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\23\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=14248, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2109608, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3a2b8000
[00007f0e3e61d1d7] mprotect(0x7f0e3a2bb000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3a4ba000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f0e3a4ba000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libkrb5support.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@'\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=43616, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2139080, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3a0ad000
[00007f0e3e61d1d7] mprotect(0x7f0e3a0b7000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3a2b6000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x9000) = 0x7f0e3a2b6000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libresolv.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\00008\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=97072, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2202240, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e39e93000
[00007f0e3e61d1d7] mprotect(0x7f0e39eaa000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3a0a9000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000)= 0x7f0e3a0a9000
[00007f0e3e61d123] mmap(0x7f0e3a0ab000, 6784, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3a0ab000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libsasl2.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340*\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=109296, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2204648, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e39c78000
[00007f0e3e61d1d7] mprotect(0x7f0e39c91000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e39e91000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19000)= 0x7f0e39e91000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libgssapi.so.3", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \333\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=265712, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2361240, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e39a37000
[00007f0e3e61d1d7] mprotect(0x7f0e39a75000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e39c74000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3d000) = 0x7f0e39c74000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libp11-kit.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\263\2\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=1237640, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e81a000
[00007f0e3e61d123] mmap(NULL, 3334512, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e39708000
[00007f0e3e61d1d7] mprotect(0x7f0e39822000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e39a22000, 81920, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x11a000) = 0x7f0e39a22000
[00007f0e3e61d123] mmap(0x7f0e39a36000, 368, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e39a36000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libtasn1.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220)\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=75776, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2171592, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e394f5000
[00007f0e3e61d1d7] mprotect(0x7f0e39506000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e39706000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x11000)= 0x7f0e39706000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libkeyutils.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\22\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=14256, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2109456, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e392f1000
[00007f0e3e61d1d7] mprotect(0x7f0e392f4000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e394f3000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f0e394f3000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libheimntlm.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\360)\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=35360, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2130512, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e390e8000
[00007f0e3e61d1d7] mprotect(0x7f0e390f0000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e392ef000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x7f0e392ef000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libkrb5.so.26", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\316\1\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=573464, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2671504, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e38e5b000
[00007f0e3e61d1d7] mprotect(0x7f0e38ee2000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e390e1000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x86000) = 0x7f0e390e1000
[00007f0e3e61d123] mmap(0x7f0e390e7000, 912, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e390e7000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libasn1.so.8", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\233\1\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=661696, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e818000
[00007f0e3e61d123] mmap(NULL, 2756848, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e38bb9000
[00007f0e3e61d1d7] mprotect(0x7f0e38c57000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e38e57000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x9e000) = 0x7f0e38e57000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libhcrypto.so.4", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`s\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=217560, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2316904, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e38983000
[00007f0e3e61d1d7] mprotect(0x7f0e389b6000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e38bb5000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x32000) = 0x7f0e38bb5000
[00007f0e3e61d123] mmap(0x7f0e38bb8000, 2664, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e38bb8000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libroken.so.18", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300N\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=88680, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2184240, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3876d000
[00007f0e3e61d1d7] mprotect(0x7f0e38782000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e38981000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x14000)= 0x7f0e38981000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libffi.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@\27\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=31032, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2127368, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e38565000
[00007f0e3e61d1d7] mprotect(0x7f0e3856c000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3876b000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7f0e3876b000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libwind.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \16\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=165880, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2261040, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3833c000
[00007f0e3e61d1d7] mprotect(0x7f0e38363000, 2097152, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e38563000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x27000)= 0x7f0e38563000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libheimbase.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340(\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=60400, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2156760, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e3812d000
[00007f0e3e61d1d7] mprotect(0x7f0e3813b000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e3833a000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xd000) = 0x7f0e3833a000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libhx509.so.5", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\20\1\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=300888, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e816000
[00007f0e3e61d123] mmap(NULL, 2397224, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e37ee3000
[00007f0e3e61d1d7] mprotect(0x7f0e37f29000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e38128000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x45000) = 0x7f0e38128000
[00007f0e3e61d123] mmap(0x7f0e3812c000, 1064, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e3812c000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libsqlite3.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300\314\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=1082648, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 3179736, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e37bda000
[00007f0e3e61d1d7] mprotect(0x7f0e37cde000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e37edd000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x103000) = 0x7f0e37edd000
[00007f0e3e61d123] mmap(0x7f0e37ee2000, 1240, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e37ee2000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e6182d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f0e3e61cebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libcrypt.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3e61cf84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\r\0\0\0\0\0\0"..., 832) = 832
[00007f0e3e61ce23] fstat(3, {st_mode=S_IFREG|0644, st_size=39208, ...}) = 0
[00007f0e3e61d123] mmap(NULL, 2322976, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0e379a2000
[00007f0e3e61d1d7] mprotect(0x7f0e379ab000, 2093056, PROT_NONE) = 0
[00007f0e3e61d123] mmap(0x7f0e37baa000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x8000) = 0x7f0e37baa000
[00007f0e3e61d123] mmap(0x7f0e37bac000, 184864, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0e37bac000
[00007f0e3e61d0b7] close(3)             = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e814000
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e812000
[00007f0e3e61d123] mmap(NULL, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e80e000
[00007f0e3e601024] arch_prctl(ARCH_SET_FS, 0x7f0e3e810040) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3d3d7000, 16384, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e37baa000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3e5fe000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3d5fa000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3cc65000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e37edd000, 12288, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3a4ba000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3a0a9000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e38981000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e38e57000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3833a000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e38bb5000, 8192, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e38563000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e38128000, 12288, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3876b000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e390e1000, 16384, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e392ef000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e394f3000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e39706000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e39a22000, 40960, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e39c74000, 8192, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e39e91000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3a2b6000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3a6ea000, 8192, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3a9b4000, 57344, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3ac43000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3ae78000, 8192, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3b0af000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3b791000, 12288, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3c6a2000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3b9b0000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3b409000, 49152, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3bbbe000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3be0d000, 8192, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3c059000, 8192, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3c269000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3c485000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3c8c6000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3d816000, 4096, PROT_READ) = 0
[00007f0e3e61d123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0e3e80c000
[00007f0e3e61d1d7] mprotect(0x7f0e3cfe0000, 40960, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3dcb3000, 180224, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3df64000, 36864, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3e1f0000, 12288, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3e3fa000, 4096, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x55803d8e6000, 24576, PROT_READ) = 0
[00007f0e3e61d1d7] mprotect(0x7f0e3e829000, 4096, PROT_READ) = 0
[00007f0e3e61d1b7] munmap(0x7f0e3e826000, 10904) = 0
[00007f0e3d3e6eb5] set_tid_address(0x7f0e3e810310) = 60
[00007f0e3d3e6f17] set_robust_list(0x7f0e3e810320, 24) = 0
[00007f0e3d3f3a4d] rt_sigaction(SIGRTMIN, {sa_handler=0x7f0e3d3e6cb0, sa_mask=[], sa_flags=SA_RESTORER|SA_SIGINFO, sa_restorer=0x7f0e3d3f3980}, NULL, 8) = 0
[00007f0e3d3f3a4d] rt_sigaction(SIGRT_1, {sa_handler=0x7f0e3d3e6d50, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART|SA_SIGINFO, sa_restorer=0x7f0e3d3f3980}, NULL, 8) = 0
[00007f0e3d3e6ff3] rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
[00007f0e3d105be0] prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
[00007f0e3d3f092e] futex(0x7f0e39a360e0, FUTEX_WAKE_PRIVATE, 2147483647) = 0
[00007f0e3d101cb7] readlink("/etc/malloc.conf", 0x7ffc30c0bdb0, 4096) = -1 ENOENT (No such file or directory)
[00007f0e3d10b539] open("/proc/sys/vm/overcommit_memory", O_RDONLY|O_CLOEXEC) = 3
[00007f0e3d10b539] read(3, "0", 1)      = 1
[00007f0e3d10b539] close(3)             = 0
[00007f0e3d10b539] open("/sys/kernel/mm/transparent_hugepage/enabled", O_RDONLY) = 3
[00007f0e3d10b539] read(3, "always [madvise] never\n", 24) = 23
[00007f0e3d10b539] close(3)             = 0
[00007f0e3d10b713] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f0e3e828000
[00007f0e3d10b8b7] madvise(0x7f0e3e828000, 4096, MADV_FREE) = 0
[00007f0e3d10b7b7] munmap(0x7f0e3e828000, 4096) = 0
[00007f0e3d10b713] mmap(NULL, 2097152, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f0e377a2000
[00007f0e3d10b7b7] munmap(0x7f0e377a2000, 2097152) = 0
[00007f0e3d10b713] mmap(NULL, 4190208, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f0e375a3000
[00007f0e3d10b7b7] munmap(0x7f0e375a3000, 380928) = 0
[00007f0e3d10b7b7] munmap(0x7f0e37800000, 1712128) = 0
[00007f0e3d1060f9] brk(NULL)            = 0x55803df7f000
[00007f0e3d10b713] mmap(NULL, 2097152, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f0e37400000
[00007f0e3d10b713] mmap(NULL, 4194304, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f0e37000000
[00007f0e3d3f3c33] sched_getaffinity(60, 128, [0, 1]) = 8
[00007f0e3d0348c4] getrandom("\xe2", 1, GRND_NONBLOCK) = 1
[00007f0e3d0ff725] stat("/etc/gnutls/default-priorities", 0x7ffc30c0ced0) = -1 ENOENT (No such file or directory)
[00007f0e3d1058e8] ioctl(1, TCGETS, {B38400 opost isig icanon echo ...}) = 0
[00007f0e3d0d5817] getpid()             = 60
[00007f0e3d112c57] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3
[00007f0e3d3f25fe] fcntl(3, F_GETFL)    = 0x2 (flags O_RDWR)
[00007f0e3d3f25fe] fcntl(3, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[00007f0e3d3f2831] connect(3, {sa_family=AF_INET, sin_port=htons(6379), sin_addr=inet_addr("127.0.0.1")}, 16) = -1 EINPROGRESS (Operation now in progress)
[00007f0e3d104b84] poll([{fd=3, events=POLLOUT}], 1, -1) = 1 ([{fd=3, revents=POLLOUT}])
[00007f0e3d3f2831] connect(3, {sa_family=AF_INET, sin_port=htons(6379), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
[00007f0e3d112bfa] setsockopt(3, SOL_TCP, TCP_NODELAY, [1], 4) = 0
[00007f0e3d3f25fe] fcntl(3, F_GETFL)    = 0x802 (flags O_RDWR|O_NONBLOCK)
[00007f0e3d3f25fe] fcntl(3, F_SETFL, O_RDWR) = 0
[00007f0e3d112bfa] setsockopt(3, SOL_SOCKET, SO_KEEPALIVE, [1], 4) = 0
[00007f0e3d112bfa] setsockopt(3, SOL_TCP, TCP_KEEPIDLE, [15], 4) = 0
[00007f0e3d112bfa] setsockopt(3, SOL_TCP, TCP_KEEPINTVL, [5], 4) = 0
[00007f0e3d112bfa] setsockopt(3, SOL_TCP, TCP_KEEPCNT, [3], 4) = 0
[00007f0e3d3f2a5a] sendto(3, "*1\r\n$6\r\nbgsave\r\n", 16, 0, NULL, 0) = 16
[00007f0e3d3f28da] recvfrom(3, "+Background saving started\r\n", 16384, 0, NULL, NULL) = 28
[00007f0e3d0ff773] fstat(1, {st_mode=S_IFCHR|0620, st_rdev=makedev(136, 1), ...}) = 0
[00007f0e3d100104] write(1, "Background saving started\n", 26Background saving started
) = 26
[00007f0e3d0d4ab6] exit_group(0)        = ?
[????????????????] +++ exited with 0 +++


strace -i keydb-cli save
[00007fcf0061bae7] execve("/usr/local/bin/keydb-cli", ["keydb-cli", "save"], 0x7ffe7276b5a0 /* 10 vars */) = 0
[00007f64ad6af0a9] brk(NULL)            = 0x55b847352000
[00007f64ad6a296e] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6b0007] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=10904, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 10904, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f64ad8b9000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\16\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=14560, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8b7000
[00007f64ad6b0123] mmap(NULL, 2109712, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ad48f000
[00007f64ad6b01d7] mprotect(0x7f64ad492000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ad691000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f64ad691000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/librt.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\"\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=31680, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2128864, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ad287000
[00007f64ad6b01d7] mprotect(0x7f64ad28e000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ad48d000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7f64ad48d000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libcurl.so.4", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`\305\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=534984, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2631048, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ad004000
[00007f64ad6b01d7] mprotect(0x7f64ad083000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ad283000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7f000) = 0x7f64ad283000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libssl.so.1.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\325\1\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=577312, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2673024, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64acd77000
[00007f64ad6b01d7] mprotect(0x7f64acdf8000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64acff7000, 53248, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x80000) = 0x7f64acff7000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libcrypto.so.1.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\200\7\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=2921312, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 5029728, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ac8ab000
[00007f64ad6b01d7] mprotect(0x7f64acb47000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64acd46000, 188416, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x29b000) = 0x7f64acd46000
[00007f64ad6b0123] mmap(0x7f64acd74000, 12128, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64acd74000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libgcc_s.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300*\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=96616, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2192432, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ac693000
[00007f64ad6b01d7] mprotect(0x7f64ac6aa000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ac8a9000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000) = 0x7f64ac8a9000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000b\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0755, st_size=144976, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8b5000
[00007f64ad6b0123] mmap(NULL, 2221184, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ac474000
[00007f64ad6b01d7] mprotect(0x7f64ac48e000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ac68d000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19000) = 0x7f64ac68d000
[00007f64ad6b0123] mmap(0x7f64ac68f000, 13440, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64ac68f000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240\35\2\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0755, st_size=2030928, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 4131552, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ac083000
[00007f64ad6b01d7] mprotect(0x7f64ac26a000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ac46a000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1e7000) = 0x7f64ac46a000
[00007f64ad6b0123] mmap(0x7f64ac470000, 15072, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64ac470000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libstdc++.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\304\10\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=1594864, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 3702848, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64abcfa000
[00007f64ad6b01d7] mprotect(0x7f64abe73000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ac073000, 49152, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x179000) = 0x7f64ac073000
[00007f64ad6b0123] mmap(0x7f64ac07f000, 12352, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64ac07f000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libm.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\272\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=1700792, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 3789144, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ab95c000
[00007f64ad6b01d7] mprotect(0x7f64abaf9000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64abcf8000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19c000) = 0x7f64abcf8000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libnghttp2.so.14", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200H\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=153352, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2248560, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ab737000
[00007f64ad6b01d7] mprotect(0x7f64ab75a000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ab959000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x22000) = 0x7f64ab959000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libidn2.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\360\24\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=116656, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2211864, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ab51a000
[00007f64ad6b01d7] mprotect(0x7f64ab536000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ab735000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1b000) = 0x7f64ab735000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/librtmp.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200J\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=113584, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8b3000
[00007f64ad6b0123] mmap(NULL, 2208688, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ab2fe000
[00007f64ad6b01d7] mprotect(0x7f64ab319000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ab518000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1a000) = 0x7f64ab518000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libpsl.so.5", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\22\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=55136, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2150504, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64ab0f0000
[00007f64ad6b01d7] mprotect(0x7f64ab0fd000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ab2fc000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xc000) = 0x7f64ab2fc000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libgssapi_krb5.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\266\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=305456, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2401088, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64aaea5000
[00007f64ad6b01d7] mprotect(0x7f64aaeed000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64ab0ec000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x47000) = 0x7f64ab0ec000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libldap_r-2.4.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\321\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=327024, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2431720, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64aac53000
[00007f64ad6b01d7] mprotect(0x7f64aaca1000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64aaea0000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4d000) = 0x7f64aaea0000
[00007f64ad6b0123] mmap(0x7f64aaea3000, 6888, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64aaea3000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/liblber-2.4.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`*\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=55544, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2150888, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64aaa45000
[00007f64ad6b01d7] mprotect(0x7f64aaa52000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64aac51000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xc000) = 0x7f64aac51000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libz.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\37\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=116960, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2212016, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64aa828000
[00007f64ad6b01d7] mprotect(0x7f64aa844000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64aaa43000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1b000) = 0x7f64aaa43000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8b1000
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libunistring.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\365\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=1562664, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 3660040, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64aa4aa000
[00007f64ad6b01d7] mprotect(0x7f64aa624000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64aa824000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x17a000) = 0x7f64aa824000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libgnutls.so.30", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@\210\2\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=1461856, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 3562312, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64aa144000
[00007f64ad6b01d7] mprotect(0x7f64aa29c000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64aa49c000, 53248, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x158000) = 0x7f64aa49c000
[00007f64ad6b0123] mmap(0x7f64aa4a9000, 2888, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64aa4a9000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libhogweed.so.4", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220o\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=219976, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2315032, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a9f0e000
[00007f64ad6b01d7] mprotect(0x7f64a9f42000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64aa142000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x34000) = 0x7f64aa142000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libnettle.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\201\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=219304, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2314384, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a9cd8000
[00007f64ad6b01d7] mprotect(0x7f64a9d0c000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a9f0b000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x33000) = 0x7f64a9f0b000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libgmp.so.10", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\223\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=526688, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2621888, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a9a57000
[00007f64ad6b01d7] mprotect(0x7f64a9ad6000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a9cd6000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7f000) = 0x7f64a9cd6000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libkrb5.so.3", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\27\2\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=877056, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2972896, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a9781000
[00007f64ad6b01d7] mprotect(0x7f64a9847000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a9a47000, 65536, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xc6000) = 0x7f64a9a47000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8af000
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libk5crypto.so.3", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0PC\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=199104, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2297976, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a954f000
[00007f64ad6b01d7] mprotect(0x7f64a957d000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a977d000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2e000) = 0x7f64a977d000
[00007f64ad6b0123] mmap(0x7f64a9780000, 120, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64a9780000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libcom_err.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\23\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=14248, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2109608, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a934b000
[00007f64ad6b01d7] mprotect(0x7f64a934e000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a954d000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f64a954d000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libkrb5support.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@'\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=43616, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2139080, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a9140000
[00007f64ad6b01d7] mprotect(0x7f64a914a000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a9349000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x9000) = 0x7f64a9349000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libresolv.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\00008\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=97072, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2202240, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a8f26000
[00007f64ad6b01d7] mprotect(0x7f64a8f3d000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a913c000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000) = 0x7f64a913c000
[00007f64ad6b0123] mmap(0x7f64a913e000, 6784, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64a913e000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libsasl2.so.2", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340*\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=109296, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2204648, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a8d0b000
[00007f64ad6b01d7] mprotect(0x7f64a8d24000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a8f24000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19000) = 0x7f64a8f24000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libgssapi.so.3", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \333\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=265712, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2361240, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a8aca000
[00007f64ad6b01d7] mprotect(0x7f64a8b08000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a8d07000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3d000) = 0x7f64a8d07000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libp11-kit.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\263\2\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=1237640, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8ad000
[00007f64ad6b0123] mmap(NULL, 3334512, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a879b000
[00007f64ad6b01d7] mprotect(0x7f64a88b5000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a8ab5000, 81920, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x11a000) = 0x7f64a8ab5000
[00007f64ad6b0123] mmap(0x7f64a8ac9000, 368, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64a8ac9000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libtasn1.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220)\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=75776, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2171592, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a8588000
[00007f64ad6b01d7] mprotect(0x7f64a8599000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a8799000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x11000) = 0x7f64a8799000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libkeyutils.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\22\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=14256, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2109456, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a8384000
[00007f64ad6b01d7] mprotect(0x7f64a8387000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a8586000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f64a8586000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libheimntlm.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\360)\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=35360, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2130512, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a817b000
[00007f64ad6b01d7] mprotect(0x7f64a8183000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a8382000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x7f64a8382000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libkrb5.so.26", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\316\1\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=573464, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2671504, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a7eee000
[00007f64ad6b01d7] mprotect(0x7f64a7f75000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a8174000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x86000) = 0x7f64a8174000
[00007f64ad6b0123] mmap(0x7f64a817a000, 912, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64a817a000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libasn1.so.8", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\233\1\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=661696, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8ab000
[00007f64ad6b0123] mmap(NULL, 2756848, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a7c4c000
[00007f64ad6b01d7] mprotect(0x7f64a7cea000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a7eea000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x9e000) = 0x7f64a7eea000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libhcrypto.so.4", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`s\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=217560, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2316904, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a7a16000
[00007f64ad6b01d7] mprotect(0x7f64a7a49000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a7c48000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x32000) = 0x7f64a7c48000
[00007f64ad6b0123] mmap(0x7f64a7c4b000, 2664, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64a7c4b000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libroken.so.18", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300N\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=88680, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2184240, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a7800000
[00007f64ad6b01d7] mprotect(0x7f64a7815000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a7a14000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x14000) = 0x7f64a7a14000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libffi.so.6", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0@\27\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=31032, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2127368, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a75f8000
[00007f64ad6b01d7] mprotect(0x7f64a75ff000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a77fe000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x7f64a77fe000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libwind.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \16\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=165880, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2261040, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a73cf000
[00007f64ad6b01d7] mprotect(0x7f64a73f6000, 2097152, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a75f6000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x27000) = 0x7f64a75f6000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libheimbase.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340(\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=60400, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2156760, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a71c0000
[00007f64ad6b01d7] mprotect(0x7f64a71ce000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a73cd000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xd000) = 0x7f64a73cd000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libhx509.so.5", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\20\1\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=300888, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8a9000
[00007f64ad6b0123] mmap(NULL, 2397224, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a6f76000
[00007f64ad6b01d7] mprotect(0x7f64a6fbc000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a71bb000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x45000) = 0x7f64a71bb000
[00007f64ad6b0123] mmap(0x7f64a71bf000, 1064, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64a71bf000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/usr/lib/x86_64-linux-gnu/libsqlite3.so.0", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300\314\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=1082648, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 3179736, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a6c6d000
[00007f64ad6b01d7] mprotect(0x7f64a6d71000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a6f70000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x103000) = 0x7f64a6f70000
[00007f64ad6b0123] mmap(0x7f64a6f75000, 1240, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64a6f75000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6ab2d9] access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
[00007f64ad6afebd] openat(AT_FDCWD, "/lib/x86_64-linux-gnu/libcrypt.so.1", O_RDONLY|O_CLOEXEC) = 3
[00007f64ad6aff84] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\r\0\0\0\0\0\0"..., 832) = 832
[00007f64ad6afe23] fstat(3, {st_mode=S_IFREG|0644, st_size=39208, ...}) = 0
[00007f64ad6b0123] mmap(NULL, 2322976, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f64a6a35000
[00007f64ad6b01d7] mprotect(0x7f64a6a3e000, 2093056, PROT_NONE) = 0
[00007f64ad6b0123] mmap(0x7f64a6c3d000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x8000) = 0x7f64a6c3d000
[00007f64ad6b0123] mmap(0x7f64a6c3f000, 184864, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f64a6c3f000
[00007f64ad6b00b7] close(3)             = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8a7000
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8a5000
[00007f64ad6b0123] mmap(NULL, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad8a1000
[00007f64ad694024] arch_prctl(ARCH_SET_FS, 0x7f64ad8a3040) = 0
[00007f64ad6b01d7] mprotect(0x7f64ac46a000, 16384, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a6c3d000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ad691000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ac68d000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64abcf8000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a6f70000, 12288, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a954d000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a913c000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a7a14000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a7eea000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a73cd000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a7c48000, 8192, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a75f6000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a71bb000, 12288, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a77fe000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a8174000, 16384, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a8382000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a8586000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a8799000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a8ab5000, 40960, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a8d07000, 8192, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a8f24000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a9349000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a977d000, 8192, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a9a47000, 57344, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a9cd6000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64a9f0b000, 8192, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64aa142000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64aa824000, 12288, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ab735000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64aaa43000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64aa49c000, 49152, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64aac51000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64aaea0000, 8192, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ab0ec000, 8192, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ab2fc000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ab518000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ab959000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ac8a9000, 4096, PROT_READ) = 0
[00007f64ad6b0123] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f64ad89f000
[00007f64ad6b01d7] mprotect(0x7f64ac073000, 40960, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64acd46000, 180224, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64acff7000, 36864, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ad283000, 12288, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ad48d000, 4096, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x55b845ce6000, 24576, PROT_READ) = 0
[00007f64ad6b01d7] mprotect(0x7f64ad8bc000, 4096, PROT_READ) = 0
[00007f64ad6b01b7] munmap(0x7f64ad8b9000, 10904) = 0
[00007f64ac479eb5] set_tid_address(0x7f64ad8a3310) = 79
[00007f64ac479f17] set_robust_list(0x7f64ad8a3320, 24) = 0
[00007f64ac486a4d] rt_sigaction(SIGRTMIN, {sa_handler=0x7f64ac479cb0, sa_mask=[], sa_flags=SA_RESTORER|SA_SIGINFO, sa_restorer=0x7f64ac486980}, NULL, 8) = 0
[00007f64ac486a4d] rt_sigaction(SIGRT_1, {sa_handler=0x7f64ac479d50, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART|SA_SIGINFO, sa_restorer=0x7f64ac486980}, NULL, 8) = 0
[00007f64ac479ff3] rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
[00007f64ac198be0] prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
[00007f64ac48392e] futex(0x7f64a8ac90e0, FUTEX_WAKE_PRIVATE, 2147483647) = 0
[00007f64ac194cb7] readlink("/etc/malloc.conf", 0x7fff289323d0, 4096) = -1 ENOENT (No such file or directory)
[00007f64ac19e539] open("/proc/sys/vm/overcommit_memory", O_RDONLY|O_CLOEXEC) = 3
[00007f64ac19e539] read(3, "0", 1)      = 1
[00007f64ac19e539] close(3)             = 0
[00007f64ac19e539] open("/sys/kernel/mm/transparent_hugepage/enabled", O_RDONLY) = 3
[00007f64ac19e539] read(3, "always [madvise] never\n", 24) = 23
[00007f64ac19e539] close(3)             = 0
[00007f64ac19e713] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f64ad8bb000
[00007f64ac19e8b7] madvise(0x7f64ad8bb000, 4096, MADV_FREE) = 0
[00007f64ac19e7b7] munmap(0x7f64ad8bb000, 4096) = 0
[00007f64ac19e713] mmap(NULL, 2097152, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f64a6835000
[00007f64ac19e7b7] munmap(0x7f64a6835000, 2097152) = 0
[00007f64ac19e713] mmap(NULL, 4190208, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f64a6636000
[00007f64ac19e7b7] munmap(0x7f64a6636000, 1875968) = 0
[00007f64ac19e7b7] munmap(0x7f64a6a00000, 217088) = 0
[00007f64ac1990f9] brk(NULL)            = 0x55b847352000
[00007f64ac19e713] mmap(NULL, 2097152, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f64a6600000
[00007f64ac19e713] mmap(NULL, 4194304, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_NORESERVE, -1, 0) = 0x7f64a6200000
[00007f64ac486c33] sched_getaffinity(79, 128, [0, 1]) = 8
[00007f64ac0c78c4] getrandom("\x67", 1, GRND_NONBLOCK) = 1
[00007f64ac192725] stat("/etc/gnutls/default-priorities", 0x7fff289334f0) = -1 ENOENT (No such file or directory)
[00007f64ac1988e8] ioctl(1, TCGETS, {B38400 opost isig icanon echo ...}) = 0
[00007f64ac168817] getpid()             = 79
[00007f64ac1a5c57] socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 3
[00007f64ac4855fe] fcntl(3, F_GETFL)    = 0x2 (flags O_RDWR)
[00007f64ac4855fe] fcntl(3, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[00007f64ac485831] connect(3, {sa_family=AF_INET, sin_port=htons(6379), sin_addr=inet_addr("127.0.0.1")}, 16) = -1 EINPROGRESS (Operation now in progress)
[00007f64ac197b84] poll([{fd=3, events=POLLOUT}], 1, -1) = 1 ([{fd=3, revents=POLLOUT}])
[00007f64ac485831] connect(3, {sa_family=AF_INET, sin_port=htons(6379), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
[00007f64ac1a5bfa] setsockopt(3, SOL_TCP, TCP_NODELAY, [1], 4) = 0
[00007f64ac4855fe] fcntl(3, F_GETFL)    = 0x802 (flags O_RDWR|O_NONBLOCK)
[00007f64ac4855fe] fcntl(3, F_SETFL, O_RDWR) = 0
[00007f64ac1a5bfa] setsockopt(3, SOL_SOCKET, SO_KEEPALIVE, [1], 4) = 0
[00007f64ac1a5bfa] setsockopt(3, SOL_TCP, TCP_KEEPIDLE, [15], 4) = 0
[00007f64ac1a5bfa] setsockopt(3, SOL_TCP, TCP_KEEPINTVL, [5], 4) = 0
[00007f64ac1a5bfa] setsockopt(3, SOL_TCP, TCP_KEEPCNT, [3], 4) = 0
[00007f64ac485a5a] sendto(3, "*1\r\n$4\r\nsave\r\n", 14, 0, NULL, 0) = 14
[00007f64ac4858da] recvfrom(3, "+OK\r\n", 16384, 0, NULL, NULL) = 5
[00007f64ac192773] fstat(1, {st_mode=S_IFCHR|0620, st_rdev=makedev(136, 1), ...}) = 0
[00007f64ac193104] write(1, "OK\n", 3OK
)  = 3
[00007f64ac167ab6] exit_group(0)        = ?
[????????????????] +++ exited with 0 +++

@ekexcello
Copy link

Hello.
We have hit the same bug.
Running keydb-6.3.3 on Gentoo with 3 nodes in master-master cluster. For some reason one and the same node has issues with background save. The issue happens daily with one and the same symptoms - after working for several hours, one bgsave process hangs and refuses to react exit unless kill -9 <pid> issued.
No process activity is printed on strace output:

# kill 21352; strace -p 21352
strace: Process 21352 attached
futex(0x55968961aa20, FUTEX_WAIT_BITSET_PRIVATE, 4290903999, NULL, 0x1
^Cstrace: Process 21352 detached
 <detached ...>

Once the bgsave process is killed, the next bgsave successfully resumes.
Also, it was noted that in case another cluster member restarts, a connection to that node as a replica is installed immediately that member comes online, but connection to it as a master is only established once the background save exits.

Log file:

6355:8111:S 27 Jul 2023 05:21:22.804 * 100000 changes in 300 seconds. Saving...
6355:8111:S 27 Jul 2023 05:21:22.835 * Background saving started by pid 121323
6355:8111:S 27 Jul 2023 05:21:22.835 * Background saving started
121323:8111:C 27 Jul 2023 05:21:25.150 * DB saved on disk
121323:8111:C 27 Jul 2023 05:21:25.196 * RDB: 4 MB of memory used by copy-on-write
6355:8111:S 27 Jul 2023 05:21:25.346 * Background saving terminated with success
6355:8111:S 27 Jul 2023 05:29:04.426 * 100000 changes in 300 seconds. Saving...
6355:8111:S 27 Jul 2023 05:29:04.457 * Background saving started by pid 9786
6355:8111:S 27 Jul 2023 05:29:04.457 * Background saving started
9786:8111:C 27 Jul 2023 05:29:07.628 * DB saved on disk
9786:8111:C 27 Jul 2023 05:29:07.675 * RDB: 7 MB of memory used by copy-on-write
6355:8111:S 27 Jul 2023 05:29:07.770 * Background saving terminated with success
6355:8111:S 27 Jul 2023 05:34:08.074 * 100000 changes in 300 seconds. Saving...
6355:8111:S 27 Jul 2023 05:34:08.105 * Background saving started by pid 21352
6355:8111:S 27 Jul 2023 05:34:08.105 * Background saving started
6355:8113:S 27 Jul 2023 10:51:57.745 # Connection with replica client id #208 lost.
6355:8111:S 27 Jul 2023 10:51:57.745 # Connection with master lost.
6355:8111:S 27 Jul 2023 10:51:57.745 * Caching the disconnected master state.
6355:8113:S 27 Jul 2023 10:52:01.790 * Replica [2001::b]:6380 asks for synchronization
6355:8113:S 27 Jul 2023 10:52:01.790 * Partial resynchronization request from [2001::b]:6380 accepted. Sending 1343663 bytes of backlog starting from offset 536248767889.
21352:signal-handler (1690448271) Received SIGTERM scheduling shutdown...
6355:8111:S 27 Jul 2023 11:17:18.050 # Background saving cancelled
6355:8111:S 27 Jul 2023 11:17:18.150 * 100000 changes in 300 seconds. Saving...
6355:8111:S 27 Jul 2023 11:17:18.190 * Background saving started by pid 83305
6355:8111:S 27 Jul 2023 11:17:18.190 * Background saving started
83305:8111:C 27 Jul 2023 11:17:20.566 * DB saved on disk
83305:8111:C 27 Jul 2023 11:17:20.619 * RDB: 13 MB of memory used by copy-on-write
6355:8111:S 27 Jul 2023 11:17:20.708 * Background saving terminated with success
6355:8111:S 27 Jul 2023 11:17:21.614 * Connecting to MASTER b:6380
6355:8111:S 27 Jul 2023 11:17:21.628 * MASTER <-> REPLICA sync started
6355:8111:S 27 Jul 2023 11:17:21.633 * Non blocking connect for SYNC fired the event.
6355:8111:S 27 Jul 2023 11:17:21.638 * Master replied to PING, replication can continue...
6355:8111:S 27 Jul 2023 11:17:21.648 * Trying a partial resynchronization (request 24e424303d8e24ff1cb21e7ca3f0c459b97a7f57:437072013133).
6355:8111:S 27 Jul 2023 11:17:21.653 * Successful partial resynchronization with master.
6355:8111:S 27 Jul 2023 11:17:21.653 * MASTER <-> REPLICA sync: Master accepted a Partial Resynchronization.

@kitobelix
Copy link

Hi! Attaching more logs. Please take a look at the time.

27/7/2023 0:00:01
27/7/2023 0:00:01                  _
27/7/2023 0:00:01               _-(+)-_
27/7/2023 0:00:01            _-- /   \ --_
27/7/2023 0:00:01         _--   /     \   --_            KeyDB  6.3.3 (86ea5c7e/1) 64 bit
27/7/2023 0:00:01     __--     /       \     --__
27/7/2023 0:00:01    (+) _    /         \    _ (+)       Running in standalone mode
27/7/2023 0:00:01     |   -- /           \ --   |        Port: 6379
27/7/2023 0:00:01     |     /--_   _   _--\     |        PID: 1
27/7/2023 0:00:01     |    /     -(+)-     \    |
27/7/2023 0:00:01     |   /        |        \   |        https://docs.keydb.dev
27/7/2023 0:00:01     |  /         |         \  |
27/7/2023 0:00:01     | /          |          \ |
27/7/2023 0:00:01    (+)_ -- -- -- | -- -- -- _(+)
27/7/2023 0:00:01        --_       |       _--
27/7/2023 0:00:01            --_   |   _--
27/7/2023 0:00:01                -(+)-        KeyDB has now joined Snap! See the announcement at:  https://docs.keydb.dev/news
27/7/2023 0:00:01
27/7/2023 0:00:01
27/7/2023 0:00:011:1:M 27 Jul 2023 03:00:01.801 # Server initialized
27/7/2023 0:00:011:1:M 27 Jul 2023 03:00:01.809 * Reading RDB preamble from AOF file...
27/7/2023 0:00:011:1:M 27 Jul 2023 03:00:01.810 * Loading RDB produced by version 6.3.3
27/7/2023 0:00:011:1:M 27 Jul 2023 03:00:01.810 * RDB age 430413 seconds
27/7/2023 0:00:011:1:M 27 Jul 2023 03:00:01.810 * RDB memory usage when created 18.93 Mb
27/7/2023 0:00:011:1:M 27 Jul 2023 03:00:01.810 * RDB has an AOF tail
27/7/2023 0:00:021:1:M 27 Jul 2023 03:00:02.270 # Done loading RDB, keys loaded: 0, keys expired: 0.
27/7/2023 0:00:021:1:M 27 Jul 2023 03:00:02.270 * Reading the remaining AOF tail...
27/7/2023 0:01:041:1:M 27 Jul 2023 03:01:04.926 * DB loaded from append only file: 63.117 seconds
27/7/2023 0:01:041:28:M 27 Jul 2023 03:01:04.926 * Thread 0 alive.
27/7/2023 0:01:041:29:M 27 Jul 2023 03:01:04.926 * Thread 1 alive.
27/7/2023 0:01:041:28:M 27 Jul 2023 03:01:04.927 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:01:041:28:M 27 Jul 2023 03:01:04.929 * Background saving started by pid 31
27/7/2023 0:01:041:28:M 27 Jul 2023 03:01:04.929 * Background saving started
27/7/2023 0:01:0531:28:C 27 Jul 2023 03:01:05.102 * DB saved on disk
27/7/2023 0:01:0531:28:C 27 Jul 2023 03:01:05.105 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:01:05NOTICE: Detuning locks due to high load per core: 147.34%
27/7/2023 0:01:051:28:M 27 Jul 2023 03:01:05.131 * Background saving terminated with success
27/7/2023 0:02:07NOTICE: Detuning locks due to high load per core: 109.62%
27/7/2023 0:02:071:28:M 27 Jul 2023 03:02:07.806 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:02:071:28:M 27 Jul 2023 03:02:07.808 * Background saving started by pid 32
27/7/2023 0:02:071:28:M 27 Jul 2023 03:02:07.808 * Background saving started
27/7/2023 0:02:0832:28:C 27 Jul 2023 03:02:08.024 * DB saved on disk
27/7/2023 0:02:0832:28:C 27 Jul 2023 03:02:08.027 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:02:081:28:M 27 Jul 2023 03:02:08.111 * Background saving terminated with success
27/7/2023 0:03:301:28:M 27 Jul 2023 03:03:30.895 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:03:301:28:M 27 Jul 2023 03:03:30.897 * Background saving started by pid 33
27/7/2023 0:03:301:28:M 27 Jul 2023 03:03:30.897 * Background saving started
27/7/2023 0:03:3133:28:C 27 Jul 2023 03:03:31.219 * DB saved on disk
27/7/2023 0:03:3133:28:C 27 Jul 2023 03:03:31.221 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:03:311:28:M 27 Jul 2023 03:03:31.298 * Background saving terminated with success
27/7/2023 0:06:361:28:M 27 Jul 2023 03:06:36.048 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:06:361:28:M 27 Jul 2023 03:06:36.050 * Background saving started by pid 34
27/7/2023 0:06:361:28:M 27 Jul 2023 03:06:36.050 * Background saving started
27/7/2023 0:06:3634:28:C 27 Jul 2023 03:06:36.261 * DB saved on disk
27/7/2023 0:06:3634:28:C 27 Jul 2023 03:06:36.269 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:06:361:28:M 27 Jul 2023 03:06:36.352 * Background saving terminated with success
27/7/2023 0:09:381:28:M 27 Jul 2023 03:09:38.951 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:09:381:28:M 27 Jul 2023 03:09:38.953 * Background saving started by pid 35
27/7/2023 0:09:381:28:M 27 Jul 2023 03:09:38.953 * Background saving started
27/7/2023 0:09:3935:28:C 27 Jul 2023 03:09:39.184 * DB saved on disk
27/7/2023 0:09:3935:28:C 27 Jul 2023 03:09:39.187 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:09:391:28:M 27 Jul 2023 03:09:39.255 * Background saving terminated with success
27/7/2023 0:12:521:28:M 27 Jul 2023 03:12:52.827 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:12:521:28:M 27 Jul 2023 03:12:52.831 * Background saving started by pid 36
27/7/2023 0:12:521:28:M 27 Jul 2023 03:12:52.831 * Background saving started
27/7/2023 0:12:5336:28:C 27 Jul 2023 03:12:53.035 * DB saved on disk
27/7/2023 0:12:5336:28:C 27 Jul 2023 03:12:53.038 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:12:531:28:M 27 Jul 2023 03:12:53.135 * Background saving terminated with success
27/7/2023 0:15:531:28:M 27 Jul 2023 03:15:53.433 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:15:531:28:M 27 Jul 2023 03:15:53.440 * Background saving started by pid 37
27/7/2023 0:15:531:28:M 27 Jul 2023 03:15:53.440 * Background saving started
27/7/2023 0:15:5337:28:C 27 Jul 2023 03:15:53.685 * DB saved on disk
27/7/2023 0:15:5337:28:C 27 Jul 2023 03:15:53.688 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:15:531:28:M 27 Jul 2023 03:15:53.746 * Background saving terminated with success
27/7/2023 0:18:551:28:M 27 Jul 2023 03:18:55.417 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:18:551:28:M 27 Jul 2023 03:18:55.420 * Background saving started by pid 38
27/7/2023 0:18:551:28:M 27 Jul 2023 03:18:55.420 * Background saving started
27/7/2023 0:18:5538:28:C 27 Jul 2023 03:18:55.664 * DB saved on disk
27/7/2023 0:18:5538:28:C 27 Jul 2023 03:18:55.667 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:18:551:28:M 27 Jul 2023 03:18:55.754 * Background saving terminated with success
27/7/2023 0:22:001:28:M 27 Jul 2023 03:22:00.230 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:22:001:28:M 27 Jul 2023 03:22:00.233 * Background saving started by pid 39
27/7/2023 0:22:001:28:M 27 Jul 2023 03:22:00.233 * Background saving started
27/7/2023 0:22:0039:28:C 27 Jul 2023 03:22:00.442 * DB saved on disk
27/7/2023 0:22:0039:28:C 27 Jul 2023 03:22:00.444 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:22:001:28:M 27 Jul 2023 03:22:00.535 * Background saving terminated with success
27/7/2023 0:25:181:28:M 27 Jul 2023 03:25:18.480 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:25:181:28:M 27 Jul 2023 03:25:18.482 * Background saving started by pid 40
27/7/2023 0:25:181:28:M 27 Jul 2023 03:25:18.482 * Background saving started
27/7/2023 0:25:1840:28:C 27 Jul 2023 03:25:18.723 * DB saved on disk
27/7/2023 0:25:1840:28:C 27 Jul 2023 03:25:18.726 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:25:181:28:M 27 Jul 2023 03:25:18.806 * Background saving terminated with success
27/7/2023 0:28:231:28:M 27 Jul 2023 03:28:23.183 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:28:231:28:M 27 Jul 2023 03:28:23.185 * Background saving started by pid 41
27/7/2023 0:28:231:28:M 27 Jul 2023 03:28:23.185 * Background saving started
27/7/2023 0:28:2341:28:C 27 Jul 2023 03:28:23.422 * DB saved on disk
27/7/2023 0:28:2341:28:C 27 Jul 2023 03:28:23.424 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:28:231:28:M 27 Jul 2023 03:28:23.487 * Background saving terminated with success
27/7/2023 0:31:331:28:M 27 Jul 2023 03:31:33.553 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:31:331:28:M 27 Jul 2023 03:31:33.555 * Background saving started by pid 42
27/7/2023 0:31:331:28:M 27 Jul 2023 03:31:33.555 * Background saving started
27/7/2023 0:31:3342:28:C 27 Jul 2023 03:31:33.802 * DB saved on disk
27/7/2023 0:31:3342:28:C 27 Jul 2023 03:31:33.804 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:31:331:28:M 27 Jul 2023 03:31:33.858 * Background saving terminated with success
27/7/2023 0:33:531:28:M 27 Jul 2023 03:33:53.933 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:33:531:28:M 27 Jul 2023 03:33:53.935 * Background saving started by pid 43
27/7/2023 0:33:531:28:M 27 Jul 2023 03:33:53.935 * Background saving started
27/7/2023 0:33:5443:28:C 27 Jul 2023 03:33:54.178 * DB saved on disk
27/7/2023 0:33:5443:28:C 27 Jul 2023 03:33:54.180 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:33:541:28:M 27 Jul 2023 03:33:54.238 * Background saving terminated with success
27/7/2023 0:37:15NOTICE: Detuning locks due to high load per core: 136.84%
27/7/2023 0:37:151:28:M 27 Jul 2023 03:37:15.513 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:37:151:28:M 27 Jul 2023 03:37:15.516 * Background saving started by pid 44
27/7/2023 0:37:151:28:M 27 Jul 2023 03:37:15.516 * Background saving started
27/7/2023 0:37:1544:28:C 27 Jul 2023 03:37:15.727 * DB saved on disk
27/7/2023 0:37:1544:28:C 27 Jul 2023 03:37:15.729 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:37:151:28:M 27 Jul 2023 03:37:15.818 * Background saving terminated with success
27/7/2023 0:40:151:28:M 27 Jul 2023 03:40:15.684 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:40:151:28:M 27 Jul 2023 03:40:15.686 * Background saving started by pid 45
27/7/2023 0:40:151:28:M 27 Jul 2023 03:40:15.686 * Background saving started
27/7/2023 0:40:1545:28:C 27 Jul 2023 03:40:15.902 * DB saved on disk
27/7/2023 0:40:1545:28:C 27 Jul 2023 03:40:15.905 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:40:151:28:M 27 Jul 2023 03:40:15.965 * Background saving terminated with success
27/7/2023 0:43:231:28:M 27 Jul 2023 03:43:23.206 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:43:231:28:M 27 Jul 2023 03:43:23.208 * Background saving started by pid 46
27/7/2023 0:43:231:28:M 27 Jul 2023 03:43:23.209 * Background saving started
27/7/2023 0:43:2346:28:C 27 Jul 2023 03:43:23.435 * DB saved on disk
27/7/2023 0:43:2346:28:C 27 Jul 2023 03:43:23.438 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:43:231:28:M 27 Jul 2023 03:43:23.512 * Background saving terminated with success
27/7/2023 0:46:331:28:M 27 Jul 2023 03:46:33.742 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:46:331:28:M 27 Jul 2023 03:46:33.744 * Background saving started by pid 47
27/7/2023 0:46:331:28:M 27 Jul 2023 03:46:33.744 * Background saving started
27/7/2023 0:46:3447:28:C 27 Jul 2023 03:46:34.263 * DB saved on disk
27/7/2023 0:46:3447:28:C 27 Jul 2023 03:46:34.270 * RDB: 1 MB of memory used by copy-on-write
27/7/2023 0:46:341:28:M 27 Jul 2023 03:46:34.331 * Background saving terminated with success
27/7/2023 0:49:451:28:M 27 Jul 2023 03:49:45.666 * 10000 changes in 60 seconds. Saving...
27/7/2023 0:49:451:28:M 27 Jul 2023 03:49:45.706 * Background saving started by pid 48
27/7/2023 0:49:451:28:M 27 Jul 2023 03:49:45.706 * Background saving started

@ekexcello
Copy link

By the way.
The same happens on the cluster without using appendonly. The behavior is nevertheless the very same, even with having

appendonly no

@kitobelix
Copy link

Hi! Just wanted to confirm ekexcello's comment, I ran a test server with appendonly disabled and happened the same.

@ekexcello
Copy link

ekexcello commented Aug 16, 2023

On further investigation and analysis of what's going on, we also found that the similar behavior was also described in redis bugtracker. Does anybody aware whether this applies (or was fixed?) in Keydb-6.3?

@zas
Copy link
Author

zas commented Aug 22, 2023

On further investigation and analysis of what's going on, we also found that the similar behavior was also described in redis bugtracker.

It seems to be an issue in redis tests, not sure it is similar to the one described here (which happens at runtime).

Does anybody aware whether this applies (or was fixed?) in Keydb-6.3?

It still unfixed, happening randomly here with keydb 6.3.3 (latest stable as today).

@gery0815
Copy link

gery0815 commented Sep 5, 2023

Happens randomly. Sometimes only the aof of one node is growing. The last time we observed the issue even all three nodes got affected at the same time and key-db was not working properly anymore. We had to clean the aof and recreate the K8s pods. Is there any update about a fix or at least any workaround ?

@fvogl
Copy link

fvogl commented Sep 19, 2023

We're affected by the same bug. Eagerly waiting for a fix.

@kitobelix
Copy link

I just realized that I have an instance running for 88 days, which I go and maintain every two days by using kill -9 {pid} whenever it stops working during bgsave.
The strange thing is that without realizing it, I stopped attending to it for 7 days. It didn't freeze again.
Isn't that strange?

@zas
Copy link
Author

zas commented Oct 17, 2023

#720 says it contains a fix for this issue, is there a time window for next stable release?

Also what is the actual fix for this issue among all those merged commits?

@msotheeswaran-sc
Copy link
Collaborator

The issue was a race condition on a lock when forking, the fix was 596c513, don't touch the lock in the forked process. Next release should be within this month, unfortunately have been busy with internal work and haven't been able to get it out yet.

@kitobelix
Copy link

Soo many "thank yous" sent in a huge array occupiying like 192GB of RAM to you guys.

@zas
Copy link
Author

zas commented Jan 7, 2024

The issue didn't appear since the fix. Thanks a lot.

@zas zas closed this as completed Jan 7, 2024
@fvogl
Copy link

fvogl commented Jan 24, 2024

This just happened again for us being on the latest version v6.3.4

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

6 participants