Skip to content

Extend CTE search optimisation to projects

Sean McGivern requested to merge extend-cte-optimisations-to-projects into master

What does this MR do?

Background

When we use the search param on an IssuableFinder, we can run into issues. We have trigram indexes to support these searches. On GitLab.com, we often see Postgres's optimiser prioritise the (global) trigram indexes over the index on project_id. For group and project searches, we know that it will be quicker to filter by project_id first, as it returns fewer rows in most cases.

For group issues search, we ran into this issue previously, and went through the following iterations:

  1. Use a CTE on the project IDs as an optimisation fence. This prevents the planner from disregarding the index on project_id. Unfortunately it breaks some types of sorting, like priority and popularity, as they sort on a joined table.
  2. Use a subquery for listing issues, and a CTE for counts. The subquery
    • in the case of group lists - didn't help as much as the CTE, but was faster than not including it. We can safely use a CTE for counts as they don't have sorting.

Now, however, we're seeing the same issue in a project context. The subquery doesn't help at all there (it would only return one row, after all). In an attempt to keep total code complexity under control, this commit removes the subquery optimisation and applies the CTE optimisation only for sorts we know that are safe.

This means that for more complicated sorts (like priority and popularity), the search will continue to be very slow. If this is a high-priority issue, we can consider introducing further optimisations, but this finder is already very complicated and additional complexity has a cost.

The group CTE optimisation is controlled by the same feature flag as before, attempt_group_search_optimizations, which is enabled by default. The new project CTE optimisation is controlled by a new feature flag, attempt_project_search_optimizations, which is disabled by default.

Results

My test case was https://gitlab.com/gitlab-org/gitlab-ce/issues/?search=slow+finder, as an anonymous user.

Before:

I, [2019-04-02T15:21:28.950060 #10367]  INFO -- : Completed 200 OK in 29108ms (Views: 7900.6ms | ActiveRecord: 20350.1ms | Elasticsearch: 0.0ms)
I, [2019-04-02T15:21:28.962082 #10367]  INFO -- : Issue total (1): 10271.2ms
I, [2019-04-02T15:21:28.962332 #10367]  INFO -- : Project total (6): 8.9ms
I, [2019-04-02T15:21:28.962417 #10367]  INFO -- : Route total (4): 3.9ms
I, [2019-04-02T15:21:28.962481 #10367]  INFO -- : Group total (3): 3.8ms
I, [2019-04-02T15:21:28.962541 #10367]  INFO -- : Note total (1): 2.7ms
I, [2019-04-02T15:21:28.962620 #10367]  INFO -- : Namespace total (2): 2.1ms
I, [2019-04-02T15:21:28.962687 #10367]  INFO -- : Upload total (2): 2.0ms
I, [2019-04-02T15:21:28.962755 #10367]  INFO -- : AwardEmoji total (1): 1.2ms
I, [2019-04-02T15:21:28.962822 #10367]  INFO -- : Label total (1): 1.1ms
I, [2019-04-02T15:21:28.962892 #10367]  INFO -- : LabelLink total (1): 1.1ms
I, [2019-04-02T15:21:28.962956 #10367]  INFO -- : User total (1): 1.1ms
I, [2019-04-02T15:21:28.963018 #10367]  INFO -- : IssueAssignee total (1): 1.0ms
I, [2019-04-02T15:21:28.963077 #10367]  INFO -- : Milestone total (1): 0.9ms
I, [2019-04-02T15:21:28.963145 #10367]  INFO -- : ProjectFeature total (1): 0.8ms
I, [2019-04-02T15:21:28.963206 #10367]  INFO -- : License total (1): 0.8ms
Full query log
D, [2019-04-02T15:20:59.721601 #10367] DEBUG -- :    (0.7ms)  SELECT EXTRACT(EPOCH FROM (now() - pg_last_xact_replay_timestamp()))::float as lag
D, [2019-04-02T15:20:59.725156 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:50:in `find_by_full_path'
D, [2019-04-02T15:20:59.725476 #10367] DEBUG -- :   ↳ lib/constraints/group_url_constrainer.rb:10:in `matches?'
D, [2019-04-02T15:20:59.725578 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.725693 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.725791 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.725893 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
Host 10.220.16.102 came back online
D, [2019-04-02T15:20:59.731022 #10367] DEBUG -- :   Group Load (1.4ms)  SELECT  "namespaces".* FROM "namespaces" INNER JOIN "routes" ON "routes"."source_id" = "namespaces"."id" AND "routes"."source_type" = 'Namespace' WHERE "namespaces"."type" IN ('Group') AND ((LOWER(routes.path) = LOWER('gitlab-org/gitlab-ce/issues'))) ORDER BY (CASE WHEN  routes.path = 'gitlab-org/gitlab-ce/issues' THEN 0 ELSE 1 END) LIMIT 1
D, [2019-04-02T15:20:59.734212 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:50:in `find_by_full_path'
D, [2019-04-02T15:20:59.734481 #10367] DEBUG -- :   ↳ lib/constraints/group_url_constrainer.rb:10:in `matches?'
D, [2019-04-02T15:20:59.734582 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.734688 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.734823 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.734922 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:20:59.744658 #10367] DEBUG -- :   Group Load (1.2ms)  SELECT  "namespaces".* FROM "namespaces" INNER JOIN "redirect_routes" ON "redirect_routes"."source_id" = "namespaces"."id" AND "redirect_routes"."source_type" = 'Namespace' WHERE "namespaces"."type" IN ('Group') AND (LOWER(redirect_routes.path) = LOWER('gitlab-org/gitlab-ce/issues')) LIMIT 1
D, [2019-04-02T15:20:59.747598 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:55:in `find_by_full_path'
D, [2019-04-02T15:20:59.747854 #10367] DEBUG -- :   ↳ lib/constraints/group_url_constrainer.rb:10:in `matches?'
D, [2019-04-02T15:20:59.747951 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.748045 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.748131 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.748221 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:20:59.757125 #10367] DEBUG -- :   Project Load (1.5ms)  SELECT  "projects".* FROM "projects" INNER JOIN "routes" ON "routes"."source_id" = "projects"."id" AND "routes"."source_type" = 'Project' WHERE ((LOWER(routes.path) = LOWER('gitlab-org/gitlab-ce'))) ORDER BY (CASE WHEN  routes.path = 'gitlab-org/gitlab-ce' THEN 0 ELSE 1 END) LIMIT 1
D, [2019-04-02T15:20:59.760192 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:50:in `find_by_full_path'
D, [2019-04-02T15:20:59.760498 #10367] DEBUG -- :   ↳ lib/constraints/project_url_constrainer.rb:15:in `matches?'
D, [2019-04-02T15:20:59.760611 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.760722 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.760810 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.760891 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
I, [2019-04-02T15:20:59.841199 #10367]  INFO -- : Processing by Projects::IssuesController#index as HTML
I, [2019-04-02T15:20:59.841598 #10367]  INFO -- :   Parameters: {"search"=>"slow finder", "namespace_id"=>"gitlab-org", "project_id"=>"gitlab-ce"}
D, [2019-04-02T15:20:59.857613 #10367] DEBUG -- :   Project Load (2.0ms)  SELECT  "projects".* FROM "projects" INNER JOIN "routes" ON "routes"."source_id" = "projects"."id" AND "routes"."source_type" = 'Project' WHERE ((LOWER(routes.path) = LOWER('gitlab-org/gitlab-ce'))) ORDER BY (CASE WHEN  routes.path = 'gitlab-org/gitlab-ce' THEN 0 ELSE 1 END) LIMIT 1
D, [2019-04-02T15:20:59.861170 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:50:in `find_by_full_path'
D, [2019-04-02T15:20:59.861441 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:7:in `find_routable!'
D, [2019-04-02T15:20:59.861559 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:25:in `project'
D, [2019-04-02T15:20:59.861651 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:20:59.861779 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.861942 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.862039 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.862121 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:20:59.885937 #10367] DEBUG -- :   License Load (0.8ms)  SELECT  "licenses".* FROM "licenses" ORDER BY "licenses"."id" DESC LIMIT 1
D, [2019-04-02T15:20:59.892165 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:231:in `load_license'
D, [2019-04-02T15:20:59.892533 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:216:in `block in current'
D, [2019-04-02T15:20:59.892646 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:216:in `current'
D, [2019-04-02T15:20:59.892754 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:222:in `feature_available?'
D, [2019-04-02T15:20:59.892839 #10367] DEBUG -- :   ↳ ee/app/models/ee/application_setting.rb:180:in `external_authorization_service_enabled'
D, [2019-04-02T15:20:59.892927 #10367] DEBUG -- :   ↳ ee/lib/ee/gitlab/external_authorization/config.rb:18:in `enabled?'
D, [2019-04-02T15:20:59.893076 #10367] DEBUG -- :   ↳ ee/lib/ee/gitlab/external_authorization/config.rb:22:in `perform_check?'
D, [2019-04-02T15:20:59.893171 #10367] DEBUG -- :   ↳ ee/app/policies/ee/base_policy.rb:9:in `block (2 levels) in <module:BasePolicy>'
D, [2019-04-02T15:20:59.893285 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `instance_eval'
D, [2019-04-02T15:20:59.893374 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `compute'
D, [2019-04-02T15:20:59.893460 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `block in pass?'
D, [2019-04-02T15:20:59.893556 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:282:in `cache'
D, [2019-04-02T15:20:59.893636 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `pass?'
D, [2019-04-02T15:20:59.893717 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:81:in `pass?'
D, [2019-04-02T15:20:59.893796 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `block in pass?'
D, [2019-04-02T15:20:59.893902 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `each'
D, [2019-04-02T15:20:59.894025 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `all?'
D, [2019-04-02T15:20:59.894115 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `pass?'
D, [2019-04-02T15:20:59.894208 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:20:59.894305 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:100:in `block in run'
D, [2019-04-02T15:20:59.894392 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:20:59.894489 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:20:59.894571 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:20:59.894651 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:20:59.894741 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:20:59.894830 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:20:59.894922 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:20:59.895022 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:20:59.895110 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:20:59.895198 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:20:59.895299 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:292:in `pass?'
D, [2019-04-02T15:20:59.895391 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `block in pass?'
D, [2019-04-02T15:20:59.895481 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `each'
D, [2019-04-02T15:20:59.895569 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `all?'
D, [2019-04-02T15:20:59.895654 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `pass?'
D, [2019-04-02T15:20:59.895741 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:20:59.895828 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:100:in `block in run'
D, [2019-04-02T15:20:59.895912 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:20:59.895999 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:20:59.896078 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:20:59.896156 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:20:59.896240 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:20:59.896346 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:20:59.896440 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:20:59.896556 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:20:59.896642 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:20:59.896727 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:20:59.896804 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:20:59.896894 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:91:in `block in run'
D, [2019-04-02T15:20:59.896981 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:20:59.897063 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:20:59.897147 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:20:59.897225 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:20:59.897345 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:20:59.897451 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:20:59.897556 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:20:59.897643 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:20:59.897733 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:20:59.897819 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:209:in `can?'
D, [2019-04-02T15:20:59.897902 #10367] DEBUG -- :   ↳ app/models/ability.rb:72:in `allowed?'
D, [2019-04-02T15:20:59.897988 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:177:in `can?'
D, [2019-04-02T15:20:59.898076 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:26:in `routable_authorized?'
D, [2019-04-02T15:20:59.898163 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:8:in `find_routable!'
D, [2019-04-02T15:20:59.898246 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:25:in `project'
D, [2019-04-02T15:20:59.898337 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:20:59.898424 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.898517 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.898596 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.898680 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:20:59.903126 #10367] DEBUG -- :    (0.8ms)  SELECT "features"."key" FROM "features"
D, [2019-04-02T15:20:59.909241 #10367] DEBUG -- :   ↳ lib/feature.rb:15:in `feature_names'
D, [2019-04-02T15:20:59.909558 #10367] DEBUG -- :   ↳ lib/feature.rb:35:in `persisted_names'
D, [2019-04-02T15:20:59.909654 #10367] DEBUG -- :   ↳ lib/feature.rb:42:in `persisted?'
D, [2019-04-02T15:20:59.909738 #10367] DEBUG -- :   ↳ lib/feature.rb:54:in `enabled?'
D, [2019-04-02T15:20:59.909821 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:312:in `feature_available?'
D, [2019-04-02T15:20:59.909924 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:222:in `feature_available?'
D, [2019-04-02T15:20:59.910033 #10367] DEBUG -- :   ↳ ee/app/models/ee/application_setting.rb:180:in `external_authorization_service_enabled'
D, [2019-04-02T15:20:59.910124 #10367] DEBUG -- :   ↳ ee/lib/ee/gitlab/external_authorization/config.rb:18:in `enabled?'
D, [2019-04-02T15:20:59.910214 #10367] DEBUG -- :   ↳ ee/lib/ee/gitlab/external_authorization/config.rb:22:in `perform_check?'
D, [2019-04-02T15:20:59.910312 #10367] DEBUG -- :   ↳ ee/app/policies/ee/base_policy.rb:9:in `block (2 levels) in <module:BasePolicy>'
D, [2019-04-02T15:20:59.910400 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `instance_eval'
D, [2019-04-02T15:20:59.910496 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `compute'
D, [2019-04-02T15:20:59.910583 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `block in pass?'
D, [2019-04-02T15:20:59.910667 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:282:in `cache'
D, [2019-04-02T15:20:59.910746 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `pass?'
D, [2019-04-02T15:20:59.910841 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:81:in `pass?'
D, [2019-04-02T15:20:59.910920 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `block in pass?'
D, [2019-04-02T15:20:59.911007 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `each'
D, [2019-04-02T15:20:59.911092 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `all?'
D, [2019-04-02T15:20:59.911175 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `pass?'
D, [2019-04-02T15:20:59.911260 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:20:59.911383 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:100:in `block in run'
D, [2019-04-02T15:20:59.911464 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:20:59.911556 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:20:59.911642 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:20:59.911725 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:20:59.911804 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:20:59.911887 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:20:59.911965 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:20:59.912051 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:20:59.912136 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:20:59.912226 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:20:59.912325 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:292:in `pass?'
D, [2019-04-02T15:20:59.912409 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `block in pass?'
D, [2019-04-02T15:20:59.912524 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `each'
D, [2019-04-02T15:20:59.912614 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `all?'
D, [2019-04-02T15:20:59.912699 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `pass?'
D, [2019-04-02T15:20:59.912782 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:20:59.912866 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:100:in `block in run'
D, [2019-04-02T15:20:59.912951 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:20:59.913043 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:20:59.913130 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:20:59.913213 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:20:59.913320 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:20:59.913405 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:20:59.913502 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:20:59.913609 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:20:59.913694 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:20:59.913775 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:20:59.913852 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:20:59.913931 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:91:in `block in run'
D, [2019-04-02T15:20:59.914040 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:20:59.914130 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:20:59.914214 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:20:59.914313 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:20:59.914400 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:20:59.914497 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:20:59.914577 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:20:59.914655 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:20:59.914757 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:20:59.914836 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:209:in `can?'
D, [2019-04-02T15:20:59.914924 #10367] DEBUG -- :   ↳ app/models/ability.rb:72:in `allowed?'
D, [2019-04-02T15:20:59.915005 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:177:in `can?'
D, [2019-04-02T15:20:59.915083 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:26:in `routable_authorized?'
D, [2019-04-02T15:20:59.915162 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:8:in `find_routable!'
D, [2019-04-02T15:20:59.915245 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:25:in `project'
D, [2019-04-02T15:20:59.915341 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:20:59.915421 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.915513 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.915601 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.915688 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:20:59.928709 #10367] DEBUG -- :   Route Load (0.9ms)  SELECT  "routes".* FROM "routes" WHERE "routes"."source_id" = 13083 AND "routes"."source_type" = 'Project' LIMIT 1
D, [2019-04-02T15:20:59.932292 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:99:in `full_path'
D, [2019-04-02T15:20:59.932588 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:38:in `ensure_canonical_path'
D, [2019-04-02T15:20:59.932686 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:9:in `find_routable!'
D, [2019-04-02T15:20:59.932770 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:25:in `project'
D, [2019-04-02T15:20:59.932867 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:20:59.932951 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.933041 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.933127 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.933223 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:20:59.958745 #10367] DEBUG -- :   ProjectFeature Load (0.8ms)  SELECT  "project_features".* FROM "project_features" WHERE "project_features"."project_id" = 13083 LIMIT 1
D, [2019-04-02T15:20:59.962448 #10367] DEBUG -- :   ↳ app/models/project.rb:63:in `feature_available?'
D, [2019-04-02T15:20:59.962729 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:219:in `feature_available?'
D, [2019-04-02T15:20:59.962825 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:89:in `check_issues_available!'
D, [2019-04-02T15:20:59.962912 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:20:59.963016 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.963171 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.963282 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.963371 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:20:59.990847 #10367] DEBUG -- :   Namespace Load (1.0ms)  SELECT  "namespaces".* FROM "namespaces" WHERE "namespaces"."id" = 9970 LIMIT 1
D, [2019-04-02T15:20:59.994623 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:591:in `load_licensed_feature_available'
D, [2019-04-02T15:20:59.994896 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:581:in `block (2 levels) in licensed_feature_available?'
D, [2019-04-02T15:20:59.994995 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:585:in `licensed_feature_available?'
D, [2019-04-02T15:20:59.995104 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:221:in `feature_available?'
D, [2019-04-02T15:20:59.995188 #10367] DEBUG -- :   ↳ ee/app/controllers/ee/projects/issues_controller.rb:57:in `finder_options'
D, [2019-04-02T15:20:59.995303 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:84:in `issuable_finder_for'
D, [2019-04-02T15:20:59.995389 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:180:in `finder'
D, [2019-04-02T15:20:59.995478 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:55:in `issuables_collection'
D, [2019-04-02T15:20:59.995567 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:18:in `set_issuables_index'
D, [2019-04-02T15:20:59.995655 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:20:59.995961 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:20:59.996051 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:20:59.996137 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:20:59.996221 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:00.055576 #10367] DEBUG -- :   Project Load (1.5ms)  SELECT  "projects".* FROM "projects" WHERE "projects"."id" = 13083 LIMIT 1
D, [2019-04-02T15:21:00.060077 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:180:in `project'
D, [2019-04-02T15:21:00.060419 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:190:in `projects'
D, [2019-04-02T15:21:00.060545 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:400:in `by_project'
D, [2019-04-02T15:21:00.060632 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:93:in `filter_items'
D, [2019-04-02T15:21:00.060721 #10367] DEBUG -- :   ↳ app/finders/issues_finder.rb:72:in `filter_items'
D, [2019-04-02T15:21:00.060825 #10367] DEBUG -- :   ↳ ee/app/finders/ee/issues_finder.rb:20:in `filter_items'
D, [2019-04-02T15:21:00.060917 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:79:in `execute'
D, [2019-04-02T15:21:00.061002 #10367] DEBUG -- :   ↳ app/finders/concerns/finder_with_cross_project_access.rb:37:in `block in execute'
D, [2019-04-02T15:21:00.061096 #10367] DEBUG -- :   ↳ app/finders/concerns/finder_with_cross_project_access.rb:40:in `execute'
D, [2019-04-02T15:21:00.061177 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:55:in `issuables_collection'
D, [2019-04-02T15:21:00.061257 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:18:in `set_issuables_index'
D, [2019-04-02T15:21:00.061377 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:00.061477 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:00.061566 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:00.061655 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:00.061735 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.353646 #10367] DEBUG -- :   Issue Load (10271.2ms)  SELECT  "issues".* FROM "issues" WHERE (issues.confidential IS NOT TRUE) AND "issues"."project_id" = 13083 AND ("issues"."state" IN ('opened')) AND ("issues"."title" ILIKE '%slow%' AND "issues"."title" ILIKE '%finder%' OR "issues"."description" ILIKE '%slow%' AND "issues"."description" ILIKE '%finder%') ORDER BY "issues"."created_at" DESC, "issues"."id" DESC LIMIT 20 OFFSET 0
D, [2019-04-02T15:21:10.357372 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:21:10.357581 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.357675 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.357792 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.357877 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.357960 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.358042 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.358116 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.392536 #10367] DEBUG -- :   Project Load (1.3ms)  SELECT "projects".* FROM "projects" WHERE "projects"."id" = 13083
D, [2019-04-02T15:21:10.396408 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:21:10.396740 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.396842 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.396931 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.397040 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.397124 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.397211 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.397313 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.406314 #10367] DEBUG -- :   User Load (1.1ms)  SELECT "users".* FROM "users" WHERE "users"."id" IN (171554, 1562869, 1530359, 654619)
D, [2019-04-02T15:21:10.410131 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:21:10.410411 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.410514 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.410600 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.410702 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.410796 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.410882 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.410973 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.483599 #10367] DEBUG -- :   IssueAssignee Load (1.0ms)  SELECT "issue_assignees".* FROM "issue_assignees" WHERE "issue_assignees"."issue_id" IN (16569097, 9835654, 9648990, 3982379)
D, [2019-04-02T15:21:10.487763 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:21:10.488098 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.488201 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.488298 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.488407 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.488530 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.488625 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.488714 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.500687 #10367] DEBUG -- :   LabelLink Load (1.1ms)  SELECT "label_links".* FROM "label_links" WHERE "label_links"."target_type" = 'Issue' AND "label_links"."target_id" IN (16569097, 9835654, 9648990, 3982379)
D, [2019-04-02T15:21:10.504801 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:21:10.505100 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.505194 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.505292 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.505388 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.505493 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.505583 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.505670 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.530566 #10367] DEBUG -- :   Label Load (1.1ms)  SELECT "labels".* FROM "labels" WHERE "labels"."id" IN (3857529, 1890178, 2492649, 3713902, 2731248, 3103451, 2278651, 3713901, 3098692, 3098698, 2677575, 2677574, 2655208, 3011693) ORDER BY "labels"."title" ASC
D, [2019-04-02T15:21:10.534544 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:21:10.534847 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.534952 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.535040 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.535134 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.535224 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.535331 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.535425 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.611262 #10367] DEBUG -- :   Milestone Load (0.9ms)  SELECT "milestones".* FROM "milestones" WHERE "milestones"."id" IN (655279, 490705)
D, [2019-04-02T15:21:10.618184 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:21:10.618509 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.618609 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.618693 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.618775 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.618869 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.618973 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.619058 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.634894 #10367] DEBUG -- :   Namespace Load (1.1ms)  SELECT "namespaces".* FROM "namespaces" WHERE "namespaces"."id" = 9970
D, [2019-04-02T15:21:10.638897 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:21:10.639175 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.639281 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.639368 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.639471 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.639557 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.639647 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.639732 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.657667 #10367] DEBUG -- :    (1.1ms)  SELECT "merge_requests_closing_issues"."issue_id", COUNT(*) as count FROM "merge_requests_closing_issues" WHERE "merge_requests_closing_issues"."issue_id" IN (16569097, 9835654, 9648990, 3982379) GROUP BY "merge_requests_closing_issues"."issue_id"
D, [2019-04-02T15:21:10.661436 #10367] DEBUG -- :   ↳ app/models/merge_requests_closing_issues.rb:14:in `count_for_collection'
D, [2019-04-02T15:21:10.661743 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:26:in `issuable_meta_data'
D, [2019-04-02T15:21:10.661845 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.661936 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.662021 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.662128 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.662223 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.662335 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.662430 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.669784 #10367] DEBUG -- :   AwardEmoji Load (1.2ms)  SELECT "award_emoji"."name", "award_emoji"."awardable_id", COUNT(*) as count FROM "award_emoji" WHERE (name IN ('thumbsdown','thumbsup') AND awardable_type = 'Issue' AND awardable_id IN (16569097,9835654,9648990,3982379)) GROUP BY "award_emoji"."name", "award_emoji"."awardable_id"
D, [2019-04-02T15:21:10.673764 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:32:in `block in issuable_meta_data'
D, [2019-04-02T15:21:10.674078 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `each'
D, [2019-04-02T15:21:10.674178 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `each_with_object'
D, [2019-04-02T15:21:10.674266 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `issuable_meta_data'
D, [2019-04-02T15:21:10.674396 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.674492 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.674607 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.674696 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.674777 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.674880 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.674964 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.689738 #10367] DEBUG -- :   Note Load (2.7ms)  SELECT "notes"."noteable_id", COUNT(*) as count FROM "notes" WHERE "notes"."system" = 'f' AND "notes"."noteable_type" = 'Issue' AND "notes"."noteable_id" IN (16569097, 9835654, 9648990, 3982379) GROUP BY "notes"."noteable_id"
D, [2019-04-02T15:21:10.693390 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:34:in `block in issuable_meta_data'
D, [2019-04-02T15:21:10.693666 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `each'
D, [2019-04-02T15:21:10.693761 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `each_with_object'
D, [2019-04-02T15:21:10.693855 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `issuable_meta_data'
D, [2019-04-02T15:21:10.693940 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:21:10.694023 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.694120 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.694229 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.694346 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.694443 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.694524 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:10.724009 #10367] DEBUG -- :   Project Load (1.4ms)  SELECT  "projects".* FROM "projects" WHERE "projects"."id" = 13083 LIMIT 1
D, [2019-04-02T15:21:10.729257 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:180:in `project'
D, [2019-04-02T15:21:10.729605 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:190:in `projects'
D, [2019-04-02T15:21:10.729711 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:400:in `by_project'
D, [2019-04-02T15:21:10.729795 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:93:in `filter_items'
D, [2019-04-02T15:21:10.729914 #10367] DEBUG -- :   ↳ app/finders/issues_finder.rb:72:in `filter_items'
D, [2019-04-02T15:21:10.730016 #10367] DEBUG -- :   ↳ ee/app/finders/ee/issues_finder.rb:20:in `filter_items'
D, [2019-04-02T15:21:10.730117 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:79:in `execute'
D, [2019-04-02T15:21:10.730211 #10367] DEBUG -- :   ↳ app/finders/concerns/finder_with_cross_project_access.rb:37:in `block in execute'
D, [2019-04-02T15:21:10.730305 #10367] DEBUG -- :   ↳ app/finders/concerns/finder_with_cross_project_access.rb:40:in `execute'
D, [2019-04-02T15:21:10.730423 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:140:in `count_by_state'
D, [2019-04-02T15:21:10.730507 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:44:in `block in initialize_cache'
D, [2019-04-02T15:21:10.730622 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:36:in `cache_for_finder'
D, [2019-04-02T15:21:10.730718 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:30:in `[]'
D, [2019-04-02T15:21:10.730809 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:19:in `for_state_or_opened'
D, [2019-04-02T15:21:10.730911 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:112:in `row_count'
D, [2019-04-02T15:21:10.731001 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:72:in `issuable_page_count'
D, [2019-04-02T15:21:10.731093 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:45:in `set_pagination'
D, [2019-04-02T15:21:10.731185 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:10.731359 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:10.731464 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:10.731557 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:10.731651 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:10.731748 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:20.750923 #10367] DEBUG -- :    (10009.5ms)  SELECT COUNT(*) AS count_all, "issues"."state" AS issues_state FROM "issues" WHERE (issues.confidential IS NOT TRUE) AND "issues"."project_id" = 13083 AND ("issues"."title" ILIKE '%slow%' AND "issues"."title" ILIKE '%finder%' OR "issues"."description" ILIKE '%slow%' AND "issues"."description" ILIKE '%finder%') GROUP BY "issues"."state"
D, [2019-04-02T15:21:20.754867 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:140:in `count_by_state'
D, [2019-04-02T15:21:20.754986 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:44:in `block in initialize_cache'
D, [2019-04-02T15:21:20.755019 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:36:in `cache_for_finder'
D, [2019-04-02T15:21:20.755050 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:30:in `[]'
D, [2019-04-02T15:21:20.755079 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:19:in `for_state_or_opened'
D, [2019-04-02T15:21:20.755107 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:112:in `row_count'
D, [2019-04-02T15:21:20.755134 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:72:in `issuable_page_count'
D, [2019-04-02T15:21:20.755161 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:45:in `set_pagination'
D, [2019-04-02T15:21:20.755188 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:21:20.755215 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:20.755242 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:20.755280 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:20.755308 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:20.755335 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:21.342672 #10367] DEBUG -- :   Group Load (1.2ms)  SELECT  "namespaces".* FROM "namespaces" WHERE "namespaces"."type" IN ('Group') AND "namespaces"."id" = 9970 AND "namespaces"."type" IN ('Group') AND "namespaces"."type" = 'Group' LIMIT 1
D, [2019-04-02T15:21:21.348221 #10367] DEBUG -- :   ↳ app/models/project.rb:1163:in `owner'
D, [2019-04-02T15:21:21.348557 #10367] DEBUG -- :   ↳ app/policies/project_policy.rb:31:in `block in <class:ProjectPolicy>'
D, [2019-04-02T15:21:21.348671 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `instance_eval'
D, [2019-04-02T15:21:21.348779 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `compute'
D, [2019-04-02T15:21:21.348883 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `block in pass?'
D, [2019-04-02T15:21:21.348979 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:282:in `cache'
D, [2019-04-02T15:21:21.349064 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `pass?'
D, [2019-04-02T15:21:21.349156 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:81:in `pass?'
D, [2019-04-02T15:21:21.349240 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:21:21.349338 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:91:in `block in run'
D, [2019-04-02T15:21:21.349415 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:21:21.349516 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:21:21.349616 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:21:21.349697 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:21:21.349778 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:21:21.349885 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:21:21.349966 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:21:21.350053 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:21:21.350144 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:21:21.350235 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:21:21.350338 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:21:21.350428 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:91:in `block in run'
D, [2019-04-02T15:21:21.350516 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:21:21.350606 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:21:21.350700 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:21:21.350805 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:21:21.350893 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:21:21.350983 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:21:21.351075 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:21:21.351164 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:21:21.351246 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:21:21.351354 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:209:in `can?'
D, [2019-04-02T15:21:21.351461 #10367] DEBUG -- :   ↳ app/models/ability.rb:72:in `allowed?'
D, [2019-04-02T15:21:21.351561 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:177:in `can?'
D, [2019-04-02T15:21:21.351652 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:2:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:21:21.351777 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:21:21.351866 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:21.351964 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:21.352061 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:21.352143 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:21.352229 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:21.386330 #10367] DEBUG -- :   Project Load (1.2ms)  SELECT  "projects".* FROM "projects" WHERE "projects"."id" = 13083 LIMIT 1
D, [2019-04-02T15:21:21.394421 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:180:in `project'
D, [2019-04-02T15:21:21.394727 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:190:in `projects'
D, [2019-04-02T15:21:21.394826 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:400:in `by_project'
D, [2019-04-02T15:21:21.394922 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:93:in `filter_items'
D, [2019-04-02T15:21:21.395020 #10367] DEBUG -- :   ↳ app/finders/issues_finder.rb:72:in `filter_items'
D, [2019-04-02T15:21:21.395141 #10367] DEBUG -- :   ↳ ee/app/finders/ee/issues_finder.rb:20:in `filter_items'
D, [2019-04-02T15:21:21.395224 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:79:in `execute'
D, [2019-04-02T15:21:21.395318 #10367] DEBUG -- :   ↳ app/finders/concerns/finder_with_cross_project_access.rb:37:in `block in execute'
D, [2019-04-02T15:21:21.395420 #10367] DEBUG -- :   ↳ app/finders/concerns/finder_with_cross_project_access.rb:40:in `execute'
D, [2019-04-02T15:21:21.395510 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:472:in `project_issues'
D, [2019-04-02T15:21:21.395591 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:10:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:21:21.395689 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:21:21.395782 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:21.395870 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:21.395958 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:21.396046 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:21.396143 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:21.405480 #10367] DEBUG -- :   Issue Exists (2.2ms)  SELECT  1 AS one FROM "issues" WHERE (issues.confidential IS NOT TRUE) AND "issues"."project_id" = 13083 LIMIT 1
D, [2019-04-02T15:21:21.409322 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:10:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:21:21.409553 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:21:21.409660 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:21.409745 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:21.409847 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:21.409929 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:21.410026 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:21.946076 #10367] DEBUG -- :   Route Load (0.8ms)  SELECT  "routes".* FROM "routes" WHERE "routes"."source_id" = 9970 AND "routes"."source_type" = 'Namespace' LIMIT 1
D, [2019-04-02T15:21:21.950356 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:99:in `full_path'
D, [2019-04-02T15:21:21.950603 #10367] DEBUG -- :   ↳ app/models/namespace.rb:126:in `to_param'
D, [2019-04-02T15:21:21.950702 #10367] DEBUG -- :   ↳ config/application.rb:271:in `block (4 levels) in <class:Application>'
D, [2019-04-02T15:21:21.950816 #10367] DEBUG -- :   ↳ app/views/projects/issues/_nav_btns.html.haml:19:in `_app_views_projects_issues__nav_btns_html_haml__2077275505556737091_70355690573380'
D, [2019-04-02T15:21:21.950910 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:14:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:21:21.951002 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:21:21.951091 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:21.951180 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:21.951289 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:21.951374 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:21.951459 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:23.227923 #10367] DEBUG -- :   Route Load (1.1ms)  SELECT  "routes".* FROM "routes" WHERE "routes"."source_id" = 9970 AND "routes"."source_type" = 'Namespace' LIMIT 1
D, [2019-04-02T15:21:23.233099 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:99:in `full_path'
D, [2019-04-02T15:21:23.233408 #10367] DEBUG -- :   ↳ app/models/namespace.rb:126:in `to_param'
D, [2019-04-02T15:21:23.233508 #10367] DEBUG -- :   ↳ config/application.rb:271:in `block (4 levels) in <class:Application>'
D, [2019-04-02T15:21:23.233596 #10367] DEBUG -- :   ↳ app/helpers/gitlab_routing_helper.rb:33:in `issue_path'
D, [2019-04-02T15:21:23.233700 #10367] DEBUG -- :   ↳ app/views/projects/issues/_issue.html.haml:1:in `_app_views_projects_issues__issue_html_haml__4080806682894947663_70355704124160'
D, [2019-04-02T15:21:23.233794 #10367] DEBUG -- :   ↳ app/views/projects/issues/_issues.html.haml:4:in `_app_views_projects_issues__issues_html_haml__3655651956007875168_70355704438280'
D, [2019-04-02T15:21:23.233891 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:21:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:21:23.233985 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:21:23.234086 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:23.234193 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:23.234293 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:23.234420 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:23.234535 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:23.755975 #10367] DEBUG -- :   Route Load (1.1ms)  SELECT  "routes".* FROM "routes" WHERE "routes"."source_id" = 9970 AND "routes"."source_type" = 'Namespace' LIMIT 1
D, [2019-04-02T15:21:23.760781 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:99:in `full_path'
D, [2019-04-02T15:21:23.761074 #10367] DEBUG -- :   ↳ app/models/namespace.rb:126:in `to_param'
D, [2019-04-02T15:21:23.761203 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:156:in `group_title_link'
D, [2019-04-02T15:21:23.761303 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:70:in `group_title'
D, [2019-04-02T15:21:23.761409 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:86:in `project_title'
D, [2019-04-02T15:21:23.761500 #10367] DEBUG -- :   ↳ app/views/layouts/project.html.haml:3:in `_app_views_layouts_project_html_haml___224099304132081445_70355684366600'
D, [2019-04-02T15:21:23.761642 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:21:23.761742 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:23.761831 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:23.761947 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:23.762036 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:23.762126 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:23.775023 #10367] DEBUG -- :   Upload Load (0.9ms)  SELECT  "uploads".* FROM "uploads" WHERE "uploads"."uploader" = 'AvatarUploader' AND "uploads"."path" IN ('uploads/-/system/group/avatar/9970/logo-extra-whitespace.png', 'group/avatar/9970/logo-extra-whitespace.png') ORDER BY "uploads"."id" ASC LIMIT 1000
D, [2019-04-02T15:21:23.780526 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:100:in `block in retrieve_upload_from_batch'
D, [2019-04-02T15:21:23.780858 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:33:in `retrieve_upload'
D, [2019-04-02T15:21:23.781034 #10367] DEBUG -- :   ↳ app/uploaders/object_storage.rb:49:in `retrieve_from_store!'
D, [2019-04-02T15:21:23.781164 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:57:in `uncached_avatar_path'
D, [2019-04-02T15:21:23.781260 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:53:in `avatar_path'
D, [2019-04-02T15:21:23.781362 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:24:in `avatar_url'
D, [2019-04-02T15:21:23.781448 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:157:in `block in group_title_link'
D, [2019-04-02T15:21:23.781534 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:156:in `group_title_link'
D, [2019-04-02T15:21:23.781989 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:70:in `group_title'
D, [2019-04-02T15:21:23.782122 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:86:in `project_title'
D, [2019-04-02T15:21:23.782297 #10367] DEBUG -- :   ↳ app/views/layouts/project.html.haml:3:in `_app_views_layouts_project_html_haml___224099304132081445_70355684366600'
D, [2019-04-02T15:21:23.782500 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:21:23.782610 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:23.782687 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:23.782760 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:23.782831 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:23.782901 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
D, [2019-04-02T15:21:25.081839 #10367] DEBUG -- :   Upload Load (1.1ms)  SELECT  "uploads".* FROM "uploads" WHERE "uploads"."uploader" = 'AvatarUploader' AND "uploads"."path" IN ('uploads/-/system/project/avatar/13083/logo-extra-whitespace.png', 'project/avatar/13083/logo-extra-whitespace.png') ORDER BY "uploads"."id" ASC LIMIT 1000
D, [2019-04-02T15:21:25.089611 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:100:in `block in retrieve_upload_from_batch'
D, [2019-04-02T15:21:25.089961 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:33:in `retrieve_upload'
D, [2019-04-02T15:21:25.090069 #10367] DEBUG -- :   ↳ app/uploaders/object_storage.rb:49:in `retrieve_from_store!'
D, [2019-04-02T15:21:25.090178 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:57:in `uncached_avatar_path'
D, [2019-04-02T15:21:25.090313 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:53:in `avatar_path'
D, [2019-04-02T15:21:25.090416 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:24:in `avatar_url'
D, [2019-04-02T15:21:25.090508 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:93:in `block in project_title'
D, [2019-04-02T15:21:25.090590 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:92:in `project_title'
D, [2019-04-02T15:21:25.090682 #10367] DEBUG -- :   ↳ app/views/layouts/project.html.haml:3:in `_app_views_layouts_project_html_haml___224099304132081445_70355684366600'
D, [2019-04-02T15:21:25.090805 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:21:25.090900 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:21:25.090990 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:21:25.091079 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:21:25.091224 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:21:25.091328 #10367] DEBUG -- :   ↳ (irb):1:in `irb_binding'
I, [2019-04-02T15:21:28.950060 #10367]  INFO -- : Completed 200 OK in 29108ms (Views: 7900.6ms | ActiveRecord: 20350.1ms | Elasticsearch: 0.0ms)
I, [2019-04-02T15:21:28.962082 #10367]  INFO -- : Issue total (1): 10271.2ms
I, [2019-04-02T15:21:28.962332 #10367]  INFO -- : Project total (6): 8.9ms
I, [2019-04-02T15:21:28.962417 #10367]  INFO -- : Route total (4): 3.9ms
I, [2019-04-02T15:21:28.962481 #10367]  INFO -- : Group total (3): 3.8ms
I, [2019-04-02T15:21:28.962541 #10367]  INFO -- : Note total (1): 2.7ms
I, [2019-04-02T15:21:28.962620 #10367]  INFO -- : Namespace total (2): 2.1ms
I, [2019-04-02T15:21:28.962687 #10367]  INFO -- : Upload total (2): 2.0ms
I, [2019-04-02T15:21:28.962755 #10367]  INFO -- : AwardEmoji total (1): 1.2ms
I, [2019-04-02T15:21:28.962822 #10367]  INFO -- : Label total (1): 1.1ms
I, [2019-04-02T15:21:28.962892 #10367]  INFO -- : LabelLink total (1): 1.1ms
I, [2019-04-02T15:21:28.962956 #10367]  INFO -- : User total (1): 1.1ms
I, [2019-04-02T15:21:28.963018 #10367]  INFO -- : IssueAssignee total (1): 1.0ms
I, [2019-04-02T15:21:28.963077 #10367]  INFO -- : Milestone total (1): 0.9ms
I, [2019-04-02T15:21:28.963145 #10367]  INFO -- : ProjectFeature total (1): 0.8ms
I, [2019-04-02T15:21:28.963206 #10367]  INFO -- : License total (1): 0.8ms

After (assuming the feature flag was on):

I, [2019-04-02T15:30:42.066887 #10367]  INFO -- : Completed 200 OK in 3345ms (Views: 491.3ms | ActiveRecord: 2568.1ms | Elasticsearch: 0.0ms)
I, [2019-04-02T15:30:42.077523 #10367]  INFO -- : Issue total (1): 540.1ms
I, [2019-04-02T15:30:42.077773 #10367]  INFO -- : Note total (1): 11.0ms
I, [2019-04-02T15:30:42.077845 #10367]  INFO -- : Project total (6): 9.0ms
I, [2019-04-02T15:30:42.077907 #10367]  INFO -- : Group total (3): 4.1ms
I, [2019-04-02T15:30:42.077968 #10367]  INFO -- : Route total (4): 4.0ms
I, [2019-04-02T15:30:42.078028 #10367]  INFO -- : LabelLink total (1): 2.6ms
I, [2019-04-02T15:30:42.078127 #10367]  INFO -- : Namespace total (2): 2.3ms
I, [2019-04-02T15:30:42.078201 #10367]  INFO -- : Upload total (2): 2.1ms
I, [2019-04-02T15:30:42.078260 #10367]  INFO -- : Label total (1): 1.3ms
I, [2019-04-02T15:30:42.078329 #10367]  INFO -- : User total (1): 1.3ms
I, [2019-04-02T15:30:42.078411 #10367]  INFO -- : AwardEmoji total (1): 1.1ms
I, [2019-04-02T15:30:42.078480 #10367]  INFO -- : Milestone total (1): 1.1ms
I, [2019-04-02T15:30:42.078540 #10367]  INFO -- : License total (1): 1.0ms
I, [2019-04-02T15:30:42.078609 #10367]  INFO -- : IssueAssignee total (1): 0.9ms
I, [2019-04-02T15:30:42.078682 #10367]  INFO -- : ProjectFeature total (1): 0.8ms
Full query log
D, [2019-04-02T15:30:38.681080 #10367] DEBUG -- :    (0.8ms)  SELECT EXTRACT(EPOCH FROM (now() - pg_last_xact_replay_timestamp()))::float as lag
D, [2019-04-02T15:30:38.685027 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:50:in `find_by_full_path'
D, [2019-04-02T15:30:38.685346 #10367] DEBUG -- :   ↳ lib/constraints/group_url_constrainer.rb:10:in `matches?'
D, [2019-04-02T15:30:38.685457 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.685543 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.685648 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.685738 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
Host 10.220.16.106 came back online
D, [2019-04-02T15:30:38.691057 #10367] DEBUG -- :   Group Load (1.5ms)  SELECT  "namespaces".* FROM "namespaces" INNER JOIN "routes" ON "routes"."source_id" = "namespaces"."id" AND "routes"."source_type" = 'Namespace' WHERE "namespaces"."type" IN ('Group') AND ((LOWER(routes.path) = LOWER('gitlab-org/gitlab-ce/issues'))) ORDER BY (CASE WHEN  routes.path = 'gitlab-org/gitlab-ce/issues' THEN 0 ELSE 1 END) LIMIT 1
D, [2019-04-02T15:30:38.694432 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:50:in `find_by_full_path'
D, [2019-04-02T15:30:38.694735 #10367] DEBUG -- :   ↳ lib/constraints/group_url_constrainer.rb:10:in `matches?'
D, [2019-04-02T15:30:38.694831 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.694914 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.695033 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.695133 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:38.701323 #10367] DEBUG -- :   Group Load (1.4ms)  SELECT  "namespaces".* FROM "namespaces" INNER JOIN "redirect_routes" ON "redirect_routes"."source_id" = "namespaces"."id" AND "redirect_routes"."source_type" = 'Namespace' WHERE "namespaces"."type" IN ('Group') AND (LOWER(redirect_routes.path) = LOWER('gitlab-org/gitlab-ce/issues')) LIMIT 1
D, [2019-04-02T15:30:38.704986 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:55:in `find_by_full_path'
D, [2019-04-02T15:30:38.705304 #10367] DEBUG -- :   ↳ lib/constraints/group_url_constrainer.rb:10:in `matches?'
D, [2019-04-02T15:30:38.705407 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.705499 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.705605 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.705693 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:38.711885 #10367] DEBUG -- :   Project Load (1.7ms)  SELECT  "projects".* FROM "projects" INNER JOIN "routes" ON "routes"."source_id" = "projects"."id" AND "routes"."source_type" = 'Project' WHERE ((LOWER(routes.path) = LOWER('gitlab-org/gitlab-ce'))) ORDER BY (CASE WHEN  routes.path = 'gitlab-org/gitlab-ce' THEN 0 ELSE 1 END) LIMIT 1
D, [2019-04-02T15:30:38.715305 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:50:in `find_by_full_path'
D, [2019-04-02T15:30:38.715614 #10367] DEBUG -- :   ↳ lib/constraints/project_url_constrainer.rb:15:in `matches?'
D, [2019-04-02T15:30:38.715722 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.715806 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.715904 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.715994 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
I, [2019-04-02T15:30:38.720933 #10367]  INFO -- : Processing by Projects::IssuesController#index as HTML
I, [2019-04-02T15:30:38.721351 #10367]  INFO -- :   Parameters: {"search"=>"slow finder", "namespace_id"=>"gitlab-org", "project_id"=>"gitlab-ce"}
D, [2019-04-02T15:30:38.738400 #10367] DEBUG -- :   Project Load (2.1ms)  SELECT  "projects".* FROM "projects" INNER JOIN "routes" ON "routes"."source_id" = "projects"."id" AND "routes"."source_type" = 'Project' WHERE ((LOWER(routes.path) = LOWER('gitlab-org/gitlab-ce'))) ORDER BY (CASE WHEN  routes.path = 'gitlab-org/gitlab-ce' THEN 0 ELSE 1 END) LIMIT 1
D, [2019-04-02T15:30:38.742603 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:50:in `find_by_full_path'
D, [2019-04-02T15:30:38.742933 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:7:in `find_routable!'
D, [2019-04-02T15:30:38.743041 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:25:in `project'
D, [2019-04-02T15:30:38.743127 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:38.743211 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.743330 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.743426 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.743520 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:38.758059 #10367] DEBUG -- :   License Load (1.0ms)  SELECT  "licenses".* FROM "licenses" ORDER BY "licenses"."id" DESC LIMIT 1
D, [2019-04-02T15:30:38.765235 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:231:in `load_license'
D, [2019-04-02T15:30:38.765614 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:216:in `block in current'
D, [2019-04-02T15:30:38.765718 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:216:in `current'
D, [2019-04-02T15:30:38.765800 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:222:in `feature_available?'
D, [2019-04-02T15:30:38.765884 #10367] DEBUG -- :   ↳ ee/app/models/ee/application_setting.rb:180:in `external_authorization_service_enabled'
D, [2019-04-02T15:30:38.765997 #10367] DEBUG -- :   ↳ ee/lib/ee/gitlab/external_authorization/config.rb:18:in `enabled?'
D, [2019-04-02T15:30:38.766142 #10367] DEBUG -- :   ↳ ee/lib/ee/gitlab/external_authorization/config.rb:22:in `perform_check?'
D, [2019-04-02T15:30:38.766251 #10367] DEBUG -- :   ↳ ee/app/policies/ee/base_policy.rb:9:in `block (2 levels) in <module:BasePolicy>'
D, [2019-04-02T15:30:38.766344 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `instance_eval'
D, [2019-04-02T15:30:38.766437 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `compute'
D, [2019-04-02T15:30:38.766528 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `block in pass?'
D, [2019-04-02T15:30:38.766615 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:282:in `cache'
D, [2019-04-02T15:30:38.766695 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `pass?'
D, [2019-04-02T15:30:38.766772 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:81:in `pass?'
D, [2019-04-02T15:30:38.766859 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `block in pass?'
D, [2019-04-02T15:30:38.766945 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `each'
D, [2019-04-02T15:30:38.767082 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `all?'
D, [2019-04-02T15:30:38.767186 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `pass?'
D, [2019-04-02T15:30:38.767343 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:30:38.767424 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:100:in `block in run'
D, [2019-04-02T15:30:38.767544 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:30:38.767641 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:30:38.767726 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:30:38.767804 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:30:38.767913 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:30:38.767996 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:30:38.768075 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:30:38.768183 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:30:38.768279 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:30:38.768384 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:30:38.768507 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:292:in `pass?'
D, [2019-04-02T15:30:38.768600 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `block in pass?'
D, [2019-04-02T15:30:38.768686 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `each'
D, [2019-04-02T15:30:38.768765 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `all?'
D, [2019-04-02T15:30:38.768853 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `pass?'
D, [2019-04-02T15:30:38.768935 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:30:38.769022 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:100:in `block in run'
D, [2019-04-02T15:30:38.769107 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:30:38.769185 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:30:38.769268 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:30:38.769366 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:30:38.769445 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:30:38.769538 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:30:38.769617 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:30:38.769694 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:30:38.769788 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:30:38.769894 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:30:38.769977 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:30:38.770072 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:91:in `block in run'
D, [2019-04-02T15:30:38.770155 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:30:38.770252 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:30:38.770350 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:30:38.770441 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:30:38.770531 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:30:38.770627 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:30:38.770716 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:30:38.770796 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:30:38.770901 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:30:38.770981 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:209:in `can?'
D, [2019-04-02T15:30:38.771075 #10367] DEBUG -- :   ↳ app/models/ability.rb:72:in `allowed?'
D, [2019-04-02T15:30:38.771153 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:177:in `can?'
D, [2019-04-02T15:30:38.771236 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:26:in `routable_authorized?'
D, [2019-04-02T15:30:38.771341 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:8:in `find_routable!'
D, [2019-04-02T15:30:38.771433 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:25:in `project'
D, [2019-04-02T15:30:38.771532 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:38.771611 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.771694 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.771772 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.771855 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:38.777245 #10367] DEBUG -- :    (1.2ms)  SELECT "features"."key" FROM "features"
D, [2019-04-02T15:30:38.784503 #10367] DEBUG -- :   ↳ lib/feature.rb:15:in `feature_names'
D, [2019-04-02T15:30:38.784834 #10367] DEBUG -- :   ↳ lib/feature.rb:35:in `persisted_names'
D, [2019-04-02T15:30:38.784931 #10367] DEBUG -- :   ↳ lib/feature.rb:42:in `persisted?'
D, [2019-04-02T15:30:38.785014 #10367] DEBUG -- :   ↳ lib/feature.rb:54:in `enabled?'
D, [2019-04-02T15:30:38.785124 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:312:in `feature_available?'
D, [2019-04-02T15:30:38.785226 #10367] DEBUG -- :   ↳ ee/app/models/license.rb:222:in `feature_available?'
D, [2019-04-02T15:30:38.785337 #10367] DEBUG -- :   ↳ ee/app/models/ee/application_setting.rb:180:in `external_authorization_service_enabled'
D, [2019-04-02T15:30:38.785439 #10367] DEBUG -- :   ↳ ee/lib/ee/gitlab/external_authorization/config.rb:18:in `enabled?'
D, [2019-04-02T15:30:38.785521 #10367] DEBUG -- :   ↳ ee/lib/ee/gitlab/external_authorization/config.rb:22:in `perform_check?'
D, [2019-04-02T15:30:38.785606 #10367] DEBUG -- :   ↳ ee/app/policies/ee/base_policy.rb:9:in `block (2 levels) in <module:BasePolicy>'
D, [2019-04-02T15:30:38.785685 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `instance_eval'
D, [2019-04-02T15:30:38.785769 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `compute'
D, [2019-04-02T15:30:38.785860 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `block in pass?'
D, [2019-04-02T15:30:38.785943 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:282:in `cache'
D, [2019-04-02T15:30:38.786030 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `pass?'
D, [2019-04-02T15:30:38.786126 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:81:in `pass?'
D, [2019-04-02T15:30:38.786210 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `block in pass?'
D, [2019-04-02T15:30:38.786309 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `each'
D, [2019-04-02T15:30:38.786401 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `all?'
D, [2019-04-02T15:30:38.786477 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `pass?'
D, [2019-04-02T15:30:38.786555 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:30:38.786640 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:100:in `block in run'
D, [2019-04-02T15:30:38.786747 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:30:38.786833 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:30:38.786911 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:30:38.786999 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:30:38.787083 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:30:38.787162 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:30:38.787240 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:30:38.787348 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:30:38.787427 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:30:38.787512 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:30:38.787596 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:292:in `pass?'
D, [2019-04-02T15:30:38.787678 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `block in pass?'
D, [2019-04-02T15:30:38.787768 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `each'
D, [2019-04-02T15:30:38.787857 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `all?'
D, [2019-04-02T15:30:38.787941 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:211:in `pass?'
D, [2019-04-02T15:30:38.788023 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:30:38.788102 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:100:in `block in run'
D, [2019-04-02T15:30:38.788189 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:30:38.788268 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:30:38.788376 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:30:38.788456 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:30:38.788570 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:30:38.788657 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:30:38.788740 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:30:38.788827 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:30:38.788912 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:30:38.788989 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:30:38.789074 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:30:38.789156 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:91:in `block in run'
D, [2019-04-02T15:30:38.789236 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:30:38.789346 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:30:38.789432 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:30:38.789509 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:30:38.789593 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:30:38.789673 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:30:38.789758 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:30:38.789843 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:30:38.789965 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:30:38.790095 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:209:in `can?'
D, [2019-04-02T15:30:38.790214 #10367] DEBUG -- :   ↳ app/models/ability.rb:72:in `allowed?'
D, [2019-04-02T15:30:38.790328 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:177:in `can?'
D, [2019-04-02T15:30:38.790430 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:26:in `routable_authorized?'
D, [2019-04-02T15:30:38.790516 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:8:in `find_routable!'
D, [2019-04-02T15:30:38.790617 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:25:in `project'
D, [2019-04-02T15:30:38.790709 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:38.790827 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.790955 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.791044 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.791149 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:38.801228 #10367] DEBUG -- :   Route Load (1.0ms)  SELECT  "routes".* FROM "routes" WHERE "routes"."source_id" = 13083 AND "routes"."source_type" = 'Project' LIMIT 1
D, [2019-04-02T15:30:38.805382 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:99:in `full_path'
D, [2019-04-02T15:30:38.805689 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:38:in `ensure_canonical_path'
D, [2019-04-02T15:30:38.805792 #10367] DEBUG -- :   ↳ app/controllers/concerns/routable_actions.rb:9:in `find_routable!'
D, [2019-04-02T15:30:38.805880 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:25:in `project'
D, [2019-04-02T15:30:38.806048 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:38.806143 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.806224 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.806318 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.806416 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:38.817605 #10367] DEBUG -- :   ProjectFeature Load (0.8ms)  SELECT  "project_features".* FROM "project_features" WHERE "project_features"."project_id" = 13083 LIMIT 1
D, [2019-04-02T15:30:38.821655 #10367] DEBUG -- :   ↳ app/models/project.rb:63:in `feature_available?'
D, [2019-04-02T15:30:38.821958 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:219:in `feature_available?'
D, [2019-04-02T15:30:38.822060 #10367] DEBUG -- :   ↳ app/controllers/projects/application_controller.rb:89:in `check_issues_available!'
D, [2019-04-02T15:30:38.822143 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:38.822251 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.822358 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.822444 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.822596 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:38.832648 #10367] DEBUG -- :   Namespace Load (1.1ms)  SELECT  "namespaces".* FROM "namespaces" WHERE "namespaces"."id" = 9970 LIMIT 1
D, [2019-04-02T15:30:38.837097 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:591:in `load_licensed_feature_available'
D, [2019-04-02T15:30:38.837428 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:581:in `block (2 levels) in licensed_feature_available?'
D, [2019-04-02T15:30:38.837537 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:585:in `licensed_feature_available?'
D, [2019-04-02T15:30:38.837624 #10367] DEBUG -- :   ↳ ee/app/models/ee/project.rb:221:in `feature_available?'
D, [2019-04-02T15:30:38.837829 #10367] DEBUG -- :   ↳ ee/app/controllers/ee/projects/issues_controller.rb:57:in `finder_options'
D, [2019-04-02T15:30:38.837925 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:84:in `issuable_finder_for'
D, [2019-04-02T15:30:38.838014 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:180:in `finder'
D, [2019-04-02T15:30:38.838104 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:55:in `issuables_collection'
D, [2019-04-02T15:30:38.838184 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:18:in `set_issuables_index'
D, [2019-04-02T15:30:38.838294 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:38.838380 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.838488 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.838571 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.838665 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:38.844320 #10367] DEBUG -- :   Project Load (1.3ms)  SELECT  "projects".* FROM "projects" WHERE "projects"."id" = 13083 LIMIT 1
D, [2019-04-02T15:30:38.849031 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:180:in `project'
D, [2019-04-02T15:30:38.849357 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:190:in `projects'
D, [2019-04-02T15:30:38.849469 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:400:in `by_project'
D, [2019-04-02T15:30:38.849555 #10367] DEBUG -- :   ↳ (irb):87:in `filter_items'
D, [2019-04-02T15:30:38.849656 #10367] DEBUG -- :   ↳ ee/app/finders/ee/issues_finder.rb:20:in `filter_items'
D, [2019-04-02T15:30:38.849739 #10367] DEBUG -- :   ↳ (irb):73:in `execute'
D, [2019-04-02T15:30:38.849830 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:55:in `issuables_collection'
D, [2019-04-02T15:30:38.849913 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:18:in `set_issuables_index'
D, [2019-04-02T15:30:38.850012 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:38.850101 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:38.850185 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:38.850265 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:38.850366 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.406408 #10367] DEBUG -- :   Issue Load (540.1ms)  WITH "issues" AS (SELECT "issues".* FROM "issues" WHERE (issues.confidential IS NOT TRUE) AND "issues"."project_id" = 13083 AND ("issues"."state" IN ('opened'))) SELECT  "issues".* FROM issues WHERE ("issues"."title" ILIKE '%slow%' AND "issues"."title" ILIKE '%finder%' OR "issues"."description" ILIKE '%slow%' AND "issues"."description" ILIKE '%finder%') ORDER BY "issues"."created_at" DESC, "issues"."id" DESC LIMIT 20 OFFSET 0
D, [2019-04-02T15:30:39.410547 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:30:39.410842 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.410939 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.411033 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.411132 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.411216 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.411308 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.411417 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.420858 #10367] DEBUG -- :   Project Load (1.3ms)  SELECT "projects".* FROM "projects" WHERE "projects"."id" = 13083
D, [2019-04-02T15:30:39.424988 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:30:39.425239 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.425351 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.425438 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.425547 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.425630 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.425709 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.425796 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.432104 #10367] DEBUG -- :   User Load (1.3ms)  SELECT "users".* FROM "users" WHERE "users"."id" IN (171554, 1562869, 1530359, 654619)
D, [2019-04-02T15:30:39.436035 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:30:39.436230 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.436339 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.436423 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.436561 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.436644 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.436737 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.436817 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.445664 #10367] DEBUG -- :   IssueAssignee Load (0.9ms)  SELECT "issue_assignees".* FROM "issue_assignees" WHERE "issue_assignees"."issue_id" IN (16569097, 9835654, 9648990, 3982379)
D, [2019-04-02T15:30:39.449823 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:30:39.450120 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.450217 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.450319 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.450423 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.450516 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.450603 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.450688 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.458628 #10367] DEBUG -- :   LabelLink Load (2.6ms)  SELECT "label_links".* FROM "label_links" WHERE "label_links"."target_type" = 'Issue' AND "label_links"."target_id" IN (16569097, 9835654, 9648990, 3982379)
D, [2019-04-02T15:30:39.462915 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:30:39.463136 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.463265 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.463382 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.463501 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.463599 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.463699 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.463778 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.474868 #10367] DEBUG -- :   Label Load (1.3ms)  SELECT "labels".* FROM "labels" WHERE "labels"."id" IN (3857529, 1890178, 2492649, 3713902, 2731248, 3103451, 2278651, 3713901, 3098692, 3098698, 2677575, 2677574, 2655208, 3011693) ORDER BY "labels"."title" ASC
D, [2019-04-02T15:30:39.478950 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:30:39.479232 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.479343 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.479429 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.479533 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.479621 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.479700 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.479778 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.489011 #10367] DEBUG -- :   Milestone Load (1.1ms)  SELECT "milestones".* FROM "milestones" WHERE "milestones"."id" IN (655279, 490705)
D, [2019-04-02T15:30:39.493293 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:30:39.493614 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.493720 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.493820 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.493913 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.494023 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.494108 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.494191 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.500032 #10367] DEBUG -- :   Namespace Load (1.2ms)  SELECT "namespaces".* FROM "namespaces" WHERE "namespaces"."id" = 9970
D, [2019-04-02T15:30:39.504731 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:18:in `issuable_meta_data'
D, [2019-04-02T15:30:39.505000 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.505102 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.505185 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.505311 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.505396 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.505493 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.505579 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.513963 #10367] DEBUG -- :    (1.6ms)  SELECT "merge_requests_closing_issues"."issue_id", COUNT(*) as count FROM "merge_requests_closing_issues" WHERE "merge_requests_closing_issues"."issue_id" IN (16569097, 9835654, 9648990, 3982379) GROUP BY "merge_requests_closing_issues"."issue_id"
D, [2019-04-02T15:30:39.517950 #10367] DEBUG -- :   ↳ app/models/merge_requests_closing_issues.rb:14:in `count_for_collection'
D, [2019-04-02T15:30:39.518235 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:26:in `issuable_meta_data'
D, [2019-04-02T15:30:39.518353 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.518449 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.518582 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.518665 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.518757 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.518842 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.518925 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.522014 #10367] DEBUG -- :   AwardEmoji Load (1.1ms)  SELECT "award_emoji"."name", "award_emoji"."awardable_id", COUNT(*) as count FROM "award_emoji" WHERE (name IN ('thumbsdown','thumbsup') AND awardable_type = 'Issue' AND awardable_id IN (16569097,9835654,9648990,3982379)) GROUP BY "award_emoji"."name", "award_emoji"."awardable_id"
D, [2019-04-02T15:30:39.525959 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:32:in `block in issuable_meta_data'
D, [2019-04-02T15:30:39.526165 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `each'
D, [2019-04-02T15:30:39.526260 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `each_with_object'
D, [2019-04-02T15:30:39.526359 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `issuable_meta_data'
D, [2019-04-02T15:30:39.526462 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.526553 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.526690 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.526773 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.526859 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.526936 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.527018 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.540484 #10367] DEBUG -- :   Note Load (11.0ms)  SELECT "notes"."noteable_id", COUNT(*) as count FROM "notes" WHERE "notes"."system" = 'f' AND "notes"."noteable_type" = 'Issue' AND "notes"."noteable_id" IN (16569097, 9835654, 9648990, 3982379) GROUP BY "notes"."noteable_id"
D, [2019-04-02T15:30:39.544644 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:34:in `block in issuable_meta_data'
D, [2019-04-02T15:30:39.544915 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `each'
D, [2019-04-02T15:30:39.545010 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `each_with_object'
D, [2019-04-02T15:30:39.545094 #10367] DEBUG -- :   ↳ lib/gitlab/issuable_metadata.rb:31:in `issuable_meta_data'
D, [2019-04-02T15:30:39.545191 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:44:in `set_pagination'
D, [2019-04-02T15:30:39.545301 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.545391 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.545491 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.545572 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.545676 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.545756 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:39.552188 #10367] DEBUG -- :   Project Load (1.4ms)  SELECT  "projects".* FROM "projects" WHERE "projects"."id" = 13083 LIMIT 1
D, [2019-04-02T15:30:39.557123 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:180:in `project'
D, [2019-04-02T15:30:39.557472 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:190:in `projects'
D, [2019-04-02T15:30:39.557577 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:400:in `by_project'
D, [2019-04-02T15:30:39.557668 #10367] DEBUG -- :   ↳ (irb):87:in `filter_items'
D, [2019-04-02T15:30:39.557764 #10367] DEBUG -- :   ↳ ee/app/finders/ee/issues_finder.rb:20:in `filter_items'
D, [2019-04-02T15:30:39.557845 #10367] DEBUG -- :   ↳ (irb):73:in `execute'
D, [2019-04-02T15:30:39.557923 #10367] DEBUG -- :   ↳ (irb):137:in `count_by_state'
D, [2019-04-02T15:30:39.558025 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:44:in `block in initialize_cache'
D, [2019-04-02T15:30:39.558113 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:36:in `cache_for_finder'
D, [2019-04-02T15:30:39.558197 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:30:in `[]'
D, [2019-04-02T15:30:39.558276 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:19:in `for_state_or_opened'
D, [2019-04-02T15:30:39.558382 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:112:in `row_count'
D, [2019-04-02T15:30:39.558470 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:72:in `issuable_page_count'
D, [2019-04-02T15:30:39.558550 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:45:in `set_pagination'
D, [2019-04-02T15:30:39.558639 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:39.558718 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:39.558800 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:39.558878 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:39.558958 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:39.559042 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.553796 #10367] DEBUG -- :    (1983.4ms)  WITH "issues" AS (SELECT "issues".* FROM "issues" WHERE (issues.confidential IS NOT TRUE) AND "issues"."project_id" = 13083) SELECT COUNT(*) AS count_all, "state" AS state FROM issues WHERE ("issues"."title" ILIKE '%slow%' AND "issues"."title" ILIKE '%finder%' OR "issues"."description" ILIKE '%slow%' AND "issues"."description" ILIKE '%finder%') GROUP BY "state"
D, [2019-04-02T15:30:41.558199 #10367] DEBUG -- :   ↳ (irb):137:in `count_by_state'
D, [2019-04-02T15:30:41.558504 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:44:in `block in initialize_cache'
D, [2019-04-02T15:30:41.558602 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:36:in `cache_for_finder'
D, [2019-04-02T15:30:41.558715 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:30:in `[]'
D, [2019-04-02T15:30:41.558799 #10367] DEBUG -- :   ↳ lib/gitlab/issuables_count_for_state.rb:19:in `for_state_or_opened'
D, [2019-04-02T15:30:41.558895 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:112:in `row_count'
D, [2019-04-02T15:30:41.558985 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:72:in `issuable_page_count'
D, [2019-04-02T15:30:41.559079 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:45:in `set_pagination'
D, [2019-04-02T15:30:41.559163 #10367] DEBUG -- :   ↳ app/controllers/concerns/issuable_collections.rb:20:in `set_issuables_index'
D, [2019-04-02T15:30:41.559323 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.559410 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.559551 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.559642 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.559721 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.573928 #10367] DEBUG -- :   Group Load (1.2ms)  SELECT  "namespaces".* FROM "namespaces" WHERE "namespaces"."type" IN ('Group') AND "namespaces"."id" = 9970 AND "namespaces"."type" IN ('Group') AND "namespaces"."type" = 'Group' LIMIT 1
D, [2019-04-02T15:30:41.580564 #10367] DEBUG -- :   ↳ app/models/project.rb:1163:in `owner'
D, [2019-04-02T15:30:41.580897 #10367] DEBUG -- :   ↳ app/policies/project_policy.rb:31:in `block in <class:ProjectPolicy>'
D, [2019-04-02T15:30:41.581000 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `instance_eval'
D, [2019-04-02T15:30:41.581085 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:23:in `compute'
D, [2019-04-02T15:30:41.581169 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `block in pass?'
D, [2019-04-02T15:30:41.581308 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:282:in `cache'
D, [2019-04-02T15:30:41.581408 #10367] DEBUG -- :   ↳ lib/declarative_policy/condition.rb:44:in `pass?'
D, [2019-04-02T15:30:41.581499 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:81:in `pass?'
D, [2019-04-02T15:30:41.581581 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:30:41.581679 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:91:in `block in run'
D, [2019-04-02T15:30:41.581766 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:30:41.581846 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:30:41.581927 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:30:41.582009 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:30:41.582092 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:30:41.582179 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:30:41.582263 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:30:41.582358 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:30:41.582451 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:30:41.582535 #10367] DEBUG -- :   ↳ lib/declarative_policy/rule.rb:159:in `pass?'
D, [2019-04-02T15:30:41.582625 #10367] DEBUG -- :   ↳ lib/declarative_policy/step.rb:81:in `pass?'
D, [2019-04-02T15:30:41.582726 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:91:in `block in run'
D, [2019-04-02T15:30:41.582877 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:179:in `block in steps_by_score'
D, [2019-04-02T15:30:41.582987 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `loop'
D, [2019-04-02T15:30:41.583074 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:148:in `steps_by_score'
D, [2019-04-02T15:30:41.583157 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:81:in `run'
D, [2019-04-02T15:30:41.583241 #10367] DEBUG -- :   ↳ lib/declarative_policy/runner.rb:59:in `pass?'
D, [2019-04-02T15:30:41.583341 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `block in allowed?'
D, [2019-04-02T15:30:41.583440 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `each'
D, [2019-04-02T15:30:41.583526 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `all?'
D, [2019-04-02T15:30:41.583613 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:217:in `allowed?'
D, [2019-04-02T15:30:41.583697 #10367] DEBUG -- :   ↳ lib/declarative_policy/base.rb:209:in `can?'
D, [2019-04-02T15:30:41.583780 #10367] DEBUG -- :   ↳ app/models/ability.rb:72:in `allowed?'
D, [2019-04-02T15:30:41.583964 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:177:in `can?'
D, [2019-04-02T15:30:41.584070 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:2:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:30:41.584197 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:30:41.584310 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.584408 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.584521 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.584608 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.584686 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.594933 #10367] DEBUG -- :   Project Load (1.2ms)  SELECT  "projects".* FROM "projects" WHERE "projects"."id" = 13083 LIMIT 1
D, [2019-04-02T15:30:41.599779 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:180:in `project'
D, [2019-04-02T15:30:41.600102 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:190:in `projects'
D, [2019-04-02T15:30:41.600200 #10367] DEBUG -- :   ↳ app/finders/issuable_finder.rb:400:in `by_project'
D, [2019-04-02T15:30:41.600314 #10367] DEBUG -- :   ↳ (irb):87:in `filter_items'
D, [2019-04-02T15:30:41.600417 #10367] DEBUG -- :   ↳ ee/app/finders/ee/issues_finder.rb:20:in `filter_items'
D, [2019-04-02T15:30:41.600528 #10367] DEBUG -- :   ↳ (irb):73:in `execute'
D, [2019-04-02T15:30:41.600608 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:472:in `project_issues'
D, [2019-04-02T15:30:41.600687 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:10:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:30:41.600797 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:30:41.600882 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.600971 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.601057 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.601149 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.601252 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.612733 #10367] DEBUG -- :   Issue Exists (3.9ms)  SELECT  1 AS one FROM "issues" WHERE (issues.confidential IS NOT TRUE) AND "issues"."project_id" = 13083 LIMIT 1
D, [2019-04-02T15:30:41.617278 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:10:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:30:41.617616 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:30:41.617726 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.617814 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.617916 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.618008 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.618098 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.667578 #10367] DEBUG -- :   Route Load (1.0ms)  SELECT  "routes".* FROM "routes" WHERE "routes"."source_id" = 9970 AND "routes"."source_type" = 'Namespace' LIMIT 1
D, [2019-04-02T15:30:41.672852 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:99:in `full_path'
D, [2019-04-02T15:30:41.673165 #10367] DEBUG -- :   ↳ app/models/namespace.rb:126:in `to_param'
D, [2019-04-02T15:30:41.673263 #10367] DEBUG -- :   ↳ config/application.rb:271:in `block (4 levels) in <class:Application>'
D, [2019-04-02T15:30:41.673421 #10367] DEBUG -- :   ↳ app/views/projects/issues/_nav_btns.html.haml:19:in `_app_views_projects_issues__nav_btns_html_haml__2077275505556737091_70355690573380'
D, [2019-04-02T15:30:41.673526 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:14:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:30:41.673619 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:30:41.673710 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.673840 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.673936 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.674017 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.674107 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.717107 #10367] DEBUG -- :   Route Load (1.0ms)  SELECT  "routes".* FROM "routes" WHERE "routes"."source_id" = 9970 AND "routes"."source_type" = 'Namespace' LIMIT 1
D, [2019-04-02T15:30:41.722195 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:99:in `full_path'
D, [2019-04-02T15:30:41.722520 #10367] DEBUG -- :   ↳ app/models/namespace.rb:126:in `to_param'
D, [2019-04-02T15:30:41.722618 #10367] DEBUG -- :   ↳ config/application.rb:271:in `block (4 levels) in <class:Application>'
D, [2019-04-02T15:30:41.722704 #10367] DEBUG -- :   ↳ app/helpers/gitlab_routing_helper.rb:33:in `issue_path'
D, [2019-04-02T15:30:41.722786 #10367] DEBUG -- :   ↳ app/views/projects/issues/_issue.html.haml:1:in `_app_views_projects_issues__issue_html_haml__4080806682894947663_70355704124160'
D, [2019-04-02T15:30:41.722869 #10367] DEBUG -- :   ↳ app/views/projects/issues/_issues.html.haml:4:in `_app_views_projects_issues__issues_html_haml__3655651956007875168_70355704438280'
D, [2019-04-02T15:30:41.722956 #10367] DEBUG -- :   ↳ app/views/projects/issues/index.html.haml:21:in `_app_views_projects_issues_index_html_haml__1400529243418209385_70355684537660'
D, [2019-04-02T15:30:41.723045 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:30:41.723125 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.723206 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.723301 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.723395 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.723481 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.794589 #10367] DEBUG -- :   Route Load (1.0ms)  SELECT  "routes".* FROM "routes" WHERE "routes"."source_id" = 9970 AND "routes"."source_type" = 'Namespace' LIMIT 1
D, [2019-04-02T15:30:41.799049 #10367] DEBUG -- :   ↳ app/models/concerns/routable.rb:99:in `full_path'
D, [2019-04-02T15:30:41.799330 #10367] DEBUG -- :   ↳ app/models/namespace.rb:126:in `to_param'
D, [2019-04-02T15:30:41.799484 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:156:in `group_title_link'
D, [2019-04-02T15:30:41.799590 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:70:in `group_title'
D, [2019-04-02T15:30:41.799677 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:86:in `project_title'
D, [2019-04-02T15:30:41.799764 #10367] DEBUG -- :   ↳ app/views/layouts/project.html.haml:3:in `_app_views_layouts_project_html_haml___224099304132081445_70355684366600'
D, [2019-04-02T15:30:41.799854 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:30:41.799933 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.800024 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.800169 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.800258 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.800362 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.806102 #10367] DEBUG -- :   Upload Load (1.0ms)  SELECT  "uploads".* FROM "uploads" WHERE "uploads"."uploader" = 'AvatarUploader' AND "uploads"."path" IN ('uploads/-/system/group/avatar/9970/logo-extra-whitespace.png', 'group/avatar/9970/logo-extra-whitespace.png') ORDER BY "uploads"."id" ASC LIMIT 1000
D, [2019-04-02T15:30:41.810770 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:100:in `block in retrieve_upload_from_batch'
D, [2019-04-02T15:30:41.811017 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:33:in `retrieve_upload'
D, [2019-04-02T15:30:41.811117 #10367] DEBUG -- :   ↳ app/uploaders/object_storage.rb:49:in `retrieve_from_store!'
D, [2019-04-02T15:30:41.811206 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:57:in `uncached_avatar_path'
D, [2019-04-02T15:30:41.811303 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:53:in `avatar_path'
D, [2019-04-02T15:30:41.811390 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:24:in `avatar_url'
D, [2019-04-02T15:30:41.811499 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:157:in `block in group_title_link'
D, [2019-04-02T15:30:41.811589 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:156:in `group_title_link'
D, [2019-04-02T15:30:41.811679 #10367] DEBUG -- :   ↳ app/helpers/groups_helper.rb:70:in `group_title'
D, [2019-04-02T15:30:41.811792 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:86:in `project_title'
D, [2019-04-02T15:30:41.811909 #10367] DEBUG -- :   ↳ app/views/layouts/project.html.haml:3:in `_app_views_layouts_project_html_haml___224099304132081445_70355684366600'
D, [2019-04-02T15:30:41.812006 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:30:41.812091 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.812171 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.812298 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.812391 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.812508 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
D, [2019-04-02T15:30:41.826316 #10367] DEBUG -- :   Upload Load (1.1ms)  SELECT  "uploads".* FROM "uploads" WHERE "uploads"."uploader" = 'AvatarUploader' AND "uploads"."path" IN ('uploads/-/system/project/avatar/13083/logo-extra-whitespace.png', 'project/avatar/13083/logo-extra-whitespace.png') ORDER BY "uploads"."id" ASC LIMIT 1000
D, [2019-04-02T15:30:41.831167 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:100:in `block in retrieve_upload_from_batch'
D, [2019-04-02T15:30:41.831470 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:33:in `retrieve_upload'
D, [2019-04-02T15:30:41.831570 #10367] DEBUG -- :   ↳ app/uploaders/object_storage.rb:49:in `retrieve_from_store!'
D, [2019-04-02T15:30:41.831654 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:57:in `uncached_avatar_path'
D, [2019-04-02T15:30:41.831759 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:53:in `avatar_path'
D, [2019-04-02T15:30:41.831891 #10367] DEBUG -- :   ↳ app/models/concerns/avatarable.rb:24:in `avatar_url'
D, [2019-04-02T15:30:41.832005 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:93:in `block in project_title'
D, [2019-04-02T15:30:41.832090 #10367] DEBUG -- :   ↳ app/helpers/projects_helper.rb:92:in `project_title'
D, [2019-04-02T15:30:41.832169 #10367] DEBUG -- :   ↳ app/views/layouts/project.html.haml:3:in `_app_views_layouts_project_html_haml___224099304132081445_70355684366600'
D, [2019-04-02T15:30:41.832340 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:116:in `render'
D, [2019-04-02T15:30:41.832443 #10367] DEBUG -- :   ↳ app/controllers/application_controller.rb:434:in `set_locale'
D, [2019-04-02T15:30:41.832557 #10367] DEBUG -- :   ↳ ee/lib/omni_auth/strategies/group_saml.rb:35:in `other_phase'
D, [2019-04-02T15:30:41.832642 #10367] DEBUG -- :   ↳ ee/lib/gitlab/jira/middleware.rb:17:in `call'
D, [2019-04-02T15:30:41.832726 #10367] DEBUG -- :   ↳ lib/gitlab/correlation_id.rb:15:in `use_id'
D, [2019-04-02T15:30:41.832809 #10367] DEBUG -- :   ↳ (irb):175:in `irb_binding'
I, [2019-04-02T15:30:42.066887 #10367]  INFO -- : Completed 200 OK in 3345ms (Views: 491.3ms | ActiveRecord: 2568.1ms | Elasticsearch: 0.0ms)
I, [2019-04-02T15:30:42.077523 #10367]  INFO -- : Issue total (1): 540.1ms
I, [2019-04-02T15:30:42.077773 #10367]  INFO -- : Note total (1): 11.0ms
I, [2019-04-02T15:30:42.077845 #10367]  INFO -- : Project total (6): 9.0ms
I, [2019-04-02T15:30:42.077907 #10367]  INFO -- : Group total (3): 4.1ms
I, [2019-04-02T15:30:42.077968 #10367]  INFO -- : Route total (4): 4.0ms
I, [2019-04-02T15:30:42.078028 #10367]  INFO -- : LabelLink total (1): 2.6ms
I, [2019-04-02T15:30:42.078127 #10367]  INFO -- : Namespace total (2): 2.3ms
I, [2019-04-02T15:30:42.078201 #10367]  INFO -- : Upload total (2): 2.1ms
I, [2019-04-02T15:30:42.078260 #10367]  INFO -- : Label total (1): 1.3ms
I, [2019-04-02T15:30:42.078329 #10367]  INFO -- : User total (1): 1.3ms
I, [2019-04-02T15:30:42.078411 #10367]  INFO -- : AwardEmoji total (1): 1.1ms
I, [2019-04-02T15:30:42.078480 #10367]  INFO -- : Milestone total (1): 1.1ms
I, [2019-04-02T15:30:42.078540 #10367]  INFO -- : License total (1): 1.0ms
I, [2019-04-02T15:30:42.078609 #10367]  INFO -- : IssueAssignee total (1): 0.9ms
I, [2019-04-02T15:30:42.078682 #10367]  INFO -- : ProjectFeature total (1): 0.8ms

Does this MR meet the acceptance criteria?

Conformity

Performance and testing

Closes https://gitlab.com/gitlab-org/gitlab-ce/issues/55170.

Merge request reports