Print Respective SQL query for activerecord

[sivag-csod]

Below is my trace

rbtrace -p 115 --methods "ActiveRecord::ConnectionAdapters::Mysql2Adapter#execute(sql)" --slow 150
*** attached to process 115
      Reline::ANSI#inner_getc <0.388345>
    Reline::ANSI#getc <0.388502>
  Kernel#loop <0.399825>
Reline::Core#read_io <0.399871>

                                                                    Mysql2::Client#_query <0.159245>
                                                                  Thread.handle_interrupt <0.159298>
                                                                Mysql2::Client#query <0.159308>
                                                              ActiveSupport::Concurrency::ShareLock#yield_shares <0.159396>
                                                            ActiveSupport::Dependencies::Interlock#permit_concurrent_loads <0.159406>
                                                          Thread.handle_interrupt <0.159505>
                                                        Thread.handle_interrupt <0.159664>
                                                      ActiveSupport::Concurrency::LoadInterlockAwareMonitor#synchronize <0.159673>
                                                    ActiveSupport::Notifications::Instrumenter#instrument <0.161257>
                                                  ActiveRecord::ConnectionAdapters::AbstractAdapter#log <0.161285>
                                                ActiveRecord::ConnectionAdapters::AbstractMysqlAdapter#raw_execute <0.161329>
                                              ActiveRecord::ConnectionAdapters::MySQL::DatabaseStatements#raw_execute <0.161343>
                                            ActiveRecord::ConnectionAdapters::MySQL::DatabaseStatements#execute <0.161403>
                                          ActiveRecord::ConnectionAdapters::AbstractMysqlAdapter#execute_and_free <0.161554>
                                        ActiveRecord::ConnectionAdapters::MySQL::DatabaseStatements#exec_query <0.161573>
                                      ActiveRecord::ConnectionAdapters::DatabaseStatements#select <0.161583>
                                    ActiveRecord::ConnectionAdapters::DatabaseStatements#select_all <0.162485>
                                  ActiveRecord::ConnectionAdapters::QueryCache#select_all <0.162550>
                                ActiveRecord::ConnectionAdapters::MySQL::DatabaseStatements#select_all <0.162676>
                              ActiveRecord::Querying#_query_by_sql <0.162848>
                            ActiveRecord::Relation#skip_query_cache_if_necessary <0.163877>
                          ActiveRecord::Relation#exec_main_query <0.163918>
                        ActiveRecord::Relation#skip_query_cache_if_necessary <0.164211>
                      ActiveRecord::Relation#exec_queries <0.164219>
                    ActiveRecord::Relation#load <0.164231>
                  ActiveRecord::Relation#records <0.164269>
                #<Module:0x00007fffde6e2838>#records <0.164385>
              ActiveRecord::Relation#to_ary <0.164406>
            ActiveRecord::FinderMethods#find_nth_with_limit <0.165418>
          ActiveRecord::FinderMethods#find_nth <0.165457>
        ActiveRecord::FinderMethods#first <0.165497>
      Kernel#eval <0.167632>
    IRB::WorkSpace#evaluate <0.167747>
  IRB::Context#evaluate <0.167835>
IRB::Irb#signal_status <0.168580>

How can I display respective sql query which takes time ?

[benoittgt]

I think this is more something you do with ActiveSupport::Notification itselft. See https://kinsta.com/blog/logging-slow-queries/

[sivag-csod]

@benoittgt Yes I am aware of that. But my use, we face sometimes sudden slowness on production for random intervals. On our APM also we are not getting that information. To debug via rails console if we display respective SQL queries execution time it would be helpful of faster debugging.