Attachment 1472149 Details for Bug 1610924 – evm.log stacktrace

evm.log stacktrace

file_1610924.txt (text/plain), 6.56 KB, created by Matouš Mojžíš on 2018-08-01 16:05:38 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Matouš Mojžíš

Created: 2018-08-01 16:05:38 UTC

Size: 6.56 KB

patch

obsolete

>[----] I, [2018-08-01T12:00:17.978044 #50462:33ef8c]  INFO -- : MIQ(MiqUiWorker::Runner#set_connection_pool_size) MIQ(MiqUiWorker::Runner) Changed connection_pool size from 5 to 8
>[----] I, [2018-08-01T12:00:17.978171 #50462:33ef8c]  INFO -- : MIQ(MiqUiWorker::Runner#start) URI: http://127.0.0.1:3000
>[----] E, [2018-08-01T12:00:18.262920 #1939:33ef8c] ERROR -- : MIQ(MiqServer#monitor) can't modify frozen Hash
>[----] E, [2018-08-01T12:00:18.263221 #1939:33ef8c] ERROR -- : [RuntimeError]: can't modify frozen Hash  Method:[block in method_missing]
>[----] E, [2018-08-01T12:00:18.263350 #1939:33ef8c] ERROR -- : /opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/attribute_set.rb:53:in `write_from_user'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/attribute_methods/write.rb:50:in `write_attribute_with_type_cast'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/attribute_methods/write.rb:32:in `write_attribute'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/attribute_methods/write.rb:20:in `__temp__c6163747f5865616274726561647='
>/opt/rh/cfme-gemset/gems/activemodel-5.0.7/lib/active_model/attribute_assignment.rb:46:in `public_send'
>/opt/rh/cfme-gemset/gems/activemodel-5.0.7/lib/active_model/attribute_assignment.rb:46:in `_assign_attribute'
>/opt/rh/cfme-gemset/gems/activemodel-5.0.7/lib/active_model/attribute_assignment.rb:40:in `block in _assign_attributes'
>/opt/rh/cfme-gemset/gems/activemodel-5.0.7/lib/active_model/attribute_assignment.rb:39:in `each'
>/opt/rh/cfme-gemset/gems/activemodel-5.0.7/lib/active_model/attribute_assignment.rb:39:in `_assign_attributes'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/attribute_assignment.rb:26:in `_assign_attributes'
>/opt/rh/cfme-gemset/gems/activemodel-5.0.7/lib/active_model/attribute_assignment.rb:33:in `assign_attributes'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/persistence.rb:274:in `block in update'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/transactions.rb:395:in `block in with_transaction_returning_status'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/connection_adapters/abstract/database_statements.rb:232:in `block in transaction'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/connection_adapters/abstract/transaction.rb:189:in `within_new_transaction'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/connection_adapters/abstract/database_statements.rb:232:in `transaction'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/transactions.rb:211:in `transaction'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/transactions.rb:392:in `with_transaction_returning_status'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/persistence.rb:273:in `update'
>/var/www/miq/vmdb/app/models/miq_server/worker_management/heartbeat.rb:75:in `validate_heartbeat'
>/var/www/miq/vmdb/app/models/miq_server/worker_management/monitor/validation.rb:11:in `validate_worker'
>/var/www/miq/vmdb/app/models/miq_server/worker_management/heartbeat.rb:61:in `block in post_message_for_workers'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/relation/delegation.rb:38:in `each'
>/opt/rh/cfme-gemset/gems/activerecord-5.0.7/lib/active_record/relation/delegation.rb:38:in `each'
>/var/www/miq/vmdb/app/models/miq_server/worker_management/heartbeat.rb:51:in `post_message_for_workers'
>/var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:32:in `block in monitor_workers'
>/var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:28:in `each'
>/var/www/miq/vmdb/app/models/miq_server/worker_management/monitor.rb:28:in `monitor_workers'
>/var/www/miq/vmdb/app/models/miq_server.rb:339:in `block in monitor'
>/opt/rh/cfme-gemset/bundler/gems/cfme-gems-pending-e92faf72a1d1/lib/gems/pending/util/extensions/miq-benchmark.rb:11:in `realtime_store'
>/opt/rh/cfme-gemset/bundler/gems/cfme-gems-pending-e92faf72a1d1/lib/gems/pending/util/extensions/miq-benchmark.rb:28:in `realtime_block'
>/var/www/miq/vmdb/app/models/miq_server.rb:339:in `monitor'
>/var/www/miq/vmdb/app/models/miq_server.rb:378:in `block (2 levels) in monitor_loop'
>/opt/rh/cfme-gemset/bundler/gems/cfme-gems-pending-e92faf72a1d1/lib/gems/pending/util/extensions/miq-benchmark.rb:11:in `realtime_store'
>/opt/rh/cfme-gemset/bundler/gems/cfme-gems-pending-e92faf72a1d1/lib/gems/pending/util/extensions/miq-benchmark.rb:35:in `realtime_block'
>/var/www/miq/vmdb/app/models/miq_server.rb:378:in `block in monitor_loop'
>/var/www/miq/vmdb/app/models/miq_server.rb:377:in `loop'
>/var/www/miq/vmdb/app/models/miq_server.rb:377:in `monitor_loop'
>/var/www/miq/vmdb/app/models/miq_server.rb:241:in `start'
>/var/www/miq/vmdb/lib/workers/evm_server.rb:27:in `start'
>/var/www/miq/vmdb/lib/workers/evm_server.rb:48:in `start'
>/var/www/miq/vmdb/lib/workers/bin/evm_server.rb:4:in `<main>'
>[----] I, [2018-08-01T12:00:18.263432 #1939:33ef8c]  INFO -- : MIQ(MiqServer#monitor) Reconnecting to database after error...
>[----] I, [2018-08-01T12:00:18.274848 #1939:33ef8c]  INFO -- : MIQ(MiqServer#monitor) Reconnecting to database after error...Successful
>[----] I, [2018-08-01T12:00:18.275118 #1939:33ef8c]  INFO -- : MIQ(MiqServer#monitor_loop) Server Monitoring Complete - Timings: {:server_dequeue=>0.004947185516357422, :worker_monitor=>1.0889861583709717, :total_time=>1.106337547302246}
>[----] I, [2018-08-01T12:00:18.353405 #50462:33ef8c]  INFO -- : MiqUiWorker::Runner started. ID [45], PID [50462], GUID [067a1572-2bd6-4239-ae95-3cd36d55b1cc], Zone [default], Role [automate,database_operations,database_owner,ems_inventory,ems_operations,event,reporting,scheduler,smartstate,user_interface,web_services,websocket]
>[----] I, [2018-08-01T12:00:18.361323 #50462:7067a0]  INFO -- : MIQ(MiqUiWorker::Runner#worker_monitor_drb) MIQ(MiqUiWorker::Runner) Initializing DRb Connection to MiqServer with ID=[1], NAME=[EVM], PID=[1939], GUID=[6bb13e70-82a2-46e2-8143-b03651cae645] DRb URI=[drbunix:///tmp/worker_monitor20180801-1939-7cqo36]
>[----] I, [2018-08-01T12:00:18.682192 #50462:33ef8c]  INFO -- : MIQ(MiqUiWorker::Runner#start_rails_server) With options: {:Host=>"127.0.0.1", :environment=>"production", :server=>"puma", :Port=>3000, :pid=>"/var/www/miq/vmdb/tmp/pids/rails_server.3000.pid"}
>=> Booting Puma
>=> Rails 5.0.7 application starting in production on http://127.0.0.1:3000
>=> Run `rails server -h` for more startup options
>Puma starting in single mode...
>* Version 3.7.1 (ruby 2.4.4-p296), codename: Snowy Sagebrush
>* Min threads: 5, max threads: 5
>* Environment: production
>* Listening on tcp://127.0.0.1:3000
>Use Ctrl-C to stop

Actions: View

Attachments on bug 1610924: 1472149