RedisModulesSDK icon indicating copy to clipboard operation
RedisModulesSDK copied to clipboard

Crash When Including Log Code

Open victorstewart opened this issue 4 years ago • 0 comments

Whenever I include any usage of RedisModule_Log, Redis crashes when I try to load the module. Any ideas here?

Doing this inside docker fyi.

Example of usage... RedisModule_Log(ctx, "warning", "RedisModule_OnLoad, argc = %d", argc);

Example of crash...

211:C 07 Nov 2019 09:49:58.254 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
211:C 07 Nov 2019 09:49:58.254 # Redis version=5.0.5, bits=64, commit=00000000, modified=0, pid=211, just started
211:C 07 Nov 2019 09:49:58.254 # Configuration loaded
                _._                                                  
           _.-``__ ''-._                                             
      _.-``    `.  `_.  ''-._           Redis 5.0.5 (00000000/0) 64 bit
  .-`` .-```.  ```\/    _.,_ ''-._                                   
 (    '      ,       .-`  | `,    )     Running in standalone mode
 |`-._`-...-` __...-.``-._|'` _.-'|     Port: 9000
 |    `-._   `._    /     _.-'    |     PID: 211
  `-._    `-._  `-./  _.-'    _.-'                                   
 |`-._`-._    `-.__.-'    _.-'_.-'|                                  
 |    `-._`-._        _.-'_.-'    |           http://redis.io        
  `-._    `-._`-.__.-'_.-'    _.-'                                   
 |`-._`-._    `-.__.-'    _.-'_.-'|                                  
 |    `-._`-._        _.-'_.-'    |                                  
  `-._    `-._`-.__.-'_.-'    _.-'                                   
      `-._    `-.__.-'    _.-'                                       
          `-._        _.-'                                           
              `-.__.-'                                               

211:M 07 Nov 2019 09:49:58.255 # WARNING: The TCP backlog setting of 511 cannot be enforced because /proc/sys/net/core/somaxconn is set to the lower value of 128.
211:M 07 Nov 2019 09:49:58.255 # Server initialized
211:M 07 Nov 2019 09:49:58.255 # WARNING you have Transparent Huge Pages (THP) support enabled in your kernel. This will create latency and memory usage issues with Redis. To fix this issue run the command 'echo never > /sys/kernel/mm/transparent_hugepage/enabled' as root, and add it to your /etc/rc.local in order to retain the setting after a reboot. Redis must be restarted after THP is disabled.
211:M 07 Nov 2019 09:49:58.256 * Module 'bf' loaded from /RedisBloom-2.0.3/redisbloom.so


=== REDIS BUG REPORT START: Cut & paste starting from here ===
211:M 07 Nov 2019 09:49:58.258 # Redis 5.0.5 crashed by signal: 11
211:M 07 Nov 2019 09:49:58.258 # Accessing address: (nil)
211:M 07 Nov 2019 09:49:58.258 # Failed assertion: <no assertion failed> (<no file>:0)

------ STACK TRACE ------
/redis/src/redis-server 0.0.0.0:9000(logStackTrace+0x37)[0x5582b01213e7]
/redis/src/redis-server 0.0.0.0:9000(sigsegvHandler+0xb0)[0x5582b0121b10]
/lib/x86_64-linux-gnu/libpthread.so.0(+0x110e0)[0x7f6db72f40e0]

------ INFO OUTPUT ------
# Server
redis_version:5.0.5
redis_git_sha1:00000000
redis_git_dirty:0
redis_build_id:c22ea6152d386227
redis_mode:standalone
os:Linux 4.9.184-linuxkit x86_64
arch_bits:64
multiplexing_api:epoll
atomicvar_api:atomic-builtin
gcc_version:6.3.0
process_id:211
run_id:d6895df8f10cd722e6c28f336151969bb516b66e
tcp_port:9000
uptime_in_seconds:0
uptime_in_days:0
hz:10
configured_hz:10
lru_clock:12839110
executable:/redis/src/redis-server
config_file:/nametag.server.v0/test/server/docker-redis-cluster/test.conf

# Clients
connected_clients:0
client_recent_max_input_buffer:0
client_recent_max_output_buffer:0
blocked_clients:0

# Memory
used_memory:798344
used_memory_human:779.63K
used_memory_rss:0
used_memory_rss_human:0B
used_memory_peak:798344
used_memory_peak_human:779.63K
used_memory_peak_perc:inf%
used_memory_overhead:791648
used_memory_startup:791648
used_memory_dataset:6696
used_memory_dataset_perc:100.00%
allocator_allocated:0
allocator_active:0
allocator_resident:0
total_system_memory:3610726400
total_system_memory_human:3.36G
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:-nan
allocator_frag_bytes:0
allocator_rss_ratio:-nan
allocator_rss_bytes:0
rss_overhead_ratio:-nan
rss_overhead_bytes:0
mem_fragmentation_ratio:-nan
mem_fragmentation_bytes:0
mem_not_counted_for_evict:0
mem_replication_backlog:0
mem_clients_slaves:0
mem_clients_normal:0
mem_aof_buffer:0
mem_allocator:jemalloc-5.1.0
active_defrag_running:0
lazyfree_pending_objects:0

# Persistence
loading:0
rdb_changes_since_last_save:0
rdb_bgsave_in_progress:0
rdb_last_save_time:1573120198
rdb_last_bgsave_status:ok
rdb_last_bgsave_time_sec:-1
rdb_current_bgsave_time_sec:-1
rdb_last_cow_size:0
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:0
aof_current_size:0
aof_base_size:0
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:0
total_commands_processed:0
instantaneous_ops_per_sec:0
total_net_input_bytes:0
total_net_output_bytes:0
instantaneous_input_kbps:0.00
instantaneous_output_kbps:0.00
rejected_connections:0
sync_full:0
sync_partial_ok:0
sync_partial_err:0
expired_keys:0
expired_stale_perc:0.00
expired_time_cap_reached_count:0
evicted_keys:0
keyspace_hits:0
keyspace_misses:0
pubsub_channels:0
pubsub_patterns:0
latest_fork_usec:0
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

# Replication
role:master
connected_slaves:0
master_replid:170d85ad3407cd2e150f5104f67bc5a263727ebb
master_replid2:0000000000000000000000000000000000000000
master_repl_offset:0
second_repl_offset:-1
repl_backlog_active:0
repl_backlog_size:1048576
repl_backlog_first_byte_offset:0
repl_backlog_histlen:0

# CPU
used_cpu_sys:0.000000
used_cpu_user:0.000000
used_cpu_sys_children:0.000000
used_cpu_user_children:0.000000

# Commandstats

# Cluster
cluster_enabled:0

# Keyspace

------ CLIENT LIST OUTPUT ------

------ REGISTERS ------
211:M 07 Nov 2019 09:49:58.258 # 
RAX:0000000000000000 RBX:00007f6db6a28630
RCX:0000000000000000 RDX:00007f6db49e8b3e
RDI:00007ffc94ea2600 RSI:00007f6db49e8b5c
RBP:00007ffc94ea25f0 RSP:00007ffc94ea25c8
R8 :0000000000000000 R9 :0000000000000003
R10:0000000000000067 R11:00007f6db49e4588
R12:00007f6db6a28649 R13:00007f6db6a097b8
R14:0000000000000000 R15:0000000000000007
RIP:0000000000000000 EFL:0000000000010202
CSGSFS:002b000000000033
211:M 07 Nov 2019 09:49:58.258 # (00007ffc94ea25d7) -> 0000000000000000
211:M 07 Nov 2019 09:49:58.258 # (00007ffc94ea25d6) -> 0000000000000000
211:M 07 Nov 2019 09:49:58.258 # (00007ffc94ea25d5) -> 0000000000000000
211:M 07 Nov 2019 09:49:58.258 # (00007ffc94ea25d4) -> 0000000000000000
211:M 07 Nov 2019 09:49:58.258 # (00007ffc94ea25d3) -> 0000000000000000
211:M 07 Nov 2019 09:49:58.258 # (00007ffc94ea25d2) -> 0000000000000000
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25d1) -> 0000000000000000
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25d0) -> 0000000000000000
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25cf) -> 00005582b0148f00
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25ce) -> 00005582b014f2d9
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25cd) -> 00005582b1f34260
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25cc) -> 00007ffc94ea2600
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25cb) -> 00007f6db6a097b8
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25ca) -> 00000000b49e7de1
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25c9) -> 00005582b014f2c5
211:M 07 Nov 2019 09:49:58.259 # (00007ffc94ea25c8) -> 00007f6db49e7e20

------ FAST MEMORY TEST ------
211:M 07 Nov 2019 09:49:58.259 # Bio thread for job type #0 terminated
211:M 07 Nov 2019 09:49:58.260 # Bio thread for job type #1 terminated
211:M 07 Nov 2019 09:49:58.260 # Bio thread for job type #2 terminated
*** Preparing to test memory region 5582b0457000 (2252800 bytes)
*** Preparing to test memory region 5582b1f26000 (274432 bytes)
*** Preparing to test memory region 7f6db49e0000 (16384 bytes)
*** Preparing to test memory region 7f6db4dfe000 (8388608 bytes)
*** Preparing to test memory region 7f6db55ff000 (8388608 bytes)
*** Preparing to test memory region 7f6db5e00000 (8388608 bytes)
*** Preparing to test memory region 7f6db6600000 (8388608 bytes)
*** Preparing to test memory region 7f6db72df000 (16384 bytes)
*** Preparing to test memory region 7f6db74fc000 (16384 bytes)
*** Preparing to test memory region 7f6db7e27000 (32768 bytes)
*** Preparing to test memory region 7f6db7e35000 (4096 bytes)
.O.O.O.O.O.O.O.O.O.O.O
Fast memory test PASSED, however your memory can still be broken. Please run a memory test for several hours if possible.

=== REDIS BUG REPORT END. Make sure to include from START to END. ===

       Please report the crash by opening an issue on github:

           http://github.com/antirez/redis/issues

  Suspect RAM error? Use redis-server --test-memory to verify it.

Segmentation fault

running a simple config...

bind 0.0.0.0
port 9000
appendonly yes
dir /redis-data/9000
loglevel verbose

victorstewart avatar Nov 07 '19 09:11 victorstewart