ActiveRecord says connection timeout when it ain't so

another_strange_error.log

NameError: uninitialized constant Relation
  load_missing_constant at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activesupport-3.0.9/lib/active_support/dependencies.rb:509
          const_missing at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activesupport-3.0.9/lib/active_support/dependencies.rb:183
                   each at org/jruby/RubyArray.java:1603
          const_missing at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activesupport-3.0.9/lib/active_support/dependencies.rb:181
               relation at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activerecord-3.0.9/lib/active_record/base.rb:906
                 scoped at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activerecord-3.0.9/lib/active_record/named_scope.rb:32
                  first at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activerecord-3.0.9/lib/active_record/base.rb:444
               __file__ at ar_test.rb:26
                   call at org/jruby/RubyProc.java:268
                   call at org/jruby/RubyProc.java:232

still_another_strange_error.log

ArgumentError: wrong number of arguments (2 for 0)
  relation at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activerecord-3.0.9/lib/active_record/base.rb:906
    scoped at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activerecord-3.0.9/lib/active_record/named_scope.rb:32
     first at /Users/bpardee/.rvm/gems/jruby-1.6.3@claritybase/gems/activerecord-3.0.9/lib/active_record/base.rb:444
  __file__ at ar_test.rb:26
      call at org/jruby/RubyProc.java:268
      call at org/jruby/RubyProc.java:232

test.rb

require 'rubygems'
require 'active_record'

# Print out what version we're running
puts "Active Record #{ActiveRecord::VERSION::STRING}"

ActiveRecord::Base.establish_connection(
  :adapter  => 'jdbcmysql',
  :database => 'foo_development',
  :username => 'root',
  :password => '',
  :host     => '127.0.0.1'
)

# ActiveRecord::Schema.define do
#   create_table :users, :force => true do |t|
#   end
# end

class User < ActiveRecord::Base
end

threads = []
(1..100).each do |i|
  threads << Thread.new do
    User.first
    ActiveRecord::Base.clear_active_connections!
  end
end
threads.each { |t| t.join }

verbose_connection_pool.rb

require 'thread'
require 'monitor'
require 'set'
require 'active_support/core_ext/module/synchronization'

module ActiveRecord
  # Raised when a connection could not be obtained within the connection
  # acquisition timeout period.
  class ConnectionTimeoutError < ConnectionNotEstablished
  end

  module ConnectionAdapters
    # Connection pool base class for managing Active Record database
    # connections.
    #
    # == Introduction
    #
    # A connection pool synchronizes thread access to a limited number of
    # database connections. The basic idea is that each thread checks out a
    # database connection from the pool, uses that connection, and checks the
    # connection back in. ConnectionPool is completely thread-safe, and will
    # ensure that a connection cannot be used by two threads at the same time,
    # as long as ConnectionPool's contract is correctly followed. It will also
    # handle cases in which there are more threads than connections: if all
    # connections have been checked out, and a thread tries to checkout a
    # connection anyway, then ConnectionPool will wait until some other thread
    # has checked in a connection.
    #
    # == Obtaining (checking out) a connection
    #
    # Connections can be obtained and used from a connection pool in several
    # ways:
    #
    # 1. Simply use ActiveRecord::Base.connection as with Active Record 2.1 and
    #    earlier (pre-connection-pooling). Eventually, when you're done with
    #    the connection(s) and wish it to be returned to the pool, you call
    #    ActiveRecord::Base.clear_active_connections!. This will be the
    #    default behavior for Active Record when used in conjunction with
    #    Action Pack's request handling cycle.
    # 2. Manually check out a connection from the pool with
    #    ActiveRecord::Base.connection_pool.checkout. You are responsible for
    #    returning this connection to the pool when finished by calling
    #    ActiveRecord::Base.connection_pool.checkin(connection).
    # 3. Use ActiveRecord::Base.connection_pool.with_connection(&block), which
    #    obtains a connection, yields it as the sole argument to the block,
    #    and returns it to the pool after the block completes.
    #
    # Connections in the pool are actually AbstractAdapter objects (or objects
    # compatible with AbstractAdapter's interface).
    #
    # == Options
    #
    # There are two connection-pooling-related options that you can add to
    # your database connection configuration:
    #
    # * +pool+: number indicating size of connection pool (default 5)
    # * +wait_timeout+: number of seconds to block and wait for a connection
    #   before giving up and raising a timeout error (default 5 seconds).
    class ConnectionPool
      attr_reader :spec, :connections

      # Creates a new ConnectionPool object. +spec+ is a ConnectionSpecification
      # object which describes database connection information (e.g. adapter,
      # host name, username, password, etc), as well as the maximum size for
      # this ConnectionPool.
      #
      # The default ConnectionPool maximum size is 5.
      def initialize(spec)
        @spec = spec

        # The cache of reserved connections mapped to threads
        @reserved_connections = {}

        # The mutex used to synchronize pool access
        @connection_mutex = Monitor.new
        @queue = @connection_mutex.new_cond

        # default 5 second timeout unless on ruby 1.9
        @timeout = spec.config[:wait_timeout] || 5

        # default max pool size to 5
        @size = (spec.config[:pool] && spec.config[:pool].to_i) || 5

        @connections = []
        @checked_out = []
      end

      # Retrieve the connection associated with the current thread, or call
      # #checkout to obtain one if necessary.
      #
      # #connection can be called any number of times; the connection is
      # held in a hash keyed by the thread id.
      def connection
        @reserved_connections[current_connection_id] ||= checkout
      end

      # Signal that the thread is finished with the current connection.
      # #release_connection releases the connection-thread association
      # and returns the connection to the pool.
      def release_connection(with_id = current_connection_id)
        conn = @reserved_connections.delete(with_id)
        checkin conn if conn
      end

      # If a connection already exists yield it to the block.  If no connection
      # exists checkout a connection, yield it to the block, and checkin the
      # connection when finished.
      def with_connection
        connection_id = current_connection_id
        fresh_connection = true unless @reserved_connections[connection_id]
        yield connection
      ensure
        release_connection(connection_id) if fresh_connection
      end

      # Returns true if a connection has already been opened.
      def connected?
        !@connections.empty?
      end

      # Disconnects all connections in the pool, and clears the pool.
      def disconnect!
        @reserved_connections.each do |name,conn|
          checkin conn
        end
        @reserved_connections = {}
        @connections.each do |conn|
          conn.disconnect!
        end
        @connections = []
      end

      # Clears the cache which maps classes
      def clear_reloadable_connections!
        @reserved_connections.each do |name, conn|
          checkin conn
        end
        @reserved_connections = {}
        @connections.each do |conn|
          conn.disconnect! if conn.requires_reloading?
        end
        @connections.delete_if do |conn|
          conn.requires_reloading?
        end
      end

      # Verify active connections and remove and disconnect connections
      # associated with stale threads.
      def verify_active_connections! #:nodoc:
        clear_stale_cached_connections!
        @connections.each do |connection|
          connection.verify!
        end
      end

      # Return any checked-out connections back to the pool by threads that
      # are no longer alive.
      def clear_stale_cached_connections!
        keys = @reserved_connections.keys - Thread.list.find_all { |t|
          t.alive?
        }.map { |thread| thread.object_id }
        keys.each do |key|
          checkin @reserved_connections[key]
          @reserved_connections.delete(key)
        end
      end

      # Check-out a database connection from the pool, indicating that you want
      # to use it. You should call #checkin when you no longer need this.
      #
      # This is done by either returning an existing connection, or by creating
      # a new connection. If the maximum number of connections for this pool has
      # already been reached, but the pool is empty (i.e. they're all being used),
      # then this method will wait until a thread has checked in a connection.
      # The wait time is bounded however: if no connection can be checked out
      # within the timeout specified for this pool, then a ConnectionTimeoutError
      # exception will be raised.
      #
      # Returns: an AbstractAdapter object.
      #
      # Raises:
      # - ConnectionTimeoutError: no connection can be obtained from the pool
      #   within the timeout period.
      def checkout
        # Checkout an available connection
puts "#{Thread.current.object_id} before mutex #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
        @connection_mutex.synchronize do
puts "#{Thread.current.object_id} in mutex #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
          loop do
puts "#{Thread.current.object_id} before checkout connection #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
            conn = if @checked_out.size < @connections.size
                     checkout_existing_connection
                   elsif @connections.size < @size
                     checkout_new_connection
                   end
puts "#{Thread.current.object_id} after checkout connection #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size} conn=#{conn}"
            return conn if conn
puts "#{Thread.current.object_id} waiting for connection #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
            puts "#{Thread.current.object_id} #{@queue.wait(@timeout)}"
puts "#{Thread.current.object_id} down waiting for connection #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"

            if(@checked_out.size < @connections.size)
              next
            else
              clear_stale_cached_connections!
              if @size == @checked_out.size
                raise ConnectionTimeoutError, "#{Thread.current.object_id} could not obtain a database connection#{" within #{@timeout} seconds" if @timeout}.  The max pool size is currently #{@size}; consider increasing it."
              end
            end

          end
        end
puts "#{Thread.current.object_id} after mutex #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
      end

      # Check-in a database connection back into the pool, indicating that you
      # no longer need this connection.
      #
      # +conn+: an AbstractAdapter object, which was obtained by earlier by
      # calling +checkout+ on this pool.
      def checkin(conn)
        @connection_mutex.synchronize do
          conn.send(:_run_checkin_callbacks) do
puts "#{Thread.current.object_id} before signal #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
            @checked_out.delete conn
            @queue.signal
puts "#{Thread.current.object_id} after signal #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
          end
        end
      end

      synchronize :clear_reloadable_connections!, :verify_active_connections!,
        :connected?, :disconnect!, :with => :@connection_mutex

      private
      def new_connection
        ActiveRecord::Base.send(spec.adapter_method, spec.config)
      end

      def current_connection_id #:nodoc:
        Thread.current.object_id
      end

      def checkout_new_connection
        c = new_connection
        @connections << c
        checkout_and_verify(c)
      end

      def checkout_existing_connection
        c = (@connections - @checked_out).first
        checkout_and_verify(c)
      end

      def checkout_and_verify(c)
        c.run_callbacks :checkout do
          c.verify!
puts "#{Thread.current.object_id} before checkout_and_verify #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
          @checked_out << c
puts "#{Thread.current.object_id} after checkout_and_verify #{Time.now} size=#{@size} cosize=#{@checked_out.size} conn size=#{@connections.size}"
        end
        c
      end
    end

    # ConnectionHandler is a collection of ConnectionPool objects. It is used
    # for keeping separate connection pools for Active Record models that connect
    # to different databases.
    #
    # For example, suppose that you have 5 models, with the following hierarchy:
    #
    #  |
    #  +-- Book
    #  |    |
    #  |    +-- ScaryBook
    #  |    +-- GoodBook
    #  +-- Author
    #  +-- BankAccount
    #
    # Suppose that Book is to connect to a separate database (i.e. one other
    # than the default database). Then Book, ScaryBook and GoodBook will all use
    # the same connection pool. Likewise, Author and BankAccount will use the
    # same connection pool. However, the connection pool used by Author/BankAccount
    # is not the same as the one used by Book/ScaryBook/GoodBook.
    #
    # Normally there is only a single ConnectionHandler instance, accessible via
    # ActiveRecord::Base.connection_handler. Active Record models use this to
    # determine that connection pool that they should use.
    class ConnectionHandler
      attr_reader :connection_pools

      def initialize(pools = {})
        @connection_pools = pools
      end

      def establish_connection(name, spec)
        @connection_pools[name] = ConnectionAdapters::ConnectionPool.new(spec)
      end

      # Returns any connections in use by the current thread back to the pool,
      # and also returns connections to the pool cached by threads that are no
      # longer alive.
      def clear_active_connections!
        @connection_pools.each_value {|pool| pool.release_connection }
      end

      # Clears the cache which maps classes
      def clear_reloadable_connections!
        @connection_pools.each_value {|pool| pool.clear_reloadable_connections! }
      end

      def clear_all_connections!
        @connection_pools.each_value {|pool| pool.disconnect! }
      end

      # Verify active connections.
      def verify_active_connections! #:nodoc:
        @connection_pools.each_value {|pool| pool.verify_active_connections! }
      end

      # Locate the connection of the nearest super class. This can be an
      # active or defined connection: if it is the latter, it will be
      # opened and set as the active connection for the class it was defined
      # for (not necessarily the current class).
      def retrieve_connection(klass) #:nodoc:
        pool = retrieve_connection_pool(klass)
        (pool && pool.connection) or raise ConnectionNotEstablished
      end

      # Returns true if a connection that's accessible to this class has
      # already been opened.
      def connected?(klass)
        conn = retrieve_connection_pool(klass)
        conn && conn.connected?
      end

      # Remove the connection for this class. This will close the active
      # connection and the defined connection (if they exist). The result
      # can be used as an argument for establish_connection, for easily
      # re-establishing the connection.
      def remove_connection(klass)
        pool = @connection_pools[klass.name]
        return nil unless pool

        @connection_pools.delete_if { |key, value| value == pool }
        pool.disconnect!
        pool.spec.config
      end

      def retrieve_connection_pool(klass)
        pool = @connection_pools[klass.name]
        return pool if pool
        return nil if ActiveRecord::Base == klass
        retrieve_connection_pool klass.superclass
      end
    end

    class ConnectionManagement
      def initialize(app)
        @app = app
      end

      def call(env)
        @app.call(env)
      ensure
        # Don't return connection (and perform implicit rollback) if
        # this request is a part of integration test
        unless env.key?("rack.test")
          ActiveRecord::Base.clear_active_connections!
        end
      end
    end
  end
end

verbose_output.log

Active Record 3.0.9
2318 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=02320 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2320 in mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2320 before checkout connection Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0

2322 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2324 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2328 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2326 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2330 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2332 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2334 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2336 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2338 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2340 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2342 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2344 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2346 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2348 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2350 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2352 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2354 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2356 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2358 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2360 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2362 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2364 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2368 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=02366 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0

2370 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2372 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2374 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2376 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2378 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2380 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2382 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2384 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2386 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2388 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2390 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2392 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2394 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2396 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2398 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2400 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2402 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2404 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2406 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2408 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2410 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2412 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2414 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2416 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2420 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2430 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2428 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2418 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2424 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2432 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2422 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2426 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2434 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2436 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2438 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2440 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2442 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2444 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2446 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2448 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2450 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2452 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2454 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2456 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2458 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2460 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2462 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2464 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2466 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2468 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2470 before mutex Tue Aug 16 11:35:11 -0400 2011 size=5 cosize=0 conn size=0
2472 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2474 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2476 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2478 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2480 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2482 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2484 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2486 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2488 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2490 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2492 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2494 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2496 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2498 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2500 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2502 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2504 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2506 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2508 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2510 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2512 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2514 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2516 before mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=0
2320 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=0 conn size=1
2320 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=1 conn size=1
2320 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=1 conn size=1 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2318 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=1 conn size=1
2318 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=1 conn size=1
2318 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=1 conn size=2
2318 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=2
2318 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=2 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2322 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=2
2322 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=2
2322 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=3
2322 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=3
2322 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=3 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2322 before signal Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=3
2322 after signal Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=3
2324 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=3
2324 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=3
2324 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=2 conn size=3
2324 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=3
2324 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=3 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2326 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=3
2326 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=3
2326 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=4
2326 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=4
2326 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=4 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2326 before signal Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=4
2326 after signal Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=4
2330 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=4
2330 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=4
2330 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=3 conn size=4
2330 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=4
2330 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=4 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2334 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=4
2334 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=4
2334 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2334 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2334 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2336 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2336 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2336 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2336 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2338 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2338 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2338 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2338 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2340 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2340 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2340 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2340 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2342 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2342 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2342 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2342 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2344 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2344 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2344 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2344 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2334 before signal Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2334 after signal Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2336 true
2336 done waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2336 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2336 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2336 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2336 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2346 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2346 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2346 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2346 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2350 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2350 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2350 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2350 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2352 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2352 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2352 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2352 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2336 before signal Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2336 after signal Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2354 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2354 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2354 before checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=4 conn size=5
2354 after checkout_and_verify Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2354 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2338 true
2338 done waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2360 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2360 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2360 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2360 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2362 in mutex Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2362 before checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2362 after checkout connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5 conn=
2362 waiting for connection Tue Aug 16 11:35:12 -0400 2011 size=5 cosize=5 conn size=5
2364 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2364 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2364 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2364 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2366 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2366 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2366 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2366 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2368 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2368 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2368 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2368 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2370 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2370 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2370 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2370 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2372 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2372 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2372 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2372 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2374 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2374 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2374 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2374 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2376 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2376 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2376 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2376 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2378 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2378 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2378 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2378 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2380 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2380 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2380 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2380 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2382 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2382 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2382 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2382 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2384 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2384 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2384 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2384 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2386 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2386 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2386 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2386 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2388 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2388 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2388 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2388 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2390 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2390 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2390 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2390 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2392 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2392 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2392 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2392 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2394 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2394 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2394 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2394 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2396 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2396 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2396 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2396 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2398 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2398 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2398 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2398 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2400 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2400 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2400 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2400 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2402 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2402 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2402 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2402 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2404 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2404 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2404 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2404 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2406 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2406 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2406 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2406 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2408 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2408 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2408 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2408 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2410 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2410 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2410 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2410 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2412 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2412 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2412 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2412 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2414 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2414 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2414 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2414 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2430 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2430 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2430 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2430 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2420 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2420 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2420 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2420 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2428 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2428 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2428 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2428 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2418 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2418 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2418 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2418 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2416 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2416 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2416 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2416 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2424 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2424 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2424 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2424 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2432 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2432 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2432 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2432 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2422 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2422 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2422 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2422 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2426 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2426 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2426 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2426 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2434 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2434 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2434 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2434 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2436 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2436 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2436 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2436 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2438 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2438 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2438 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2438 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2440 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2440 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2440 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2440 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2442 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2442 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2442 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2442 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2444 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2444 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2444 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2444 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2446 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2446 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2446 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2446 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2448 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2448 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2448 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2448 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2450 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2450 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2450 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2450 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2452 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2452 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2452 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2452 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2454 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2454 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2454 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2454 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2456 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2456 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2456 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2456 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2458 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2458 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2458 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2458 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2460 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2460 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2460 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2460 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2462 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2462 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2462 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2462 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2464 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2464 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2464 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2464 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2466 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2466 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2466 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2466 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2468 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2468 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2468 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2468 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2470 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2470 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2470 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2470 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2472 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2472 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2472 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2472 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2474 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2474 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2474 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2474 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2476 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2476 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2476 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2476 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2478 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2478 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2478 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2478 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2480 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2480 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2480 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2480 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2482 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2482 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2482 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2482 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2484 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2484 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2484 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2484 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2486 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2486 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2486 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2486 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2488 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2488 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2488 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2488 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2490 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2490 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2490 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2490 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2492 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2492 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2492 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2492 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2494 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2494 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2494 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2494 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2496 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2496 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2496 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2496 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2498 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2498 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2498 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2498 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2500 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2500 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2500 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2500 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2502 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2502 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2502 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2502 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2504 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2504 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2504 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2504 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2506 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2506 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2506 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2506 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2508 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2508 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2508 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2508 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2510 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2510 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2510 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2510 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2512 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2512 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2512 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2512 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2514 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2514 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2514 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2514 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2516 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2516 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2516 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2516 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2320 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2320 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2340 true
2340 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2340 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2340 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2340 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2340 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2318 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2318 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2328 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2328 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2328 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2328 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2328 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2342 true
2342 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2332 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2332 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2332 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2332 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2330 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2330 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2348 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2348 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2348 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2348 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2348 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2344 true
2344 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2356 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2356 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2356 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2356 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2348 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2348 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2346 true
2346 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2346 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2346 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2346 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2346 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2324 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2324 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2350 true
2350 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2350 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2350 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2350 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2350 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2358 in mutex Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2358 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2358 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=
2358 waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2354 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2354 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2352 true
2352 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2352 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2352 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2352 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2352 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2340 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2340 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2360 true
2360 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2360 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2360 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2360 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2360 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2350 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2350 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2362 true
2362 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2362 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2362 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2362 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2362 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2360 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2360 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2364 true
2364 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2364 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2364 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2364 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2364 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2364 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2364 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2366 true
2366 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2366 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2366 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2366 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2366 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2328 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2328 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2368 true
2368 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2368 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2368 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2368 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2368 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2366 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2366 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2370 true
2370 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2370 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2370 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2370 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2370 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2370 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2370 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2372 true
2372 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2372 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2372 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2372 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2372 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2372 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2372 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2368 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2368 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2376 true
2376 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2376 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2376 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2376 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2376 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2374 true
2374 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2374 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2374 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2374 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2374 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2376 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2376 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2374 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2374 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2378 true
2378 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2378 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2378 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2378 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2378 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2380 true
2380 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2380 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2380 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2380 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2380 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2378 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2378 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2346 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2346 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2382 true
2382 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2382 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2382 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2382 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2382 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2384 true
2384 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2384 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2384 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2384 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2384 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2362 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2362 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2386 true
2386 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2386 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2386 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2386 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2386 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2382 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2382 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2386 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2386 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2388 true
2388 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2388 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2388 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2388 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2388 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2390 true
2390 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2390 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2390 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2390 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2390 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2380 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2380 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2392 true
2392 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2392 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2392 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2392 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2392 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2390 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2390 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2394 true
2394 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2394 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2394 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2394 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2394 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2388 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2388 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2396 true
2396 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2396 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2396 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2396 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2396 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2396 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2396 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2398 true
2398 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2398 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2398 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2398 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2398 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2394 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2394 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2400 true
2400 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2400 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2400 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2400 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2400 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2392 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2392 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2402 true
2402 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2402 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2402 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2402 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2402 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2384 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2384 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2404 true
2404 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2404 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2404 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2404 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2404 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2400 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2400 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2406 true
2406 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2406 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2406 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2406 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2406 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2404 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2404 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2408 true
2408 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2408 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2408 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2408 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2408 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2406 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2406 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2410 true
2410 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2410 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2410 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2410 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2410 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2408 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2408 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2402 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2402 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2412 true
2412 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2412 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2412 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2412 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2412 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2410 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2410 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2414 true
2414 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2414 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2414 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2414 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2414 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2430 true
2430 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2430 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2430 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2430 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2430 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2398 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2398 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2430 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2430 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2414 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2414 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2412 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2412 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=1 conn size=5
2420 true
2420 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=1 conn size=5
2420 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=1 conn size=5
2420 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=1 conn size=5
2420 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2420 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2416 true
2416 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2416 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2416 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2416 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2416 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2418 true
2418 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2418 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2418 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2418 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2418 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2428 true
2428 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2428 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2428 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2428 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2428 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2352 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2352 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2424 true
2424 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2424 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2424 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2424 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2424 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2418 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2418 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2432 true
2432 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2432 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2432 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2432 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2432 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2428 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2428 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2422 true
2422 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2422 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2422 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2422 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2422 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2420 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2420 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2426 true
2426 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2426 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2426 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2426 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2426 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2432 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2432 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2434 true
2434 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2434 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2434 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2434 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2434 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2434 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2434 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2436 true
2436 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2436 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2436 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2436 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2436 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2436 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2436 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2438 true
2438 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2438 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2438 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2438 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2438 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2426 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2426 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2440 true
2440 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2440 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2440 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2440 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2440 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2422 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2422 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2442 true
2442 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2442 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2442 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2442 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2442 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2424 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2424 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2444 true
2444 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2444 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2444 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2444 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2444 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2416 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2416 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2446 true
2446 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2446 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2446 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2446 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2446 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2442 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2442 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2448 true
2448 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2448 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2448 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2448 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2448 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2438 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2438 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2440 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2440 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2450 true
2450 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2450 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2450 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2450 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2450 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2452 true
2452 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2452 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2452 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2452 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2452 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2450 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2450 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2454 true
2454 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2454 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2454 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2454 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2454 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2454 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2454 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2456 true
2456 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2456 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2456 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2456 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2456 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2448 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2448 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2458 true
2458 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2458 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2458 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2458 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2458 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2456 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2456 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2460 true
2460 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2460 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2460 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2460 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2460 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2460 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2460 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2452 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2452 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2462 true
2462 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2462 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2462 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2462 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2462 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2464 true
2464 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2464 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2464 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2464 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2464 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2458 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2458 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2466 true
2466 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2466 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2466 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2466 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2466 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2466 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2466 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2468 true
2468 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2468 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2468 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2468 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2468 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2444 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2444 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2470 true
2470 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2470 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2470 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2470 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2470 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2462 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2462 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2472 true
2472 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2472 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2472 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2472 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2472 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2472 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2472 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2474 true
2474 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2474 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2474 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2474 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2474 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2474 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2474 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2476 true
2476 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2476 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2476 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2476 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2476 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2470 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2470 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2478 true
2478 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2478 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2478 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2478 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2478 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2476 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2476 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2480 true
2480 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2480 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2480 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2480 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2480 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2446 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2446 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2482 true
2482 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2482 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2482 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2482 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2482 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2480 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2480 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2484 true
2484 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2484 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2484 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2484 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2484 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2484 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2484 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2482 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2482 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2478 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2478 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2486 true
2486 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2486 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2486 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2486 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2486 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2490 true
2490 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2490 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2490 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2490 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2490 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2488 true
2488 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2488 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2488 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2488 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2488 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2468 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2468 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2464 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2464 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2492 true
2492 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2492 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2492 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2492 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2492 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2494 true
2494 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2494 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2494 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2494 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2494 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2492 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2492 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2496 true
2496 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2496 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2496 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2496 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2496 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2490 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2490 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2498 true
2498 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2498 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2498 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2498 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2498 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x7f546c85>
2494 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2494 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2500 true
2500 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2500 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2500 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2500 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2500 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2486 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2486 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2502 true
2502 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2502 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2502 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2502 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2502 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>
2496 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2496 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2504 true
2504 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2504 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2504 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2504 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2504 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x27e6ac83>
2488 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2488 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2500 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2500 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2506 true
2506 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2506 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2506 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2506 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2506 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2508 true
2508 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2508 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2508 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2508 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2508 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2508 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2508 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2510 true
2510 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2510 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2510 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2510 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2510 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2510 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2510 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2512 true
2512 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2512 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2512 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2512 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2512 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2512 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2512 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2514 true
2514 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2514 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2514 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2514 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2514 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2514 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2514 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2516 true
2516 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2516 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2516 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2516 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2516 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x248523a0>
2506 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2506 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2332 true
2332 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2332 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2332 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2332 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2332 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>
2332 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=5 conn size=5
2332 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2516 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2516 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2358 true
2358 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2358 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2358 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2358 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
ActiveRecord::ConnectionTimeoutError: 2338 could not obtain a database connection within 5 seconds.  The max pool size is currently 5; consider increasing it.
             checkout at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/connection_adapters/abstract/connection_pool.rb:207
                 loop at org/jruby/RubyKernel.java:1419
             checkout at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/connection_adapters/abstract/connection_pool.rb:189
      mon_synchronize at /Users/bpardee/.rvm/rubies/jruby-1.6.3/lib/ruby/1.8/monitor.rb:191
             checkout at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/connection_adapters/abstract/connection_pool.rb:187
           connection at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/connection_adapters/abstract/connection_pool.rb:94
  retrieve_connection at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/connection_adapters/abstract/connection_pool.rb:327
  retrieve_connection at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/connection_adapters/abstract/connection_specification.rb:97
           connection at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/connection_adapters/abstract/connection_specification.rb:89
                 send at org/jruby/RubyKernel.java:2092
       method_missing at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/relation.rb:370
              scoping at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/relation.rb:125
       method_missing at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/relation.rb:370
           build_arel at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/relation/query_methods.rb:182
                 arel at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/relation/query_methods.rb:149
                 to_a at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/relation.rb:64
           find_first at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/relation/finder_methods.rb:341
                first at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/relation/finder_methods.rb:122
                 send at org/jruby/RubyKernel.java:2092
                first at /Users/bpardee/.rvm/gems/jruby-1.6.3@foobase/gems/activerecord-3.0.9/lib/active_record/base.rb:444
             __file__ at ar_test.rb:26
                 call at org/jruby/RubyProc.java:268
                 call at org/jruby/RubyProc.java:232
2358 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>

358 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x5511e28>2504 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=4 conn size=5
2504 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2502 before signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2502 after signal Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2356 true
2356 done waiting for connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2356 before checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2356 before checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=2 conn size=5
2356 after checkout_and_verify Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5
2356 after checkout connection Tue Aug 16 11:35:13 -0400 2011 size=5 cosize=3 conn size=5 conn=#<ActiveRecord::ConnectionAdapters::MysqlAdapter:0x15dbac11>