2012-10-12 11:29:05.551667 D [68542:70350125805860] SimpleTCPServer -- Waiting for a client to connect 2012-10-12 11:29:05.552971 T [68542:70350121687760] ResilientSocket::TCPClient ["localhost:2000"] -- ==> Sending -- #actionfailattempt 2012-10-12 11:29:05.553407 D [68542:70350121687760] (0.4ms) ResilientSocket::TCPClient ["localhost:2000"] -- ==> #send Sent 35 bytes 2012-10-12 11:29:05.553640 D [68542:70350125805860] SimpleTCPServer -- Client connected, waiting for data from client 2012-10-12 11:29:05.553837 D [68542:70350125805860] SimpleTCPServer -- ****************** Received request 2012-10-12 11:29:05.553852 T [68542:70350125805860] SimpleTCPServer -- Request -- {"action"=>"fail", "attempt"=>1} 2012-10-12 11:29:05.553868 D [68542:70350125805860] SimpleTCPServer -- Closing client since no reply is being sent back 2012-10-12 11:29:05.553909 D [68542:70350125805860] SimpleTCPServer -- Server closed 2012-10-12 11:29:05.553922 D [68542:70350125805860] SimpleTCPServer -- thread killed 2012-10-12 11:29:05.557671 D [68542:70350125805860] SimpleTCPServer -- Server Restarted 2012-10-12 11:29:05.557795 D [68542:70350125848780] SimpleTCPServer -- Waiting for a client to connect 2012-10-12 11:29:05.557905 W [68542:70350121687760] ResilientSocket::TCPClient ["localhost:2000"] -- #read Connection failure while reading data: EOFError: end of file reached 2012-10-12 11:29:05.558010 D [68542:70350121687760] (4.5ms) ResilientSocket::TCPClient ["localhost:2000"] -- <== #read Received upto 4 bytes -- ResilientSocket::ConnectionFailure: EOFError: end of file reached /Users/rmorrison/Sandbox/resilient_socket/lib/resilient_socket/tcp_client.rb:269:in `rescue in block in read' /Users/rmorrison/Sandbox/resilient_socket/lib/resilient_socket/tcp_client.rb:263:in `block in read' /Users/rmorrison/.rvm/gems/ruby-1.9.3-p194/gems/semantic_logger-0.7.0/lib/semantic_logger/base.rb:181:in `benchmark_debug' /Users/rmorrison/Sandbox/resilient_socket/lib/resilient_socket/tcp_client.rb:247:in `read' /Users/rmorrison/Sandbox/resilient_socket/test/simple_tcp_server.rb:11:in `read_bson_document' test/tcp_client_test.rb:92:in `block (5 levels) in ' /Users/rmorrison/Sandbox/resilient_socket/lib/resilient_socket/tcp_client.rb:338:in `retry_on_connection_failure' test/tcp_client_test.rb:87:in `block (4 levels) in ' /Users/rmorrison/.rvm/gems/ruby-1.9.3-p194/gems/shoulda-context-1.0.0/lib/shoulda/context/context.rb:398:in `call' /Users/rmorrison/.rvm/gems/ruby-1.9.3-p194/gems/shoulda-context-1.0.0/lib/shoulda/context/context.rb:398:in `block in create_test_from_should_hash' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:949:in `run' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit/testcase.rb:17:in `run' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:787:in `block in _run_suite' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:780:in `map' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:780:in `_run_suite' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:565:in `block in _run_suites' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:563:in `each' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:563:in `_run_suites' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:746:in `_run_anything' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:909:in `run_tests' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:896:in `block in _run' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:895:in `each' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:895:in `_run' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/minitest/unit.rb:884:in `run' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:21:in `run' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:326:in `block (2 levels) in autorun' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:27:in `run_once' /Users/rmorrison/.rvm/rubies/ruby-1.9.3-p194/lib/ruby/1.9.1/test/unit.rb:325:in `block in autorun' 2012-10-12 11:29:05.558043 W [68542:70350121687760] ResilientSocket::TCPClient ["localhost:2000"] -- #retry_on_connection_failure Connection failure: EOFError: end of file reached. Retry: 1 2012-10-12 11:29:05.558865 T [68542:70350121687760] ResilientSocket::TCPClient ["localhost:2000"] -- ==> Sending -- #actionfailattempt 2012-10-12 11:29:05.559013 D [68542:70350121687760] (0.1ms) ResilientSocket::TCPClient ["localhost:2000"] -- ==> #send Sent 35 bytes 2012-10-12 11:29:05.559194 D [68542:70350125848780] SimpleTCPServer -- Client connected, waiting for data from client 2012-10-12 11:29:05.559345 D [68542:70350125848780] SimpleTCPServer -- ****************** Received request 2012-10-12 11:29:05.559360 T [68542:70350125848780] SimpleTCPServer -- Request -- {"action"=>"fail", "attempt"=>2} 2012-10-12 11:29:05.559376 D [68542:70350125848780] SimpleTCPServer -- Sending Reply 2012-10-12 11:29:05.559387 T [68542:70350125848780] SimpleTCPServer -- Reply -- {"result"=>"fail"} 2012-10-12 11:29:05.559782 T [68542:70350121687760] ResilientSocket::TCPClient ["localhost:2000"] -- <== #read Received --  2012-10-12 11:29:05.559796 D [68542:70350121687760] (0.7ms) ResilientSocket::TCPClient ["localhost:2000"] -- <== #read Received upto 4 bytes 2012-10-12 11:29:05.559845 T [68542:70350121687760] ResilientSocket::TCPClient ["localhost:2000"] -- <== #read Received -- resultfail 2012-10-12 11:29:05.559854 D [68542:70350121687760] (0.0ms) ResilientSocket::TCPClient ["localhost:2000"] -- <== #read Received upto 18 bytes