If I turn on debug logging, I get the following in an iex session:
`iex> from(f in SlowGoing.Platform.FloatsTest, limit: 10000) |> SlowGoing.Repo.all()
[error] Tds.Protocol (pid<0.450.0>) disconnected: ** (DBConnection.ConnectionError) client pid<0.513.0> timed out because it queued and checked out the connection for longer than 5000ms
pid<0.513.0> was at location:
(tds 2.3.4) lib/tds/tokens.ex:547: Tds.Tokens.decode_row_columns/3
(tds 2.3.4) lib/tds/tokens.ex:199: Tds.Tokens.decode_row/2
(tds 2.3.4) lib/tds/tokens.ex:65: Tds.Tokens.decode_tokens/2
(tds 2.3.4) lib/tds/tokens.ex:72: Tds.Tokens.decode_tokens/2
(tds 2.3.4) lib/tds/messages.ex:153: Tds.Messages.parse/3
(tds 2.3.4) lib/tds/protocol.ex:483: Tds.Protocol.decode/2
(tds 2.3.4) lib/tds/protocol.ex:544: Tds.Protocol.send_query/2
(tds 2.3.4) lib/tds/protocol.ex:175: Tds.Protocol.handle_execute/4
(db_connection 2.6.0) lib/db_connection/holder.ex:354: DBConnection.Holder.holder_apply/4
(db_connection 2.6.0) lib/db_connection.ex:1512: DBConnection.run_execute/5
(db_connection 2.6.0) lib/db_connection.ex:1607: DBConnection.run/6
(db_connection 2.6.0) lib/db_connection.ex:743: DBConnection.parsed_prepare_execute/5
(db_connection 2.6.0) lib/db_connection.ex:735: DBConnection.prepare_execute/4
(ecto_sql 3.11.1) lib/ecto/adapters/tds/connection.ex:52: Ecto.Adapters.Tds.Connection.execute/4
(ecto_sql 3.11.1) lib/ecto/adapters/sql.ex:996: Ecto.Adapters.SQL.execute!/5
(ecto_sql 3.11.1) lib/ecto/adapters/sql.ex:952: Ecto.Adapters.SQL.execute/6
(ecto 3.11.1) lib/ecto/repo/queryable.ex:232: Ecto.Repo.Queryable.execute/4
(ecto 3.11.1) lib/ecto/repo/queryable.ex:19: Ecto.Repo.Queryable.all/3
(elixir 1.14.3) src/elixir.erl:309: anonymous fn/4 in :elixir.eval_external_handler/1
(stdlib 4.2) erl_eval.erl:748: :erl_eval.do_apply/7`
[debug] QUERY OK source="floats_test" db=46034.3ms decode=0.3ms queue=1.1ms idle=1936.2ms SELECT TOP(10000) f0.[id], f0.[float_eight], f0.[float_five], f0.[float_four], f0.[float_nine], f0.[float_one], f0.[float_seven], f0.[float_six], f0.[float_three], f0.[float_two], f0.[int_one], f0.[int_three], f0.[int_two], f0.[inserted_at], f0.[updated_at] FROM [floats_test] AS f0 [] ↳ anonymous fn/4 in :elixir.eval_external_handler/1, at: src/elixir.erl:309
Which seems to indicate that it spent 46 seconds in db, 2 seconds in idle and almost no time in decode and queue
If I’m reading this right Tds.Tokens.decode_row_columns eventually calls Tds.Types.decode_data – and this is where I get completely lost.
