valkey
valkey copied to clipboard
Published
20 hours ago •
valkey-io
valkey-io
[CRASH] Crashing on running `make test`.
Crash report
What error am i getting?
=== VALKEY BUG REPORT START: Cut & paste starting from here ===
27666:M 09 Oct 2024 00:53:56.140 # valkey 255.255.255 crashed by signal: 11, si_code: 2
27666:M 09 Oct 2024 00:53:56.140 # Accessing address: 0x1e823bfff
27666:M 09 Oct 2024 00:53:56.140 # Crashed running the instruction at: 0x10243f2d8
------ STACK TRACE ------
EIP:
0 valkey-server 0x000000010243f2d8 luaS_newlstr + 56
Backtrace:
0 libsystem_platform.dylib 0x000000018171f584 _sigtramp + 56
1 valkey-server 0x0000000102430068 lua_pushlstring + 72
2 valkey-server 0x0000000102430068 lua_pushlstring + 72
3 valkey-server 0x000000010244f38c bit_tohex + 476
4 valkey-server 0x0000000102436170 luaD_precall + 852
5 valkey-server 0x00000001024424fc luaV_execute + 2772
6 valkey-server 0x0000000102436948 luaD_call + 120
7 valkey-server 0x0000000102435b44 luaD_rawrunprotected + 76
8 valkey-server 0x0000000102436c64 luaD_pcall + 60
9 valkey-server 0x0000000102431458 lua_pcall + 300
10 valkey-server 0x0000000102597bbc luaCallFunction + 672
11 valkey-server 0x000000010259bec8 luaEngineCall + 148
12 valkey-server 0x000000010259a438 fcallCommandGeneric + 328
13 valkey-server 0x00000001024a8d30 call + 348
14 valkey-server 0x00000001024aa82c processCommand + 3528
15 valkey-server 0x00000001024c2510 processInputBuffer + 516
16 valkey-server 0x00000001024c1b04 readQueryFromClient + 104
17 valkey-server 0x00000001025922cc connSocketEventHandler + 136
18 valkey-server 0x0000000102495848 aeProcessEvents + 372
19 valkey-server 0x0000000102495c28 aeMain + 32
20 valkey-server 0x00000001024b62a4 main + 20716
21 dyld 0x00000001813660e0 start + 2360
------ STACK TRACE DONE ------
------ REGISTERS ------
27666:M 09 Oct 2024 00:53:56.149 #
x0:0000000114e041c0 x1:000000016d9d9150 x2:0000000080000000 x3:000000011580a850
x4:0000000000000010 x5:0000000000400000 x6:0000600003d50050 x7:0000000000000000
x8:000000016d9d914f x9:000000007a862eb0 x10:00000000ba59eac6 x11:0000000000000000
x12:00000000ffffffff x13:000000011580a850 x14:00000000000007fb x15:00000000802657fb
x16:5518179d2b27dc58 x17:00000001f38de088 x18:0000000000000000 x19:0000000114e041c0
x20:0000000080000000 x21:000000016d9d9150 x22:000000011580a880 x23:000000000180009d
x24:000000003d581f18 x25:0000000000000050 x26:0000000114e04430 x27:0000600001950454
x28:00000000000f4240 fp:000000016d9d9110 lr:0000000102430068
sp:000000016d9d90e0 pc:000000010243f2d8 cpsr:20001000
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90ef) -> 8d72c7a3f59000c0
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90ee) -> 0000000114e04430
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90ed) -> 000000010244f38c
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90ec) -> 000000016d9d9170
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90eb) -> 0000000114e041c0
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90ea) -> 433800000000ffff
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e9) -> 000000011580a850
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e8) -> 000000011580a850
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e7) -> 0000000102430068
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e6) -> 000000016d9d9140
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e5) -> 0000000114e041c0
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e4) -> 0000000080000000
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e3) -> 000000016d9d9150
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e2) -> 000000011580a880
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e1) -> 000000000180009d
27666:M 09 Oct 2024 00:53:56.149 # (000000016d9d90e0) -> 0000600001950450
------ INFO OUTPUT ------
# Server
redis_version:7.2.4
server_name:valkey
valkey_version:255.255.255
redis_git_sha1:cba8eaf4
redis_git_dirty:0
redis_build_id:fd6a514a29ae6cf2
server_mode:standalone
os:Darwin 23.5.0 arm64
arch_bits:64
monotonic_clock:POSIX clock_gettime
multiplexing_api:kqueue
gcc_version:4.2.1
process_id:27666
process_supervised:no
run_id:c7622bd3826b26229b3f64e5f22b3ce4a0861749
tcp_port:22611
server_time_usec:1728415435634151
uptime_in_seconds:5
uptime_in_days:0
hz:10
configured_hz:10
lru_clock:362187
executable:/Users/swaingotnochill/sources/source_files/valkey/src/valkey-server
config_file:/Users/swaingotnochill/sources/source_files/valkey/./tests/tmp/valkey.conf.27608.2
io_threads_active:0
availability_zone:
listener0:name=tcp,bind=127.0.0.1,port=22611
listener1:name=unix,bind=/Users/swaingotnochill/sources/source_files/valkey/tests/tmp/server.27608.1/socket
# Clients
connected_clients:1
cluster_connections:0
maxclients:10000
client_recent_max_input_buffer:0
client_recent_max_output_buffer:0
blocked_clients:0
tracking_clients:0
pubsub_clients:0
watching_clients:0
clients_in_timeout_table:0
total_watched_keys:0
total_blocking_keys:0
total_blocking_keys_on_nokey:0
# Memory
used_memory:1840864
used_memory_human:1.76M
used_memory_rss:41680896
used_memory_rss_human:39.75M
used_memory_peak:1854200
used_memory_peak_human:1.77M
used_memory_peak_perc:99.28%
used_memory_overhead:1114968
used_memory_startup:1111648
used_memory_dataset:725896
used_memory_dataset_perc:99.54%
allocator_allocated:2955680
allocator_active:3948544
allocator_resident:6520832
allocator_muzzy:0
total_system_memory:8589934592
total_system_memory_human:8.00G
used_memory_lua:31744
used_memory_vm_eval:31744
used_memory_lua_human:31.00K
used_memory_scripts_eval:0
number_of_cached_scripts:0
number_of_functions:1
number_of_libraries:1
used_memory_vm_functions:27872256
used_memory_vm_total:27904000
used_memory_vm_total_human:26.61M
used_memory_functions:464
used_memory_scripts:464
used_memory_scripts_human:464B
maxmemory:0
maxmemory_human:0B
maxmemory_policy:noeviction
allocator_frag_ratio:1.34
allocator_frag_bytes:992864
allocator_rss_ratio:1.65
allocator_rss_bytes:2572288
rss_overhead_ratio:6.39
rss_overhead_bytes:35160064
mem_fragmentation_ratio:22.48
mem_fragmentation_bytes:39826840
mem_not_counted_for_evict:0
mem_replication_backlog:0
mem_total_replication_buffers:0
mem_clients_slaves:0
mem_clients_normal:1920
mem_cluster_links:0
mem_aof_buffer:0
mem_allocator:jemalloc-5.3.0
mem_overhead_db_hashtable_rehashing:0
active_defrag_running:0
lazyfree_pending_objects:0
lazyfreed_objects:0
# Persistence
loading:0
async_loading:0
current_cow_peak: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:103
rdb_bgsave_in_progress:0
rdb_last_save_time:1728415430
rdb_last_bgsave_status:ok
rdb_last_bgsave_time_sec:-1
rdb_current_bgsave_time_sec:-1
rdb_saves:0
rdb_last_cow_size:0
rdb_last_load_keys_expired:0
rdb_last_load_keys_loaded:0
aof_enabled:0
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_rewrites:0
aof_rewrites_consecutive_failures:0
aof_last_write_status:ok
aof_last_cow_size:0
module_fork_in_progress:0
module_fork_last_cow_size:0
# Stats
total_connections_received:2
total_commands_processed:202
instantaneous_ops_per_sec:16
total_net_input_bytes:23407
total_net_output_bytes:3484
total_net_repl_input_bytes:0
total_net_repl_output_bytes:0
instantaneous_input_kbps:1.68
instantaneous_output_kbps:0.20
instantaneous_input_repl_kbps:0.00
instantaneous_output_repl_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
expire_cycle_cpu_milliseconds:0
evicted_keys:0
evicted_clients:0
evicted_scripts:0
total_eviction_exceeded_time:0
current_eviction_exceeded_time:0
keyspace_hits:20
keyspace_misses:2
pubsub_channels:0
pubsub_patterns:0
pubsubshard_channels:0
latest_fork_usec:0
total_forks: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
total_active_defrag_time:0
current_active_defrag_time:0
tracking_total_keys:0
tracking_total_items:0
tracking_total_prefixes:0
unexpected_error_replies:0
total_error_replies:16
dump_payload_sanitizations:0
total_reads_processed:175
total_writes_processed:173
io_threaded_reads_processed:0
io_threaded_writes_processed:0
io_threaded_freed_objects:0
io_threaded_poll_processed:0
io_threaded_total_prefetch_batches:0
io_threaded_total_prefetch_entries:0
client_query_buffer_limit_disconnections:0
client_output_buffer_limit_disconnections:0
reply_buffer_shrinks:1
reply_buffer_expands:0
eventloop_cycles:177
eventloop_duration_sum:4776432
eventloop_duration_cmd_sum:4771326
instantaneous_eventloop_cycles_per_sec:13
instantaneous_eventloop_duration_usec:1758
acl_access_denied_auth:0
acl_access_denied_cmd:0
acl_access_denied_key:0
acl_access_denied_channel:0
# Replication
role:master
connected_slaves:0
replicas_waiting_psync:0
master_failover_state:no-failover
master_replid:2aa3e4e668eca5ff37229fcbb2b2af087d64dbd3
master_replid2:0000000000000000000000000000000000000000
master_repl_offset:0
second_repl_offset:-1
repl_backlog_active:0
repl_backlog_size:10485760
repl_backlog_first_byte_offset:0
repl_backlog_histlen:0
# CPU
used_cpu_sys:0.677335
used_cpu_user:0.653072
used_cpu_sys_children:0.000000
used_cpu_user_children:0.000000
# Modules
# Commandstats
cmdstat_lrange:calls=1,usec=1,usec_per_call=1.00,rejected_calls=0,failed_calls=0
cmdstat_lpush:calls=5,usec=7,usec_per_call=1.40,rejected_calls=0,failed_calls=1
cmdstat_xgroup|create:calls=1,usec=3,usec_per_call=3.00,rejected_calls=0,failed_calls=0
cmdstat_blmove:calls=1,usec=0,usec_per_call=0.00,rejected_calls=0,failed_calls=0
cmdstat_xreadgroup:calls=3,usec=19,usec_per_call=6.33,rejected_calls=0,failed_calls=0
cmdstat_waitaof:calls=1,usec=0,usec_per_call=0.00,rejected_calls=0,failed_calls=0
cmdstat_del:calls=4,usec=2,usec_per_call=0.50,rejected_calls=1,failed_calls=0
cmdstat_blpop:calls=1,usec=3,usec_per_call=3.00,rejected_calls=0,failed_calls=0
cmdstat_set:calls=12,usec=22,usec_per_call=1.83,rejected_calls=0,failed_calls=0
cmdstat_wait:calls=1,usec=0,usec_per_call=0.00,rejected_calls=0,failed_calls=0
cmdstat_select:calls=4,usec=2,usec_per_call=0.50,rejected_calls=0,failed_calls=0
cmdstat_brpop:calls=1,usec=0,usec_per_call=0.00,rejected_calls=0,failed_calls=0
cmdstat_zadd:calls=2,usec=14,usec_per_call=7.00,rejected_calls=0,failed_calls=0
cmdstat_xadd:calls=2,usec=8,usec_per_call=4.00,rejected_calls=0,failed_calls=0
cmdstat_xread:calls=4,usec=8,usec_per_call=2.00,rejected_calls=0,failed_calls=0
cmdstat_fcall_ro:calls=2,usec=44,usec_per_call=22.00,rejected_calls=0,failed_calls=1
cmdstat_brpoplpush:calls=1,usec=0,usec_per_call=0.00,rejected_calls=0,failed_calls=0
cmdstat_fcall:calls=65,usec=4769897,usec_per_call=73383.03,rejected_calls=0,failed_calls=14
cmdstat_get:calls=7,usec=11,usec_per_call=1.57,rejected_calls=1,failed_calls=0
cmdstat_zmpop:calls=2,usec=8,usec_per_call=4.00,rejected_calls=0,failed_calls=0
cmdstat_randomkey:calls=1,usec=1,usec_per_call=1.00,rejected_calls=0,failed_calls=0
cmdstat_incr:calls=2,usec=6,usec_per_call=3.00,rejected_calls=0,failed_calls=1
cmdstat_function|load:calls=68,usec=1307,usec_per_call=19.22,rejected_calls=0,failed_calls=0
cmdstat_ping:calls=1,usec=2,usec_per_call=2.00,rejected_calls=0,failed_calls=0
cmdstat_rpush:calls=3,usec=5,usec_per_call=1.67,rejected_calls=0,failed_calls=0
cmdstat_bzpopmax:calls=1,usec=1,usec_per_call=1.00,rejected_calls=0,failed_calls=0
cmdstat_bzpopmin:calls=1,usec=1,usec_per_call=1.00,rejected_calls=0,failed_calls=0
cmdstat_lpop:calls=4,usec=8,usec_per_call=2.00,rejected_calls=0,failed_calls=0
cmdstat_hincrby:calls=1,usec=4,usec_per_call=4.00,rejected_calls=0,failed_calls=0
# Errorstats
errorstat_ERR:count=15
errorstat_WRONGTYPE:count=1
# Latencystats
latency_percentiles_usec_lrange:p50=1.003,p99=1.003,p99.9=1.003
latency_percentiles_usec_lpush:p50=1.003,p99=2.007,p99.9=2.007
latency_percentiles_usec_xgroup|create:p50=3.007,p99=3.007,p99.9=3.007
latency_percentiles_usec_blmove:p50=0.001,p99=0.001,p99.9=0.001
latency_percentiles_usec_xreadgroup:p50=2.007,p99=17.023,p99.9=17.023
latency_percentiles_usec_waitaof:p50=0.001,p99=0.001,p99.9=0.001
latency_percentiles_usec_del:p50=0.001,p99=1.003,p99.9=1.003
latency_percentiles_usec_blpop:p50=3.007,p99=3.007,p99.9=3.007
latency_percentiles_usec_set:p50=1.003,p99=6.015,p99.9=6.015
latency_percentiles_usec_wait:p50=0.001,p99=0.001,p99.9=0.001
latency_percentiles_usec_select:p50=0.001,p99=2.007,p99.9=2.007
latency_percentiles_usec_brpop:p50=0.001,p99=0.001,p99.9=0.001
latency_percentiles_usec_zadd:p50=3.007,p99=11.007,p99.9=11.007
latency_percentiles_usec_xadd:p50=3.007,p99=5.023,p99.9=5.023
latency_percentiles_usec_xread:p50=1.003,p99=4.015,p99.9=4.015
latency_percentiles_usec_fcall_ro:p50=22.015,p99=22.015,p99.9=22.015
latency_percentiles_usec_brpoplpush:p50=0.001,p99=0.001,p99.9=0.001
latency_percentiles_usec_fcall:p50=6.015,p99=562036.735,p99.9=1002438.655
latency_percentiles_usec_get:p50=0.001,p99=8.031,p99.9=8.031
latency_percentiles_usec_zmpop:p50=4.015,p99=4.015,p99.9=4.015
latency_percentiles_usec_randomkey:p50=1.003,p99=1.003,p99.9=1.003
latency_percentiles_usec_incr:p50=1.003,p99=5.023,p99.9=5.023
latency_percentiles_usec_function|load:p50=13.055,p99=79.359,p99.9=205.823
latency_percentiles_usec_ping:p50=2.007,p99=2.007,p99.9=2.007
latency_percentiles_usec_rpush:p50=1.003,p99=3.007,p99.9=3.007
latency_percentiles_usec_bzpopmax:p50=1.003,p99=1.003,p99.9=1.003
latency_percentiles_usec_bzpopmin:p50=1.003,p99=1.003,p99.9=1.003
latency_percentiles_usec_lpop:p50=2.007,p99=3.007,p99.9=3.007
latency_percentiles_usec_hincrby:p50=4.015,p99=4.015,p99.9=4.015
# Cluster
cluster_enabled:0
# Keyspace
db9:keys=6,expires=0,avg_ttl=0
db10:keys=1,expires=0,avg_ttl=0
------ CLIENT LIST OUTPUT ------
id=4 addr=127.0.0.1:51852 laddr=127.0.0.1:22611 fd=12 name= age=5 idle=0 flags=N db=9 sub=0 psub=0 ssub=0 multi=-1 watch=0 qbuf=0 qbuf-free=0 argv-mem=10 multi-mem=0 rbs=1024 rbp=117 obl=0 oll=0 omem=0 tot-mem=1954 events=r cmd=fcall user=default redir=-1 resp=2 lib-name= lib-ver= tot-net-in=23400 tot-net-out=3477 tot-cmds=201
------ CURRENT CLIENT INFO ------
id=4 addr=127.0.0.1:51852 laddr=127.0.0.1:22611 fd=12 name= age=5 idle=0 flags=N db=9 sub=0 psub=0 ssub=0 multi=-1 watch=0 qbuf=0 qbuf-free=0 argv-mem=10 multi-mem=0 rbs=1024 rbp=117 obl=0 oll=0 omem=0 tot-mem=1954 events=r cmd=fcall user=default redir=-1 resp=2 lib-name= lib-ver= tot-net-in=23400 tot-net-out=3477 tot-cmds=201
argc: '3'
argv[0]: '"fcall"'
argv[1]: '"test"'
argv[2]: '"0"'
------ EXECUTING CLIENT INFO ------
id=4 addr=127.0.0.1:51852 laddr=127.0.0.1:22611 fd=12 name= age=5 idle=0 flags=N db=9 sub=0 psub=0 ssub=0 multi=-1 watch=0 qbuf=0 qbuf-free=0 argv-mem=10 multi-mem=0 rbs=1024 rbp=117 obl=0 oll=0 omem=0 tot-mem=1954 events=r cmd=fcall user=default redir=-1 resp=2 lib-name= lib-ver= tot-net-in=23400 tot-net-out=3477 tot-cmds=201
argc: '3'
argv[0]: '"fcall"'
argv[1]: '"test"'
argv[2]: '"0"'
------ MODULES INFO OUTPUT ------
------ CONFIG DEBUG OUTPUT ------
replica-read-only yes
dual-channel-replication-enabled no
lazyfree-lazy-eviction yes
repl-diskless-sync yes
io-threads-do-reads yes
proto-max-bulk-len 512mb
list-compress-depth 0
lazyfree-lazy-user-del yes
sanitize-dump-payload no
slave-read-only yes
activedefrag no
lazyfree-lazy-server-del yes
lazyfree-lazy-user-flush yes
debug-context ""
client-query-buffer-limit 1gb
repl-diskless-load disabled
io-threads 1
lazyfree-lazy-expire yes
------ DUMPING CODE AROUND EIP ------
Symbol: luaS_newlstr (base: 0x10243f2a0)
Module: /Users/swaingotnochill/sources/source_files/valkey/src/valkey-server (base 0x102424000)
$ xxd -r -p /tmp/dump.hex /tmp/dump.bin
$ objdump --adjust-vma=0x10243f2a0 -D -b binary -m i386:x86-64 /tmp/dump.bin
------
27666:M 09 Oct 2024 00:53:56.151 # dump of function (hexdump of 184 bytes):
f85fbca9f65701a9f44f02a9fd7b03a9fdc30091f40302aaf50301aaf30300aa820100b4a80600d1e90314aaf80314aa0a6b1b534a09580b0b6969384a010b0b5801184a290500f141ffff540200001418008052771240f9e80240f9e90e40b9290500512901180a16d969f8760600b5886e00911f6500f168000054e00313aa07eeff9783660091e00313aa010080d2020080d2e7edff97f60300aa140800f9180c00b9681240f908814039080500120824003988008052
=== VALKEY BUG REPORT END. Make sure to include from START to END. ===
[[0;31;49mexception[0m]: Executing test client: I/O error reading reply.
I/O error reading reply
while executing
"[srv $level "client"] {*}$args"
(procedure "r" line 7)
invoked from within
"r fcall test {*}[lrange $args 1 end]"
(procedure "run_script" line 8)
invoked from within
"run_script {return bit.tohex(65535, -2147483648)} 0"
("uplevel" body line 2)
invoked from within
"uplevel 1 $code"
(procedure "test" line 58)
invoked from within
"test {lua bit.tohex bug} {
set res [run_script {return bit.tohex(65535, -2147483648)} 0]
r ping
set res
} {0000FFFF}"
("uplevel" body line 661)
invoked from within
"uplevel 1 $code "
(procedure "start_server" line 2)
invoked from within
"start_server {tags {"scripting"}} {
if {$is_eval eq 1 && $script_compatibility_api == "redis"} {
test {Script - disallow write on OOM} {
..."
("foreach" body line 72)
invoked from within
"foreach script_compatibility_api {server redis} {
# We run the tests using both the server APIs, e.g. server.call(), and valkey APIs, e.g. redis.call..."
("foreach" body line 2)
invoked from within
"foreach is_eval {0 1} {
foreach script_compatibility_api {server redis} {
# We run the tests using both the server APIs, e.g. server.call(), and valk..."
(file "tests/unit/scripting.tcl" line 1)
invoked from within
"source $path"
(procedure "execute_test_file" line 4)
invoked from within
"execute_test_file $data"
(procedure "test_client_main" line 10)
invoked from within
"test_client_main $::test_server_port "
Additional information
OS: MacOS 14.5 23F79 arm64
Kernel: 23.5.0
Steps to reproduce:
1. Run the `make` command in the root directory.
2. Run `make test` command.
