undefined method [] and Object doesn't support #inspect

[ringe]

I have a TimeCard model with the following method

1.9.2p290 :002 > TimeCard.import_heavy_job_time_cards 200
  SQL (0.5ms)  USE [HeavyJob]
  Timedetl Load (36033.3ms)  EXEC sp_executesql N'SELECT TOP (200) timedetl.total_reg, timedetl.total_ot, timedetl.total_oth, timedetl.foreman, timedetl.card_num, timedetl.date, timedetl.empeqp_code, timedetl.jobcode FROM [TIMEDETL] INNER JOIN ( SELECT MAX(card_num) as max_num, foreman, date, jobcode FROM timedetl GROUP BY foreman, date, jobcode ) mxCards
 ON timedetl.card_num = mxCards.max_num AND timedetl.foreman = mxCards.foreman AND timedetl.date = mxCards.date AND timedetl.jobcode = mxCards.jobcode WHERE (timedetl.empeqp_code BETWEEN ''110128'' AND ''993017'') AND (timedetl.jobcode NOT IN (N''TRAINING'',N''JOBMAST'',N''TRAFFIC'',N''HRSAFETY'',N''ESTIMATING''))'
NoMethodError: undefined method `[]' for nil:NilClass

The import_heavy_job_time_cards method uses my Timedetl model's get_cards method and tries to do an .each on it.

1.9.2p290 :004 > s = Timedetl.get_cards 2
  Timedetl Load (1766.2ms)  EXEC sp_executesql N'SELECT TOP (2) timedetl.total_reg, timedetl.total_ot, timedetl.total_oth, timedetl.foreman, timedetl.card_num, timedetl.date, timedetl.empeqp_code, timedetl.jobcode FROM [TIMEDETL] INNER JOIN ( SELECT MAX(card_num) as max_num, foreman, date, jobcode FROM timedetl GROUP BY foreman, date, jobcode ) mxCards
 ON timedetl.card_num = mxCards.max_num AND timedetl.foreman = mxCards.foreman AND timedetl.date = mxCards.date AND timedetl.jobcode = mxCards.jobcode WHERE (timedetl.empeqp_code BETWEEN ''110128'' AND ''993017'') AND (timedetl.jobcode NOT IN (N''TRAINING'',N''JOBMAST'',N''TRAFFIC'',N''HRSAFETY'',N''ESTIMATING''))'
(Object doesn't support #inspect)
 =>  
1.9.2p290 :005 > s
 => [#<Timedetl >, #<Timedetl >]

The latter model looks like this:

# Time cards from HeavyJob queries
class Timedetl < ActiveRecord::Base
  self.table_name = 'TIMEDETL'

  # Get TimeCards from the TIMEDETL table
  def self.get_cards(limit)
    return nil unless TulanCheck.do

    jobs = ["TRAINING"] # Excluded job codes

    s = Timedetl.select("timedetl.total_reg, timedetl.total_ot, timedetl.total_oth, timedetl.foreman, timedetl.card_num, timedetl.date, timedetl.empeqp_code, timedetl.jobcode"
      ).joins("INNER JOIN ( SELECT MAX(card_num) as max_num, foreman, date, jobcode FROM timedetl GROUP BY foreman, date, jobcode ) mxCards
      ON timedetl.card_num = mxCards.max_num AND timedetl.foreman = mxCards.foreman AND timedetl.date = mxCards.date AND timedetl.jobcode = mxCards.jobcode"
      ).where("timedetl.empeqp_code BETWEEN '32238' AND '234017'"
      ).where("timedetl.jobcode NOT IN (?)", jobs
             ).limit(limit)
    s
  end
end
Timedetl.establish_connection(
  adapter: "sqlserver",
  mode: "dblib",
  host: "server05",
  database: "HeavyJob"
  timeout: 0)

Why would Timedetl.get_cards return an empty response but still set the s parameter?

Could this be related to http://stackoverflow.com/questions/7690697/object-doesnt-support-inspect

[metaskills]

Good lord, that's almost too much info. Without reading all that, I have a hunch that your issue is with the explain support in development, tho a full stack trace would show for sure. You only posted the last line. But explain/showplan issues typically yield those type of NoMethodErrors. See our README and rails guides link for more info.

[ringe]

Sounds reasonable, but I tried to disable explain using the tricks in your README and the Ruby on Rails Guides. No luck.

[metaskills]

Then please post a full stack trace.

[ringe]

?? How do I get a stack trace when nothing goes wrong? Or rather, that the behaviour is like I said. Nothing returned, even if the variable get set.

[metaskills]

Check out issue #183. Your first few lines above look exactly like that. If you are seeing "NoMethodError" then you should be seeing a stack trace in your console and log just like #183 below the lines you posted.

[metaskills]

BTW, please make sure that if you followed the rails guides to disable explain support that you did in fact restart your server process or started a new console. The fact that the SQL your running takes way longer than the default threshold and that your error is exactly like others have for explain support, your issue really looks like the same. In lieu of a full stack trace, I can not say otherwise. Lemme know.

[ringe]

You are right about the stack trace, sorry:

1.9.2p290 :002 > TimeCard.import_heavy_job_time_cards 1
  Timedetl Load (369.8ms)  EXEC sp_executesql N'SELECT TOP (1) timedetl.total_reg, timedetl.total_ot, timedetl.total_oth, timedetl.foreman, timedetl.card_num, timedetl.date, timedetl.empeqp_code, timedetl.jobcode FROM [TIMEDETL] INNER JOIN ( SELECT MAX(card_num) as max_num, foreman, date, jobcode FROM timedetl GROUP BY foreman, date, jobcode ) mxCards
 ON timedetl.card_num = mxCards.max_num AND timedetl.foreman = mxCards.foreman AND timedetl.date = mxCards.date AND timedetl.jobcode = mxCards.jobcode WHERE (timedetl.empeqp_code BETWEEN ''110128'' AND ''993017'') AND (timedetl.jobcode <> ''TRAINING'') AND (timedetl.jobcode <> ''JOBMAST'') AND (timedetl.jobcode <> ''TRAFFIC'') AND (timedetl.jobcode <> ''HRSAFETY'') AND (timedetl.jobcode <> ''ESTIMATING'')'
Could not import: SPRJO 09124 2000-06-27 00:00:00 UTC => nil 
1.9.2p290 :003 > TimeCard.import_heavy_job_time_cards 100
  Timedetl Load (663.6ms)  EXEC sp_executesql N'SELECT TOP (100) timedetl.total_reg, timedetl.total_ot, timedetl.total_oth, timedetl.foreman, timedetl.card_num, timedetl.date, timedetl.empeqp_code, timedetl.jobcode FROM [TIMEDETL] INNER JOIN ( SELECT MAX(card_num) as max_num, foreman, date, jobcode FROM timedetl GROUP BY foreman, date, jobcode ) mxCards
 ON timedetl.card_num = mxCards.max_num AND timedetl.foreman = mxCards.foreman AND timedetl.date = mxCards.date AND timedetl.jobcode = mxCards.jobcode WHERE (timedetl.empeqp_code BETWEEN ''110128'' AND ''993017'') AND (timedetl.jobcode <> ''TRAINING'') AND (timedetl.jobcode <> ''JOBMAST'') AND (timedetl.jobcode <> ''TRAFFIC'') AND (timedetl.jobcode <> ''HRSAFETY'') AND (timedetl.jobcode <> ''ESTIMATING'')'
NoMethodError: undefined method `[]' for nil:NilClass
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/activerecord-sqlserver-adapter-3.2.4/lib/active_record/connection_adapters/sqlserver/core_ext/explain.rb:26:in `unprepare_sqlserver_statement'
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/activerecord-sqlserver-adapter-3.2.4/lib/active_record/connection_adapters/sqlserver/core_ext/explain.rb:11:in `block in exec_explain'
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/activerecord-sqlserver-adapter-3.2.4/lib/active_record/connection_adapters/sqlserver/core_ext/explain.rb:11:in `map'
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/activerecord-sqlserver-adapter-3.2.4/lib/active_record/connection_adapters/sqlserver/core_ext/explain.rb:11:in `exec_explain'
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/activerecord-3.2.3/lib/active_record/explain.rb:34:in `logging_query_plan'
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/activerecord-3.2.3/lib/active_record/relation.rb:159:in `to_a'
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/activerecord-3.2.3/lib/active_record/relation/delegation.rb:36:in `each_slice'
    from /home/silver/iron/app/models/time_card.rb:23:in `import_heavy_job_time_cards'
    from (irb):3
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/railties-3.2.3/lib/rails/commands/console.rb:47:in `start'
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/railties-3.2.3/lib/rails/commands/console.rb:8:in `start'
    from /usr/local/rvm/gems/ruby-1.9.2-p290@iron/gems/railties-3.2.3/lib/rails/commands.rb:41:in `<top (required)>'
    from script/rails:6:in `require'
    from script/rails:6:in `<main>'

[ringe]

When I do this, it works:

ActiveRecord::Base.logger = nil

[metaskills]

Well the stack trace just proves what I said, this is a bug in the explain feature. Please disable it.

[ringe]

Thank you, but I already did in config/application.rb

# Don't explain queries
config.active_record.auto_explain_threshold_in_seconds = nil

And I tried the 21.1.1 Disabling Automatic EXPLAIN from [here](http://guides.rubyonrails.org/active_record_querying.html#automatic-explain]

Did I miss something?

[metaskills]

Of course, because if there is no logger, there is nothing for explain to log too. But you really should disable the explain feature via the explicit method.

[ringe]

I'm sorry, my patient friend - but I don't get it. How do I disable the explain method that way?

[ringe]

The explicit method, if that's what you refer to in the README, please see my comment above. I already did. Is it supposed to be set in a different file?

[metaskills]

That's really a general rails-talk question at this point. My advice is if the config is not working or you, start to ask yourself why not and go thru some paces. Like did you restart your app/process so it would pick up on that change? Do you have a config in another environment say development.rb that is taking precedence over base app configs? Did you try putting the config in development.rb vs application since the rails docs say the default in any other env besides development is nil?