Protocol error, got ' ' as initial reply byte

[balepc]

Hello,
I got this error after some time working on production. I use redis-1.2.6. Here is a trace:

 Protocol error, got ' ' as initial reply byte
   /usr/local/lib/ruby/gems/1.8/gems/ezmobius-redis-0.1/lib/redis.rb:296:in `read_reply'
   /usr/local/lib/ruby/gems/1.8/gems/ezmobius-redis-0.1/lib/redis.rb:200:in `raw_call_command'
   /usr/local/lib/ruby/gems/1.8/gems/ezmobius-redis-0.1/lib/redis.rb:198:in `map'
   /usr/local/lib/ruby/gems/1.8/gems/ezmobius-redis-0.1/lib/redis.rb:198:in `raw_call_command'
   /usr/local/lib/ruby/gems/1.8/gems/ezmobius-redis-0.1/lib/redis.rb:163:in `call_command'
   /usr/local/lib/ruby/gems/1.8/gems/redis-store-0.3.7/lib/redis/marshaled_redis.rb:13:in `get'
   /usr/local/lib/ruby/gems/1.8/gems/redis-store-0.3.7/lib/cache/rails/redis_store.rb:27:in `read'

I assume this is a redis bug?

[ezmobius]

What version of redis server are you using? The version from git? This smells like a redis server bug to me so I might ask on the redis list or in irc.

[balepc]

Hello,

I use redis-1.2.6. I've asked on redis list also, no response yet.

But it seems I figured out what was causing the bug. I've attached log file and got the following:

29 Mar 19:10:29 - 10 changes in 300 seconds. Saving...
29 Mar 19:10:29 * Failed saving the DB: Permission denied
29 Mar 19:10:29 - Background saving started by pid 26565
29 Mar 19:10:30 * Background saving error
29 Mar 19:10:31 - 10 changes in 300 seconds. Saving...
29 Mar 19:10:31 * Failed saving the DB: Permission denied
29 Mar 19:10:31 - Background saving started by pid 26566
29 Mar 19:10:32 * Background saving error
29 Mar 19:10:33 - 10 changes in 300 seconds. Saving...
29 Mar 19:10:33 * Failed saving the DB: Permission denied
29 Mar 19:10:33 - Background saving started by pid 26567
29 Mar 19:10:34 * Background saving error
29 Mar 19:10:35 - 10 changes in 300 seconds. Saving...
29 Mar 19:10:35 * Failed saving the DB: Permission denied
29 Mar 19:10:35 - Background saving started by pid 26568
29 Mar 19:10:36 * Background saving error
29 Mar 19:10:37 - 10 changes in 300 seconds. Saving...
29 Mar 19:10:37 * Failed saving the DB: Permission denied
29 Mar 19:10:37 - Background saving started by pid 26569

Redis-server was trying to save db, but faced permission problem. So it created a new process every second, which seems quite strange to me.

So I fixed permissions I it stabilised for now.