Bug 1318825 - "Terminating non responsive method with pid *" error when method is executed in wrong zone
Summary: "Terminating non responsive method with pid *" error when method is executed ...
Keywords:
Status: CLOSED ERRATA
Alias: None
Product: Red Hat CloudForms Management Engine
Classification: Red Hat
Component: Automate
Version: 5.4.0
Hardware: All
OS: All
urgent
urgent
Target Milestone: GA
: 5.6.0
Assignee: Lucy Fu
QA Contact: Alex Newman
URL:
Whiteboard:
Depends On:
Blocks: 1324985 1331439
TreeView+ depends on / blocked
 
Reported: 2016-03-17 23:47 UTC by Josh Carter
Modified: 2019-10-10 11:35 UTC (History)
13 users (show)

Fixed In Version: 5.6.0.5
Doc Type: Bug Fix
Doc Text:
In the previous version of CloudForms Management Engine, service provisions were being executed in the wrong zone, which triggered an error - "Terminating non responsive method with pid 19690". This patch adds an option to turn off calling RBAC in MiqRequestWorkflow and the issue is now resolved.
Clone Of:
: 1324985 1331439 (view as bug list)
Environment:
Last Closed: 2016-06-29 15:43:34 UTC
Category: ---
Cloudforms Team: ---
Target Upstream Version:
Embargoed:


Attachments (Terms of Use)
Trigger for increasing queue message timeout (554 bytes, text/plain)
2016-03-18 19:00 UTC, Gregg Tanzillo
no flags Details
from March 19 rails debug testing (25.72 KB, application/zip)
2016-03-21 18:31 UTC, Thomas Hennessy
no flags Details
from March 19 Rails debug trace (25.69 KB, application/zip)
2016-03-21 18:32 UTC, Thomas Hennessy
no flags Details
from March 19 rails debug testing (26.69 KB, application/zip)
2016-03-21 18:33 UTC, Thomas Hennessy
no flags Details
from March 19 rails debug testing (26.69 KB, application/zip)
2016-03-21 18:34 UTC, Thomas Hennessy
no flags Details
spreadsheet with rails debug query analyses (19.12 MB, application/zip)
2016-03-21 18:37 UTC, Thomas Hennessy
no flags Details
message duration times (1.32 KB, text/plain)
2016-03-21 18:42 UTC, Thomas Hennessy
no flags Details
combined evm and production debug lines for pid 11187 (2.54 MB, application/zip)
2016-03-21 19:14 UTC, Thomas Hennessy
no flags Details
combined evm and prduction debug lines for pid 19870 (1.48 MB, application/zip)
2016-03-21 19:18 UTC, Thomas Hennessy
no flags Details
combined evm and production debug lines for pid 24848 (1.55 MB, application/zip)
2016-03-21 19:19 UTC, Thomas Hennessy
no flags Details
combined evm and production debug log lines for pid 25316 (2.77 MB, application/zip)
2016-03-21 19:20 UTC, Thomas Hennessy
no flags Details


Links
System ID Private Priority Status Summary Last Updated
Red Hat Product Errata RHBA-2016:1348 0 normal SHIPPED_LIVE CFME 5.6.0 bug fixes and enhancement update 2016-06-29 18:50:04 UTC

Description Josh Carter 2016-03-17 23:47:42 UTC
Description of problem:

Service provisions are being executed in the wrong zone and triggering a "Terminating non responsive method with pid 19690" error. 

evm_20160317_1835_kbb0cfz4a01.log:[----] E, [2016-03-17T07:51:51.769172 #19307:db9e94] ERROR -- : Q-task_id([service_template_provision_task_1000000011404]) <AutomationEngine> Terminating non responsive method with pid 20929
evm_20160317_1835_kbb0cfz4a01.log:[----] E, [2016-03-17T11:56:16.084378 #18474:c69e90] ERROR -- : Q-task_id([service_template_provision_task_1000000011452]) <AutomationEngine> Terminating non responsive method with pid 6935
evm_20160317_1835_kbb0cfz4a01.log:[----] E, [2016-03-17T12:16:39.856908 #21189:8b1e88] ERROR -- : Q-task_id([service_template_provision_task_1000000011459]) <AutomationEngine> Terminating non responsive method with pid 11274

Version-Release number of selected component (if applicable): 5.4.3.1


How reproducible:


Steps to Reproduce:
1.
2.
3.

Actual results:


Expected results:


Additional info:

Comment 10 Gregg Tanzillo 2016-03-18 19:00:29 UTC
Created attachment 1137846 [details]
Trigger for increasing queue message timeout

Copy file to appliance and create the trigger with this command:

psql -d vmdb_production < <path to file>

Comment 11 Thomas Hennessy 2016-03-21 18:31:06 UTC
Created attachment 1138733 [details]
from March 19 rails debug testing

Comment 12 Thomas Hennessy 2016-03-21 18:32:02 UTC
Created attachment 1138734 [details]
from March 19 Rails debug trace

Comment 13 Thomas Hennessy 2016-03-21 18:33:42 UTC
Created attachment 1138738 [details]
from March 19 rails debug testing

Comment 14 Thomas Hennessy 2016-03-21 18:34:41 UTC
Created attachment 1138739 [details]
from March 19 rails debug testing

Comment 15 Thomas Hennessy 2016-03-21 18:37:10 UTC
Created attachment 1138756 [details]
spreadsheet with rails debug query analyses

Comment 16 Thomas Hennessy 2016-03-21 18:42:52 UTC
Created attachment 1138758 [details]
message duration times

Comment 17 Thomas Hennessy 2016-03-21 19:14:10 UTC
Created attachment 1138776 [details]
combined evm and production debug lines for pid 11187

Comment 18 Thomas Hennessy 2016-03-21 19:18:18 UTC
Created attachment 1138777 [details]
combined evm and prduction debug lines for pid 19870

Comment 19 Thomas Hennessy 2016-03-21 19:19:23 UTC
Created attachment 1138778 [details]
combined evm and production debug lines for pid 24848

Comment 20 Thomas Hennessy 2016-03-21 19:20:33 UTC
Created attachment 1138779 [details]
combined evm and production debug log lines for pid 25316

Comment 32 CFME Bot 2016-04-27 20:50:42 UTC
New commit detected on ManageIQ/manageiq/master:
https://github.com/ManageIQ/manageiq/commit/880f717434f23fc7d99540da64eb13ea11029188

commit 880f717434f23fc7d99540da64eb13ea11029188
Author:     Lucy Fu <lufu>
AuthorDate: Mon Apr 25 12:18:16 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 13:31:18 2016 -0400

    Use the field value that has been returned.
    
    Use the field value that has been returned to avoid the extra calling to get_field.
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/controllers/miq_request_controller.rb | 3 +--
 1 file changed, 1 insertion(+), 2 deletions(-)

Comment 33 CFME Bot 2016-04-27 20:50:47 UTC
New commit detected on ManageIQ/manageiq/master:
https://github.com/ManageIQ/manageiq/commit/428e6b0ab1691e0dc6585871de33dca6a5d5b6d1

commit 428e6b0ab1691e0dc6585871de33dca6a5d5b6d1
Author:     Lucy Fu <lufu>
AuthorDate: Mon Apr 25 12:23:26 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 13:31:18 2016 -0400

    Add refresh_values option to get_field to turn off the calling to Rbac when it is not necessary.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/models/miq_request_workflow.rb | 16 ++++++++--------
 1 file changed, 8 insertions(+), 8 deletions(-)

Comment 34 CFME Bot 2016-04-27 20:50:53 UTC
New commit detected on ManageIQ/manageiq/master:
https://github.com/ManageIQ/manageiq/commit/bcded598b6781c4b07e41739d21f49a4582a6e9f

commit bcded598b6781c4b07e41739d21f49a4582a6e9f
Author:     Lucy Fu <lufu>
AuthorDate: Thu Apr 21 19:15:04 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 13:31:18 2016 -0400

    MiqReqestWorkflow#validate does not need to call Rbac.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/controllers/application_controller/miq_request_methods.rb | 6 ++----
 app/models/miq_request_workflow.rb                            | 4 ++--
 2 files changed, 4 insertions(+), 6 deletions(-)

Comment 35 CFME Bot 2016-04-27 20:50:58 UTC
New commit detected on ManageIQ/manageiq/master:
https://github.com/ManageIQ/manageiq/commit/0e407024425ce421f431baf11b60d153b28e6c30

commit 0e407024425ce421f431baf11b60d153b28e6c30
Author:     Lucy Fu <lufu>
AuthorDate: Mon Apr 25 15:23:41 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 13:31:18 2016 -0400

    Refactor the call to validate_values so validate is called only once.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/models/miq_host_provision_workflow.rb |  6 +++---
 app/models/miq_provision_virt_workflow.rb |  6 +++---
 app/models/miq_request_workflow.rb        | 14 ++++++--------
 spec/models/miq_request_workflow_spec.rb  | 18 ++++++++++++++++++
 4 files changed, 30 insertions(+), 14 deletions(-)

Comment 36 CFME Bot 2016-04-27 20:51:04 UTC
New commit detected on ManageIQ/manageiq/master:
https://github.com/ManageIQ/manageiq/commit/931c43751eadde6493018f514781f99d41b6d91f

commit 931c43751eadde6493018f514781f99d41b6d91f
Author:     Lucy Fu <lufu>
AuthorDate: Wed Apr 27 11:45:03 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 13:31:18 2016 -0400

    Set placement_auto true from the start by default to reduce the method calls.
    
    Enforce placement_auto true from the start and set to false in ws_environment_fields if needed.
    This way lots of method calls about placement are reduced and some of which calls would even go to Rbac.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/models/miq_provision_virt_workflow.rb | 7 +++----
 1 file changed, 3 insertions(+), 4 deletions(-)

Comment 37 CFME Bot 2016-04-27 20:51:10 UTC
New commit detected on ManageIQ/manageiq/master:
https://github.com/ManageIQ/manageiq/commit/4e42e50c553041b791cd84c2adde5bd37e3c46ec

commit 4e42e50c553041b791cd84c2adde5bd37e3c46ec
Author:     Lucy Fu <lufu>
AuthorDate: Wed Apr 27 12:01:56 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 13:31:19 2016 -0400

    Fix rubocop warnings in UI code.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/controllers/application_controller/miq_request_methods.rb | 4 ++--
 app/controllers/miq_request_controller.rb                     | 2 +-
 2 files changed, 3 insertions(+), 3 deletions(-)

Comment 40 CFME Bot 2016-04-29 14:48:46 UTC
New commit detected on cfme/5.5.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=c5d553c2e0b3ebcbda09a6f090809a4f63d8eec1

commit c5d553c2e0b3ebcbda09a6f090809a4f63d8eec1
Author:     Lucy Fu <lufu>
AuthorDate: Mon Apr 25 12:18:16 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 17:47:52 2016 -0400

    Use the field value that has been returned.
    
    Use the field value that has been returned to avoid the extra calling to get_field.
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/controllers/miq_request_controller.rb | 3 +--
 1 file changed, 1 insertion(+), 2 deletions(-)

Comment 41 CFME Bot 2016-04-29 14:48:51 UTC
New commit detected on cfme/5.5.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=5399f5dc77e73d2d8a92c2b6df27ca01a8fa144d

commit 5399f5dc77e73d2d8a92c2b6df27ca01a8fa144d
Author:     Lucy Fu <lufu>
AuthorDate: Mon Apr 25 12:23:26 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 17:48:04 2016 -0400

    Add refresh_values option to get_field to turn off the calling to Rbac when it is not necessary.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/models/miq_request_workflow.rb | 16 ++++++++--------
 1 file changed, 8 insertions(+), 8 deletions(-)

Comment 42 CFME Bot 2016-04-29 14:48:57 UTC
New commit detected on cfme/5.5.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=8982c13f06d49816e16eaf9b8165e2994595dd7d

commit 8982c13f06d49816e16eaf9b8165e2994595dd7d
Author:     Lucy Fu <lufu>
AuthorDate: Thu Apr 21 19:15:04 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 17:48:14 2016 -0400

    MiqReqestWorkflow#validate does not need to call Rbac.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/controllers/application_controller/miq_request_methods.rb | 6 ++----
 app/models/miq_request_workflow.rb                            | 4 ++--
 2 files changed, 4 insertions(+), 6 deletions(-)

Comment 43 CFME Bot 2016-04-29 14:49:02 UTC
New commit detected on cfme/5.5.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=9cda847a60cbe0f27d5365645122455a32b2f598

commit 9cda847a60cbe0f27d5365645122455a32b2f598
Author:     Lucy Fu <lufu>
AuthorDate: Wed Apr 27 12:01:56 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 18:03:47 2016 -0400

    Fix rubocop warnings in UI code.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/controllers/application_controller/miq_request_methods.rb | 4 ++--
 app/controllers/miq_request_controller.rb                     | 2 +-
 2 files changed, 3 insertions(+), 3 deletions(-)

Comment 44 CFME Bot 2016-04-29 14:49:08 UTC
New commit detected on cfme/5.5.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=d39596770faa5d11b5ccda2ddb0a77734c5d9ac6

commit d39596770faa5d11b5ccda2ddb0a77734c5d9ac6
Author:     Lucy Fu <lufu>
AuthorDate: Mon Apr 25 15:23:41 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Wed Apr 27 17:51:07 2016 -0400

    Refactor the call to validate_values so validate is called only once.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/models/miq_host_provision_workflow.rb |  6 +++---
 app/models/miq_provision_virt_workflow.rb |  6 +++---
 app/models/miq_request_workflow.rb        | 14 ++++++--------
 spec/models/miq_request_workflow_spec.rb  | 18 ++++++++++++++++++
 4 files changed, 30 insertions(+), 14 deletions(-)

Comment 45 CFME Bot 2016-04-29 14:49:14 UTC
New commit detected on cfme/5.5.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=41201ca9165751496e6e7113781bfee7ad7d27ab

commit 41201ca9165751496e6e7113781bfee7ad7d27ab
Author:     Lucy Fu <lufu>
AuthorDate: Thu Apr 28 09:16:33 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Thu Apr 28 09:16:33 2016 -0400

    Set placement_auto true from the start by default to reduce the method calls.
    
    This way lots of method calls about placement are reduced and some of which calls would even go to Rbac.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 app/models/miq_provision_virt_workflow.rb | 1 +
 1 file changed, 1 insertion(+)

Comment 46 CFME Bot 2016-04-29 21:02:43 UTC
New commit detected on cfme/5.4.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=9e76d35acd8fce720fd71727915804c51fcb2db5

commit 9e76d35acd8fce720fd71727915804c51fcb2db5
Author:     Lucy Fu <lufu>
AuthorDate: Thu Apr 28 11:23:03 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Fri Apr 29 15:32:42 2016 -0400

    Use the field value that has been returned.
    
    Use the field value that has been returned to avoid the extra calling to get_field.
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 vmdb/app/controllers/miq_request_controller.rb | 5 ++---
 1 file changed, 2 insertions(+), 3 deletions(-)

Comment 47 CFME Bot 2016-04-29 21:02:48 UTC
New commit detected on cfme/5.4.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=a83f9da6f65f8628209de470a8cbb66136d553cf

commit a83f9da6f65f8628209de470a8cbb66136d553cf
Author:     Lucy Fu <lufu>
AuthorDate: Thu Apr 28 11:29:08 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Fri Apr 29 15:32:42 2016 -0400

    Add refresh_values option to get_field to turn off the calling to Rbac when it is not necessary.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 vmdb/app/models/miq_request_workflow.rb | 16 ++++++++--------
 1 file changed, 8 insertions(+), 8 deletions(-)

Comment 48 CFME Bot 2016-04-29 21:02:53 UTC
New commit detected on cfme/5.4.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=f0e7e3d3ea33766f95277beb30c09aff27000150

commit f0e7e3d3ea33766f95277beb30c09aff27000150
Author:     Lucy Fu <lufu>
AuthorDate: Thu Apr 28 11:34:04 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Fri Apr 29 15:32:42 2016 -0400

    MiqReqestWorkflow#validate does not need to call Rbac.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 .../app/controllers/application_controller/miq_request_methods.rb | 8 +++-----
 vmdb/app/models/miq_request_workflow.rb                           | 4 ++--
 2 files changed, 5 insertions(+), 7 deletions(-)

Comment 49 CFME Bot 2016-04-29 21:02:58 UTC
New commit detected on cfme/5.4.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=04f67d0e7b1202e3506c7c8604b6d4073302e208

commit 04f67d0e7b1202e3506c7c8604b6d4073302e208
Author:     Lucy Fu <lufu>
AuthorDate: Thu Apr 28 11:44:02 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Fri Apr 29 15:32:43 2016 -0400

    Refactor the call to validate_values so validate is called only once.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 vmdb/app/models/miq_host_provision_workflow.rb |  6 +++---
 vmdb/app/models/miq_provision_virt_workflow.rb |  6 +++---
 vmdb/app/models/miq_request_workflow.rb        | 16 +++++++---------
 vmdb/spec/models/miq_request_workflow_spec.rb  | 18 ++++++++++++++++++
 4 files changed, 31 insertions(+), 15 deletions(-)

Comment 50 CFME Bot 2016-04-29 21:03:03 UTC
New commit detected on cfme/5.4.z:
https://code.engineering.redhat.com/gerrit/gitweb?p=cfme.git;a=commitdiff;h=ad1dde5eaece7c3eeb7deb41388ad075cd9c1429

commit ad1dde5eaece7c3eeb7deb41388ad075cd9c1429
Author:     Lucy Fu <lufu>
AuthorDate: Thu Apr 28 11:47:18 2016 -0400
Commit:     Lucy Fu <lufu>
CommitDate: Fri Apr 29 15:32:43 2016 -0400

    Set placement_auto true from the start by default to reduce the method calls.
    
    This way lots of method calls about placement are reduced and some of which calls would even go to Rbac.
    
    https://bugzilla.redhat.com/show_bug.cgi?id=1318825

 vmdb/app/models/miq_provision_virt_workflow.rb | 1 +
 1 file changed, 1 insertion(+)

Comment 55 errata-xmlrpc 2016-06-29 15:43:34 UTC
Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory, and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

https://access.redhat.com/errata/RHBA-2016:1348


Note You need to log in before you can comment on or make changes to this bug.