Unable to process PUT request to elasticsearch URL

ecomsilio · June 13, 2019, 7:24pm

Infos:

Used Zammad version: 3.0.x
Used Zammad installation source: (source, package, …) package
Operating system: cent OS 7
Browser + version: Mac OS 10.12 + Safari and Chrome

Expected behavior:

Search working correctly, no failing background jobs

Actual behavior:

15 failing background jobs
Failed to run background job #1 ‘SearchIndexJob’ 10 time(s) with 122 attempt(s).

Steps to reproduce the behavior:

I have updated the Zammad installation to version 3. As soon as the update was ready, the monitoring said "failing background jobs.

production.log

E, [2019-06-13T21:08:15.596752 #9224-47313749407760] ERROR -- : Retrying SearchIndexJob in #<Proc:0x000056102f762c90@/opt/zammad/app/jobs/search_index_job.rb:3 (lambda)> seconds, due to a StandardError. The original exception was nil.
I, [2019-06-13T21:08:15.602812 #9224-47313749407760]  INFO -- : Enqueued SearchIndexJob (Job ID: 80cd0f31-13eb-4609-a459-46e79021901c) to DelayedJob(default) at 2019-06-13 19:09:35 UTC with arguments: "StatsStore", 3
I, [2019-06-13T21:08:15.604695 #9224-47313749407760]  INFO -- : 2019-06-13T21:08:15+0200: [Worker(host:zammad pid:9224)] Job ActiveJob::QueueAdapters::DelayedJobAdapter::JobWrapper (id=188066) (queue=default) COMPLETED after 0.0507
I, [2019-06-13T21:08:15.607218 #9224-47313749407760]  INFO -- : 2019-06-13T21:08:15+0200: [Worker(host:zammad pid:9224)] Job ActiveJob::QueueAdapters::DelayedJobAdapter::JobWrapper (id=188067) (queue=default) RUNNING
I, [2019-06-13T21:08:15.625881 #9224-47313749407760]  INFO -- : Performing SearchIndexJob (Job ID: d9f0e346-21e4-4915-8ff3-4c483d600447) from DelayedJob(default) with arguments: "StatsStore", 4
I, [2019-06-13T21:08:15.639309 #9224-47313749407760]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/StatsStore/4?pipeline=zammad39873510958" \
I, [2019-06-13T21:08:15.643766 #9224-47313749407760]  INFO -- : # 400
E, [2019-06-13T21:08:15.645036 #9224-47313749407760] ERROR -- : Unable to process POST request to elasticsearch URL 'http://127.0.0.1:9200/zammad_production/StatsStore/4?pipeline=zammad39873510958'. Check the response and payload for detailed information: 

Response:
#<UserAgent::Result:0x00007f213e51dea0 @success=false, @body="{\"error\":{\"root_cause\":[{\"type\":\"illegal_argument_exception\",\"reason\":\"pipeline with id [zammad39873510958] does not exist\"}],\"type\":\"illegal_argument_exception\",\"reason\":\"pipeline with id [zammad39873510958] does not exist\"},\"status\":400}", @data=nil, @code="400", @content_type=nil, @error="Client Error: #<Net::HTTPBadRequest 400 Bad Request readbody=true>!">

Payload:
{"id"=>4, "stats_store_object_id"=>1, "o_id"=>384, "key"=>"dashboard", "related_o_id"=>nil, "related_stats_store_object_id"=>nil, "data"=>{"StatsTicketWaitingTime"=>{"handling_time"=>139, "average_per_agent"=>139, "state"=>"good", "percent"=>0.4388888888888889}, "StatsTicketEscalation"=>{"used_for_average"=>0, "average_per_agent"=>0.0, "state"=>"supergood", "own"=>0, "total"=>0}, "StatsTicketChannelDistribution"=>{"channels"=>{"email"=>{"icon"=>"email", "inbound"=>68, "outbound"=>51, "inbound_in_percent"=>90, "outbound_in_percent"=>100}, "phone"=>{"icon"=>"phone", "inbound"=>7, "outbound"=>0, "inbound_in_percent"=>9, "outbound_in_percent"=>0}, "twitter"=>{"icon"=>"twitter", "inbound"=>0, "outbound"=>0, "inbound_in_percent"=>0, "outbound_in_percent"=>0}, "facebook"=>{"icon"=>"facebook", "inbound"=>0, "outbound"=>0, "inbound_in_percent"=>0, "outbound_in_percent"=>0}}}, "StatsTicketLoadMeasure"=>{"used_for_average"=>33.9, "average_per_agent"=>54.9, "percent"=>33.9, "state"=>"supergood", "own"=>56, "total"=>165, "average_per_agent_in_percent"=>101.8}, "StatsTicketInProcess"=>{"used_for_average"=>52.9, "average_per_agent"=>19.1, "state"=>"supergood", "in_process"=>9, "percent"=>52.9, "total"=>17}, "StatsTicketReopen"=>{"used_for_average"=>0.0, "percent"=>0.0, "average_per_agent"=>4.2, "state"=>"supergood", "count"=>0, "total"=>12}}, "created_by_id"=>1, "created_at"=>Mon, 07 Jan 2019 04:05:44 UTC +00:00, "updated_at"=>Thu, 13 Jun 2019 18:04:48 UTC +00:00, "stats_store_object"=>"User", "stats_store_object_ref"=>{"id"=>384, "organization_id"=>1, "login"=>"j.slaar@ecomsilio.de", "firstname"=>"Jane", "lastname"=>"Slaar", "email"=>"j.slaar@ecomsilio.de", "web"=>"", "phone"=>"", "fax"=>"", "mobile"=>"", "department"=>"", "street"=>"", "zip"=>"", "city"=>"", "country"=>"", "address"=>"", "vip"=>false, "verified"=>false, "active"=>true, "note"=>"", "last_login"=>Tue, 11 Jun 2019 05:24:58 UTC +00:00, "out_of_office"=>true, "out_of_office_start_at"=>Mon, 15 Apr 2019, "out_of_office_end_at"=>Mon, 22 Apr 2019, "out_of_office_replacement_id"=>3, "preferences"=>{"notification_config"=>{"matrix"=>{"create"=>{"criteria"=>{"owned_by_me"=>true}, "channel"=>{"email"=>false, "online"=>true}}, "update"=>{"criteria"=>{"owned_by_me"=>true}, "channel"=>{"email"=>false, "online"=>true}}, "reminder_reached"=>{"criteria"=>{"owned_by_me"=>true}, "channel"=>{"email"=>false, "online"=>true}}, "escalation"=>{"criteria"=>{"owned_by_me"=>true}, "channel"=>{"email"=>false, "online"=>true}}}, "group_ids"=>["1"]}, "locale"=>"de-de", "intro"=>true, "cti"=>true, "chat"=>{"active"=>{"1"=>"on"}}, "tickets_open"=>1, "tickets_closed"=>2, "notification_sound"=>{"file"=>"Xylo.mp3", "enabled"=>true}, "out_of_office_text"=>"Urlaub"}, "updated_by_id"=>1, "created_by_id"=>3, "created_at"=>Mon, 07 Jan 2019 03:56:10 UTC +00:00, "updated_at"=>Tue, 11 Jun 2019 12:06:28 UTC +00:00, "organization"=>"ecomsilio GmbH", "permissions"=>["admin.text_module", "user_preferences", "user_preferences.password", "user_preferences.notifications", "user_preferences.access_token", "user_preferences.language", "user_preferences.linked_accounts", "user_preferences.device", "user_preferences.avatar", "user_preferences.calendar", "user_preferences.out_of_office", "ticket.agent", "chat.agent", "cti.agent", "knowledge_base.reader"], "role_ids"=>[2]}}

Payload size: 0M
E, [2019-06-13T21:08:15.645274 #9224-47313749407760] ERROR -- : Error performing SearchIndexJob (Job ID: d9f0e346-21e4-4915-8ff3-4c483d600447) from DelayedJob(default) in 19.23ms: RuntimeError (Unable to process POST request to elasticsearch URL 'http://127.0.0.1:9200/zammad_production/StatsStore/4?pipeline=zammad39873510958'. Check the response and payload for detailed information: 

Response:
#<UserAgent::Result:0x00007f213e51dea0 @success=false, @body="{\"error\":{\"root_cause\":[{\"type\":\"illegal_argument_exception\",\"reason\":\"pipeline with id [zammad39873510958] does not exist\"}],\"type\":\"illegal_argument_exception\",\"reason\":\"pipeline with id [zammad39873510958] does not exist\"},\"status\":400}", @data=nil, @code="400", @content_type=nil, @error="Client Error: #<Net::HTTPBadRequest 400 Bad Request readbody=true>!">

Payload:
{"id"=>4, "stats_store_object_id"=>1, "o_id"=>384, "key"=>"dashboard", "related_o_id"=>nil, "related_stats_store_object_id"=>nil, "data"=>{"StatsTicketWaitingTime"=>{"handling_time"=>139, "average_per_agent"=>139, "state"=>"good", "percent"=>0.4388888888888889}, "StatsTicketEscalation"=>{"used_for_average"=>0, "average_per_agent"=>0.0, "state"=>"supergood", "own"=>0, "total"=>0}, "StatsTicketChannelDistribution"=>{"channels"=>{"email"=>{"icon"=>"email", "inbound"=>68, "outbound"=>51, "inbound_in_percent"=>90, "outbound_in_percent"=>100}, "phone"=>{"icon"=>"phone", "inbound"=>7, "outbound"=>0, "inbound_in_percent"=>9, "outbound_in_percent"=>0}, "twitter"=>{"icon"=>"twitter", "inbound"=>0, "outbound"=>0, "inbound_in_percent"=>0, "outbound_in_percent"=>0}, "facebook"=>{"icon"=>"facebook", "inbound"=>0, "outbound"=>0, "inbound_in_percent"=>0, "outbound_in_percent"=>0}}}, "StatsTicketLoadMeasure"=>{"used_for_average"=>33.9, "average_per_agent"=>54.9, "percent"=>33.9, "state"=>"supergood", "own"=>56, "total"=>165, "average_per_agent_in_percent"=>101.8}, "StatsTicketInProcess"=>{"used_for_average"=>52.9, "average_per_agent"=>19.1, "state"=>"supergood", "in_process"=>9, "percent"=>52.9, "total"=>17}, "StatsTicketReopen"=>{"used_for_average"=>0.0, "percent"=>0.0, "average_per_agent"=>4.2, "state"=>"supergood", "count"=>0, "total"=>12}}, "created_by_id"=>1, "created_at"=>Mon, 07 Jan 2019 04:05:44 UTC +00:00, "updated_at"=>Thu, 13 Jun 2019 18:04:48 UTC +00:00, "stats_store_object"=>"User", "stats_store_object_ref"=>{"id"=>384, "organization_id"=>1, "login"=>"j.slaar@ecomsilio.de", "firstname"=>"Jane", "lastname"=>"Slaar", "email"=>"j.slaar@ecomsilio.de", "web"=>"", "phone"=>"", "fax"=>"", "mobile"=>"", "department"=>"", "street"=>"", "zip"=>"", "city"=>"", "country"=>"", "address"=>"", "vip"=>false, "verified"=>false, "active"=>true, "note"=>"", "last_login"=>Tue, 11 Jun 2019 05:24:58 UTC +00:00, "out_of_office"=>true, "out_of_office_start_at"=>Mon, 15 Apr 2019, "out_of_office_end_at"=>Mon, 22 Apr 2019, "out_of_office_replacement_id"=>3, "preferences"=>{"notification_config"=>{"matrix"=>{"create"=>{"criteria"=>{"owned_by_me"=>true}, "channel"=>{"email"=>false, "online"=>true}}, "update"=>{"criteria"=>{"owned_by_me"=>true}, "channel"=>{"email"=>false, "online"=>true}}, "reminder_reached"=>{"criteria"=>{"owned_by_me"=>true}, "channel"=>{"email"=>false, "online"=>true}}, "escalation"=>{"criteria"=>{"owned_by_me"=>true}, "channel"=>{"email"=>false, "online"=>true}}}, "group_ids"=>["1"]}, "locale"=>"de-de", "intro"=>true, "cti"=>true, "chat"=>{"active"=>{"1"=>"on"}}, "tickets_open"=>1, "tickets_closed"=>2, "notification_sound"=>{"file"=>"Xylo.mp3", "enabled"=>true}, "out_of_office_text"=>"Urlaub"}, "updated_by_id"=>1, "created_by_id"=>3, "created_at"=>Mon, 07 Jan 2019 03:56:10 UTC +00:00, "updated_at"=>Tue, 11 Jun 2019 12:06:28 UTC +00:00, "organization"=>"ecomsilio GmbH", "permissions"=>["admin.text_module", "user_preferences", "user_preferences.password", "user_preferences.notifications", "user_preferences.access_token", "user_preferences.language", "user_preferences.linked_accounts", "user_preferences.device", "user_preferences.avatar", "user_preferences.calendar", "user_preferences.out_of_office", "ticket.agent", "chat.agent", "cti.agent", "knowledge_base.reader"], "role_ids"=>[2]}}

Payload size: 0M):
/opt/zammad/lib/search_index_backend.rb:229:in `add'
/opt/zammad/app/models/concerns/has_search_index_backend.rb:66:in `search_index_update_backend'
/opt/zammad/app/jobs/search_index_job.rb:14:in `perform'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/execution.rb:37:in `block in perform_now'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:108:in `block in run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/i18n-1.6.0/lib/i18n.rb:297:in `with_locale'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/translation.rb:7:in `block (2 levels) in <module:Translation>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:117:in `instance_exec'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:117:in `block in run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/logging.rb:24:in `block (4 levels) in <module:Logging>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/notifications.rb:166:in `block in instrument'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/notifications.rb:166:in `instrument'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/logging.rb:23:in `block (3 levels) in <module:Logging>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/logging.rb:44:in `block in tag_logger'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/tagged_logging.rb:69:in `block in tagged'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/tagged_logging.rb:26:in `tagged'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/tagged_logging.rb:69:in `tagged'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/logging.rb:44:in `tag_logger'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/logging.rb:20:in `block (2 levels) in <module:Logging>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:117:in `instance_exec'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:117:in `block in run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:135:in `run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/execution.rb:33:in `perform_now'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/execution.rb:22:in `block in execute'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:108:in `block in run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/railtie.rb:26:in `block (4 levels) in <class:Railtie>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/execution_wrapper.rb:85:in `wrap'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/reloader.rb:68:in `block in wrap'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/execution_wrapper.rb:85:in `wrap'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/reloader.rb:67:in `wrap'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/railtie.rb:25:in `block (3 levels) in <class:Railtie>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:117:in `instance_exec'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:117:in `block in run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.7/lib/active_support/callbacks.rb:135:in `run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/execution.rb:20:in `execute'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activejob-5.1.7/lib/active_job/queue_adapters/delayed_job_adapter.rb:36:in `perform'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/backend/base.rb:81:in `block in invoke_job'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/lifecycle.rb:61:in `block in initialize'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/lifecycle.rb:66:in `execute'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/lifecycle.rb:40:in `run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/backend/base.rb:78:in `invoke_job'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/worker.rb:230:in `block (2 levels) in run'
/opt/zammad/vendor/ruby-2.4.4/lib/ruby/2.4.0/timeout.rb:93:in `block in timeout'
/opt/zammad/vendor/ruby-2.4.4/lib/ruby/2.4.0/timeout.rb:103:in `timeout'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/worker.rb:230:in `block in run'
/opt/zammad/vendor/ruby-2.4.4/lib/ruby/2.4.0/benchmark.rb:308:in `realtime'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/worker.rb:229:in `run'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/worker.rb:312:in `block in reserve_and_run_one_job'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/lifecycle.rb:61:in `block in initialize'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/lifecycle.rb:66:in `execute'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/lifecycle.rb:40:in `run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/worker.rb:312:in `reserve_and_run_one_job'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/worker.rb:213:in `block in work_off'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/worker.rb:212:in `times'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/delayed_job-4.1.5/lib/delayed/worker.rb:212:in `work_off'
/opt/zammad/app/models/scheduler.rb:367:in `block (3 levels) in worker'
/opt/zammad/vendor/ruby-2.4.4/lib/ruby/2.4.0/benchmark.rb:308:in `realtime'
/opt/zammad/app/models/scheduler.rb:365:in `block (2 levels) in worker'
/opt/zammad/app/models/scheduler.rb:361:in `loop'
/opt/zammad/app/models/scheduler.rb:361:in `block in worker'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
E, [2019-06-13T21:08:15.645373 #9224-47313749407760] ERROR -- : Retrying SearchIndexJob in #<Proc:0x000056102f762c90@/opt/zammad/app/jobs/search_index_job.rb:3 (lambda)> seconds, due to a StandardError. The original exception was nil.
I, [2019-06-13T21:08:15.651344 #9224-47313749407760]  INFO -- : Enqueued SearchIndexJob (Job ID: d9f0e346-21e4-4915-8ff3-4c483d600447) to DelayedJob(default) at 2019-06-13 19:09:35 UTC with arguments: "StatsStore", 4
I, [2019-06-13T21:08:15.653254 #9224-47313749407760]  INFO -- : 2019-06-13T21:08:15+0200: [Worker(host:zammad pid:9224)] Job ActiveJob::QueueAdapters::DelayedJobAdapter::JobWrapper (id=188067) (queue=default) COMPLETED after 0.0459

zammad.error.log

2019/06/13 21:09:54 [error] 9994#0: *343 connect() failed (111: Connection refused) while connecting to upstream, client: 88.153.132.27, server: support.ecomsilio.de, request: "GET /api/v1/monitoring/health_check?_=1560452031091 HTTP/1.1", upstream: "http://[::1]:3000/api/v1/monitoring/health_check?_=1560452031091", host: "support.ecomsilio.de", referrer: "https://support.ecomsilio.de/"

I then ran sudo zammad run rake searchindex:rebuild with the following result:

drop indexes...done
delete pipeline (pipeline)... done
create indexes...done
create pipeline (pipeline)... rake aborted!
Unable to process PUT request to elasticsearch URL 'http://127.0.0.1:9200/_ingest/pipeline/zammad39873510958'. Check the response and payload for detailed information: 

Response:
#<UserAgent::Result:0x000056198c9f9538 @success=false, @body="{\"error\":{\"root_cause\":[{\"type\":\"parse_exception\",\"reason\":\"No processor type exists with name [attachment]\",\"header\":{\"processor_type\":\"foreach\"}}],\"type\":\"parse_exception\",\"reason\":\"No processor type exists with name [attachment]\",\"header\":{\"processor_type\":\"foreach\"}},\"status\":400}", @data=nil, @code="400", @content_type=nil, @error="Client Error: #<Net::HTTPBadRequest 400 Bad Request readbody=true>!">

Payload:
{:description=>"Extract zammad-attachment information from arrays", :processors=>[{:foreach=>{:field=>"article", :ignore_failure=>true, :processor=>{:foreach=>{:field=>"_ingest._value.attachment", :ignore_failure=>true, :processor=>{:attachment=>{:target_field=>"_ingest._value", :field=>"_ingest._value._content", :ignore_failure=>true}}}}}}]}

Payload size: 0M
/opt/zammad/lib/search_index_backend.rb:116:in `block (2 levels) in processors'
/opt/zammad/lib/search_index_backend.rb:76:in `each'
/opt/zammad/lib/search_index_backend.rb:76:in `block in processors'
/opt/zammad/lib/search_index_backend.rb:73:in `each'
/opt/zammad/lib/search_index_backend.rb:73:in `processors'
/opt/zammad/lib/tasks/search_index_es.rake:70:in `block (2 levels) in <top (required)>'
/opt/zammad/lib/tasks/search_index_es.rake:50:in `block (2 levels) in <top (required)>'
/opt/zammad/lib/tasks/search_index_es.rake:147:in `block (2 levels) in <top (required)>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rake-12.3.2/exe/rake:27:in `<top (required)>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/cli/exec.rb:74:in `load'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/cli/exec.rb:74:in `kernel_load'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/cli/exec.rb:27:in `run'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/cli.rb:360:in `exec'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/vendor/thor/lib/thor/invocation.rb:126:in `invoke_command'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/vendor/thor/lib/thor.rb:369:in `dispatch'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/cli.rb:20:in `dispatch'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/vendor/thor/lib/thor/base.rb:444:in `start'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/cli.rb:10:in `start'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/exe/bundle:30:in `block in <top (required)>'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/lib/bundler/friendly_errors.rb:121:in `with_friendly_errors'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/bundler-1.15.2/exe/bundle:22:in `<top (required)>'
/opt/zammad/bin/bundle:3:in `load'
/opt/zammad/bin/bundle:3:in `<main>'
Tasks: TOP => searchindex:rebuild
(See full trace by running task with --trace)

It might be the same problem as discussed here. I couldn’t answer to that thread as it is closed already.

Does anyone know how I get elastic search back to work?

Many thanks in advance!

MrGeneration · June 13, 2019, 7:41pm

Elasticsearch Error

Did you try removing the attachment-plugin and afterwards reinstalling it already?
A restart of elasticsearch might be needed. :-X

Zammad Error-Log

As for the connection refused in your nginx-error log:
This is not related to elasticsearch but indicates that you configured your upstream servers to be localhost:(3000|6042) - if you change localhost to 127.0.0.1 this issue should disappear after restarting nginx.

Reason for that is that our puma only listens to IPv4, but not IPv6

ecomsilio · June 13, 2019, 9:13pm

sudo /usr/share/elasticsearch/bin/elasticsearch-plugin remove ingest-attachment gave an error.

sudo /usr/share/elasticsearch/bin/elasticsearch-plugin list didn’t list any plugin. So I installed it by running the command sudo /usr/share/elasticsearch/bin/elasticsearch-plugin install ingest-attachment

After that I ran sudo zammad run rake search index:rebuild search is working again and no errors are displayed at the monitoring section.

Now I only need to fix this nginx upstream server issue.

Thanks for your help.

ecomsilio · June 14, 2019, 7:02am

The zammad.error.log is empty now. But the production log shows the following error:

I, [2019-06-14T04:45:33.006882 #9215-47195310296660]  INFO -- : Started GET "/y000000000068.cfg" for 207.180.217.211 at 2019-06-14 04:45:33 +0200
I, [2019-06-14T04:45:33.010538 #9215-47195310296660]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-14T04:45:33.010610 #9215-47195310296660]  INFO -- :   Parameters: {"a"=>"y000000000068"}
E, [2019-06-14T04:45:33.017054 #9215-47195310296660] ERROR -- : No route matches [GET] /y000000000068.cfg (ActionController::RoutingError)

This doesn’t seem to be a major error but I just want to try to get is completely clean.

MrGeneration · June 14, 2019, 7:49am

Hey,

that *.cfg is nothing from us. Maybe someone is “trying” stuff against your Server.
Can’t tell that for sure to be honest. If your Server does only provide Zammad, it would at least not be a mixed vHost that leads into the wrong direction.

ecomsilio · June 14, 2019, 8:15am

The server is hosted at 1&1 / ionos.
The ip address shown belongs to Contabo GmbH, a hosting company in Munich.

When I google the file name y000000000068.cfg, I get a lot of results to Yealink IP phones. We use the placetel integration so I guess it has something to do with placetel.

I will disable the integration this weekend to see if we still get the errors.

MrGeneration · June 14, 2019, 8:28am

I don’t think that this will change then.
As hint, I can’t seem to find those entries in other instances with placetel enabled.

ecomsilio · June 14, 2019, 9:02am

I checked the logs of the last 14 days. (is there an easier way to only check “error” and “fatal” instead of downloading the logs and search in the file?)

I have found some “E” and “F”. See examples below. Could you please let me know if I can just ignore them because they are simple bots or if there is a high security risk? Also let me know if this is something that can only be done by a paid ticket through zammad.com.

Thanks a lot.

Multiple E and F from the same IP

I, [2019-06-11T00:33:38.699712 #9202-47137000824960]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.700282 #9202-47137000824960]  INFO -- :   Rendered inline template (0.5ms)
I, [2019-06-11T00:33:38.701376 #9202-47137000824960]  INFO -- : Completed 404 Not Found in 8ms (Views: 0.8ms | ActiveRecord: 4.4ms)
I, [2019-06-11T00:33:38.706210 #9202-47136987379360]  INFO -- : Started GET "/app/temp.sql" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.708360 #9202-47136987379360]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-11T00:33:38.708413 #9202-47136987379360]  INFO -- :   Parameters: {"a"=>"app/temp"}
E, [2019-06-11T00:33:38.713034 #9202-47136987379360] ERROR -- : No route matches [GET] /app/temp.sql (ActionController::RoutingError)

I, [2019-06-11T00:33:38.713414 #9202-47136987379360]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.713977 #9202-47136987379360]  INFO -- :   Rendered inline template (0.5ms)
I, [2019-06-11T00:33:38.715073 #9202-47136987379360]  INFO -- : Completed 404 Not Found in 7ms (Views: 0.8ms | ActiveRecord: 3.2ms)
I, [2019-06-11T00:33:38.729924 #9202-47136987273660]  INFO -- : Started GET "/didiwiki/WikiHome" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.732096 #9202-47136987273660]  INFO -- : Processing by ErrorsController#routing as HTML
I, [2019-06-11T00:33:38.732161 #9202-47136987273660]  INFO -- :   Parameters: {"a"=>"didiwiki/WikiHome"}
E, [2019-06-11T00:33:38.735734 #9202-47136987273660] ERROR -- : No route matches [GET] /didiwiki/WikiHome (ActionController::RoutingError)

I, [2019-06-11T00:33:38.736085 #9202-47136987273660]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.736589 #9202-47136987273660]  INFO -- :   Rendered inline template (0.4ms)
I, [2019-06-11T00:33:38.737735 #9202-47136987273660]  INFO -- : Completed 404 Not Found in 5ms (Views: 0.7ms | ActiveRecord: 2.3ms)
I, [2019-06-11T00:33:38.741660 #9202-47137038431540]  INFO -- : Started GET "/sm_forum/" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.743767 #9202-47137038431540]  INFO -- : Processing by ErrorsController#routing as HTML
I, [2019-06-11T00:33:38.744067 #9202-47137038431540]  INFO -- :   Parameters: {"a"=>"sm_forum"}
E, [2019-06-11T00:33:38.747178 #9202-47137038431540] ERROR -- : No route matches [GET] /sm_forum (ActionController::RoutingError)

I, [2019-06-11T00:33:38.747513 #9202-47137038431540]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.748025 #9202-47137038431540]  INFO -- :   Rendered inline template (0.4ms)
I, [2019-06-11T00:33:38.749074 #9202-47137038431540]  INFO -- : Completed 404 Not Found in 5ms (Views: 0.7ms | ActiveRecord: 1.8ms)
I, [2019-06-11T00:33:38.754142 #9202-47137002584860]  INFO -- : Started GET "/app/id_rsa" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.756251 #9202-47137002584860]  INFO -- : Processing by ErrorsController#routing as HTML
I, [2019-06-11T00:33:38.756303 #9202-47137002584860]  INFO -- :   Parameters: {"a"=>"app/id_rsa"}
E, [2019-06-11T00:33:38.761092 #9202-47137002584860] ERROR -- : No route matches [GET] /app/id_rsa (ActionController::RoutingError)

I, [2019-06-11T00:33:38.761430 #9202-47137002584860]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.761933 #9202-47137002584860]  INFO -- :   Rendered inline template (0.4ms)
I, [2019-06-11T00:33:38.763032 #9202-47137002584860]  INFO -- : Completed 404 Not Found in 7ms (Views: 0.7ms | ActiveRecord: 3.3ms)
I, [2019-06-11T00:33:38.778993 #9202-69835008363480]  INFO -- : Started GET "/wiki/WikiHome" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.781147 #9202-69835008363480]  INFO -- : Processing by ErrorsController#routing as HTML
I, [2019-06-11T00:33:38.781661 #9202-69834828073140]  INFO -- : Started GET "/sugarcrm/index.php?action=Login&module=Users&login_module=Home&login_action=index" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.781734 #9202-69835008363480]  INFO -- :   Parameters: {"a"=>"wiki/WikiHome"}
I, [2019-06-11T00:33:38.783865 #9202-69834828073140]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-11T00:33:38.784389 #9202-69834828073140]  INFO -- :   Parameters: {"module"=>"Users", "login_module"=>"Home", "login_action"=>"index", "a"=>"sugarcrm/index"}
E, [2019-06-11T00:33:38.788531 #9202-69835008363480] ERROR -- : No route matches [GET] /wiki/WikiHome (ActionController::RoutingError)

I, [2019-06-11T00:33:38.788938 #9202-69835008363480]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.790129 #9202-69835008363480]  INFO -- :   Rendered inline template (1.1ms)
I, [2019-06-11T00:33:38.791274 #9202-69835008363480]  INFO -- : Completed 404 Not Found in 7ms (Views: 1.4ms | ActiveRecord: 2.9ms)
E, [2019-06-11T00:33:38.792400 #9202-69834828073140] ERROR -- : No route matches [GET] /sugarcrm/index.php (ActionController::RoutingError)

I, [2019-06-11T00:33:38.793552 #9202-69834828073140]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.794057 #9202-69834828073140]  INFO -- :   Rendered inline template (0.4ms)
I, [2019-06-11T00:33:38.795208 #9202-69834828073140]  INFO -- : Completed 404 Not Found in 11ms (Views: 0.7ms | ActiveRecord: 6.7ms)
I, [2019-06-11T00:33:38.814215 #9202-47137000824960]  INFO -- : Started GET "/app/mysqldump.sql" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.816705 #9202-47137000824960]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-11T00:33:38.816759 #9202-47137000824960]  INFO -- :   Parameters: {"a"=>"app/mysqldump"}
E, [2019-06-11T00:33:38.821281 #9202-47137000824960] ERROR -- : No route matches [GET] /app/mysqldump.sql (ActionController::RoutingError)

I, [2019-06-11T00:33:38.821667 #9202-47137000824960]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.822186 #9202-47137000824960]  INFO -- :   Rendered inline template (0.4ms)
I, [2019-06-11T00:33:38.823581 #9202-47137000824960]  INFO -- : Completed 404 Not Found in 7ms (Views: 0.7ms | ActiveRecord: 3.5ms)
I, [2019-06-11T00:33:38.843015 #9202-47136987379360]  INFO -- : Started PROPFIND "/" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
F, [2019-06-11T00:33:38.845184 #9202-47136987379360] FATAL -- :   
F, [2019-06-11T00:33:38.845232 #9202-47136987379360] FATAL -- : ActionController::RoutingError (No route matches [PROPFIND] "/"):
F, [2019-06-11T00:33:38.845257 #9202-47136987379360] FATAL -- :   
F, [2019-06-11T00:33:38.845284 #9202-47136987379360] FATAL -- : vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/debug_exceptions.rb:63:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:36:in `call_app'
vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:24:in `block in call'
vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:69:in `block in tagged'
vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:26:in `tagged'
vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:69:in `tagged'
vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:24:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/request_id.rb:25:in `call'
vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/method_override.rb:22:in `call'
vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/runtime.rb:22:in `call'
vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/executor.rb:12:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/static.rb:125:in `call'
vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/sendfile.rb:111:in `call'
vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/engine.rb:522:in `call'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/configuration.rb:225:in `call'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:624:in `handle_request'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:438:in `process_client'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:302:in `block in run'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/thread_pool.rb:120:in `block in spawn_thread'
vendor/bundle/ruby/2.4.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
I, [2019-06-11T00:33:38.852567 #9202-47136987273660]  INFO -- : Started GET "/sugarcrm/" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.854866 #9202-47136987273660]  INFO -- : Processing by ErrorsController#routing as HTML
I, [2019-06-11T00:33:38.854920 #9202-47136987273660]  INFO -- :   Parameters: {"a"=>"sugarcrm"}
E, [2019-06-11T00:33:38.860325 #9202-47136987273660] ERROR -- : No route matches [GET] /sugarcrm (ActionController::RoutingError)

I, [2019-06-11T00:33:38.860755 #9202-47136987273660]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.868653 #9202-47136987273660]  INFO -- :   Rendered inline template (7.8ms)
I, [2019-06-11T00:33:38.869089 #9202-47137038431540]  INFO -- : Started GET "/refbase/index.php" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.872134 #9202-47137038431540]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-11T00:33:38.872297 #9202-47137038431540]  INFO -- :   Parameters: {"a"=>"refbase/index"}
I, [2019-06-11T00:33:38.873254 #9202-47137002584860]  INFO -- : Started GET "/sites/default/files/.ht.sqlite" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.876566 #9202-47137002584860]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-11T00:33:38.876651 #9202-47137002584860]  INFO -- :   Parameters: {"a"=>"sites/default/files/.ht"}
I, [2019-06-11T00:33:38.877544 #9202-47136987273660]  INFO -- : Completed 404 Not Found in 23ms (Views: 8.6ms | ActiveRecord: 11.1ms)
E, [2019-06-11T00:33:38.888284 #9202-47137038431540] ERROR -- : No route matches [GET] /refbase/index.php (ActionController::RoutingError)

I, [2019-06-11T00:33:38.888748 #9202-47137038431540]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.889309 #9202-47137038431540]  INFO -- :   Rendered inline template (0.5ms)
I, [2019-06-11T00:33:38.890458 #9202-47137038431540]  INFO -- : Completed 404 Not Found in 18ms (Views: 0.8ms | ActiveRecord: 14.0ms)
E, [2019-06-11T00:33:38.892911 #9202-47137002584860] ERROR -- : No route matches [GET] /sites/default/files/.ht.sqlite (ActionController::RoutingError)

I, [2019-06-11T00:33:38.893300 #9202-47137002584860]  INFO -- :   Rendering inline template
I, [2019-06-11T00:33:38.894127 #9202-47137002584860]  INFO -- :   Rendered inline template (0.4ms)
I, [2019-06-11T00:33:38.895280 #9202-47137002584860]  INFO -- : Completed 404 Not Found in 19ms (Views: 1.0ms | ActiveRecord: 14.2ms)
I, [2019-06-11T00:33:38.908156 #9202-69835008363480]  INFO -- : Started GET "/app/WikiHome" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.910629 #9202-69835008363480]  INFO -- : Processing by ErrorsController#routing as HTML
I, [2019-06-11T00:33:38.910684 #9202-69835008363480]  INFO -- :   Parameters: {"a"=>"app/WikiHome"}
I, [2019-06-11T00:33:38.922306 #9202-69834828073140]  INFO -- : Started GET "/SugarCRM/index.php?action=Login&module=Users&login_module=Home&login_action=index" for 34.76.132.140 at 2019-06-11 00:33:38 +0200
I, [2019-06-11T00:33:38.924749 #9202-69834828073140]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-11T00:33:38.924817 #9202-69834828073140]  INFO -- :   Parameters: {"module"=>"Users", "login_module"=>"Home", "login_action"=>"index", "a"=>"SugarCRM/index"}
E, [2019-06-11T00:33:38.928022 #9202-69835008363480] ERROR -- : No route matches [GET] /app/WikiHome (ActionController::RoutingError)

Fatal on "/api/v1/form_config" (Multiple times every 4-5 min. over 1 hour from the same IP)

I, [2019-06-11T08:29:19.029923 #9202-47137032745040]  INFO -- : Started OPTIONS "/api/v1/form_config" for 188.138.41.138 at 2019-06-11 08:29:19 +0200
F, [2019-06-11T08:29:19.035754 #9202-47137032745040] FATAL -- :   
F, [2019-06-11T08:29:19.036374 #9202-47137032745040] FATAL -- : ActionController::RoutingError (No route matches [OPTIONS] "/api/v1/form_config"):
F, [2019-06-11T08:29:19.036430 #9202-47137032745040] FATAL -- :   
F, [2019-06-11T08:29:19.036478 #9202-47137032745040] FATAL -- : vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/debug_exceptions.rb:63:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:36:in `call_app'
vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:24:in `block in call'
vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:69:in `block in tagged'
vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:26:in `tagged'
vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:69:in `tagged'
vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:24:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/request_id.rb:25:in `call'
vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/method_override.rb:22:in `call'
vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/runtime.rb:22:in `call'
vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/executor.rb:12:in `call'
vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/static.rb:125:in `call'
vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/sendfile.rb:111:in `call'
vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/engine.rb:522:in `call'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/configuration.rb:225:in `call'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:624:in `handle_request'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:438:in `process_client'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:302:in `block in run'
vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/thread_pool.rb:120:in `block in spawn_thread'
vendor/bundle/ruby/2.4.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'

Error: /login_sid.lua

I, [2019-06-01T18:53:33.388016 #9202-69834864054720]  INFO -- : Started GET "/login_sid.lua" for 162.241.4.196 at 2019-06-01 18:53:33 +0200
I, [2019-06-01T18:53:33.395696 #9202-69834864054720]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-01T18:53:33.395771 #9202-69834864054720]  INFO -- :   Parameters: {"a"=>"login_sid"}
E, [2019-06-01T18:53:33.401625 #9202-69834864054720] ERROR -- : No route matches [GET] /login_sid.lua (ActionController::RoutingError)

I, [2019-06-01T18:53:33.402384 #9202-69834864054720]  INFO -- :   Rendering inline template
I, [2019-06-01T18:53:33.409044 #9202-69834864054720]  INFO -- :   Rendered inline template (6.6ms)
I, [2019-06-01T18:53:33.410420 #9202-69834864054720]  INFO -- : Completed 404 Not Found in 15ms (Views: 7.1ms | ActiveRecord: 3.5ms)

Error: /api/v1/form_config

I, [2019-06-02T01:03:24.811037 #9202-47136989589960]  INFO -- : Started GET "/api/v1/form_config" for 40.77.191.37 at 2019-06-02 01:03:24 +0200
I, [2019-06-02T01:03:24.819456 #9202-47136989589960]  INFO -- : Processing by ErrorsController#routing as */*
I, [2019-06-02T01:03:24.819525 #9202-47136989589960]  INFO -- :   Parameters: {"a"=>"api/v1/form_config"}
E, [2019-06-02T01:03:24.824904 #9202-47136989589960] ERROR -- : No route matches [GET] /api/v1/form_config (ActionController::RoutingError)

I, [2019-06-02T01:03:24.826732 #9202-47136989589960]  INFO -- : Completed 404 Not Found in 7ms (Views: 0.2ms | ActiveRecord: 3.1ms)

Error: /admin/config.php

I, [2019-06-09T01:15:02.677544 #9202-69834828073140]  INFO -- : Started GET "/admin//config.php" for 58.82.133.66 at 2019-06-09 01:15:02 +0200
I, [2019-06-09T01:15:02.685781 #9202-69834828073140]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-09T01:15:02.685846 #9202-69834828073140]  INFO -- :   Parameters: {"a"=>"admin/config"}
E, [2019-06-09T01:15:02.691150 #9202-69834828073140] ERROR -- : No route matches [GET] /admin/config.php (ActionController::RoutingError)

I, [2019-06-09T01:15:02.691986 #9202-69834828073140]  INFO -- :   Rendering inline template
I, [2019-06-09T01:15:02.692849 #9202-69834828073140]  INFO -- :   Rendered inline template (0.8ms)
I, [2019-06-09T01:15:02.694240 #9202-69834828073140]  INFO -- : Completed 404 Not Found in 8ms (Views: 1.4ms | ActiveRecord: 3.2ms)

Error: /HNAP1

I, [2019-06-08T19:56:03.134938 #9202-69834828073140]  INFO -- : Started GET "/HNAP1/" for 60.173.9.126 at 2019-06-08 19:56:03 +0200
I, [2019-06-08T19:56:03.139987 #9202-69834828073140]  INFO -- : Processing by ErrorsController#routing as HTML
I, [2019-06-08T19:56:03.140045 #9202-69834828073140]  INFO -- :   Parameters: {"a"=>"HNAP1"}
E, [2019-06-08T19:56:03.140324 #9202-69834828073140] ERROR -- : No route matches [GET] /HNAP1 (ActionController::RoutingError)

I, [2019-06-08T19:56:03.142660 #9202-69834828073140]  INFO -- :   Rendering inline template
I, [2019-06-08T19:56:03.147769 #9202-69834828073140]  INFO -- :   Rendered inline template (5.0ms)
I, [2019-06-08T19:56:03.149127 #9202-69834828073140]  INFO -- : Completed 404 Not Found in 9ms (Views: 5.4ms | ActiveRecord: 0.4ms)

Error: /app/.env

I, [2019-06-06T00:28:17.755054 #9202-47136987738580]  INFO -- : Started GET "/app/.env" for 71.243.234.3 at 2019-06-06 00:28:17 +0200
I, [2019-06-06T00:28:17.762772 #9202-47136987738580]  INFO -- : Processing by ErrorsController#routing as 
I, [2019-06-06T00:28:17.762838 #9202-47136987738580]  INFO -- :   Parameters: {"a"=>"app/"}
E, [2019-06-06T00:28:17.768158 #9202-47136987738580] ERROR -- : No route matches [GET] /app/.env (ActionController::RoutingError)

I, [2019-06-06T00:28:17.786012 #9202-47136987738580]  INFO -- :   Rendering inline template
I, [2019-06-06T00:28:17.798494 #9202-47136987738580]  INFO -- :   Rendered inline template (12.3ms)
I, [2019-06-06T00:28:17.800305 #9202-47136987738580]  INFO -- : Completed 404 Not Found in 37ms (Views: 13.0ms | ActiveRecord: 3.2ms)

Error: /sitemap.xml

I, [2019-06-04T17:03:03.899183 #9202-47136993764960]  INFO -- : Started GET "/sitemap.xml" for 71.6.199.23 at 2019-06-04 17:03:03 +0200
I, [2019-06-04T17:03:03.903649 #9202-47136993764960]  INFO -- : Processing by ErrorsController#routing as XML
I, [2019-06-04T17:03:03.903767 #9202-47136993764960]  INFO -- :   Parameters: {"a"=>"sitemap"}
E, [2019-06-04T17:03:03.910381 #9202-47136993764960] ERROR -- : No route matches [GET] /sitemap.xml (ActionController::RoutingError)

I, [2019-06-04T17:03:03.913657 #9202-47136993764960]  INFO -- :   Rendering inline template
I, [2019-06-04T17:03:03.914956 #9202-47136993764960]  INFO -- :   Rendered inline template (1.2ms)
I, [2019-06-04T17:03:03.916457 #9202-47136993764960]  INFO -- : Completed 404 Not Found in 13ms (Views: 1.7ms | ActiveRecord: 3.5ms)

Error: /.well-known/security.txt

I, [2019-06-04T19:11:11.662098 #9202-69834867356420]  INFO -- : Started GET "/.well-known/security.txt" for 198.20.87.98 at 2019-06-04 19:11:11 +0200
I, [2019-06-04T19:11:11.664624 #9202-69834867356420]  INFO -- : Processing by ErrorsController#routing as TEXT
I, [2019-06-04T19:11:11.664680 #9202-69834867356420]  INFO -- :   Parameters: {"a"=>".well-known/security"}
E, [2019-06-04T19:11:11.669621 #9202-69834867356420] ERROR -- : No route matches [GET] /.well-known/security.txt (ActionController::RoutingError)

I, [2019-06-04T19:11:11.670085 #9202-69834867356420]  INFO -- :   Rendering inline template
I, [2019-06-04T19:11:11.672975 #9202-69834867356420]  INFO -- :   Rendered inline template (2.8ms)
I, [2019-06-04T19:11:11.674511 #9202-69834867356420]  INFO -- : Completed 404 Not Found in 10ms (Views: 3.2ms | ActiveRecord: 3.0ms)

MrGeneration · June 14, 2019, 2:31pm

Well the only Zammad-relevant Part is /api/v1/form_config.
This is an existing endpoint that expects “POST”-requests and thus, for OPTION and GET it will throw errors. Are you using the form? If not, disable it and you’ll be fine.

Generally I can’t give you suggestions on securing your system (sorry, that’s your part don’t want to be the bad guy giving wrong advices )

Normally you don’t want such probing on your systems and I’m sure you can at least temporarily ban them. Of course you could also work on VPN which will hide Zammad completely and only allow access to legimate persons - at least if that’s working out for you.

Edit: In the end this shouldn’t affect the performance of Zammad (except if they keep boming you with requests)

system · October 12, 2019, 2:31pm

This topic was automatically closed 120 days after the last reply. New replies are no longer allowed.