Creating ticket with attachment fails

Technical assistance
1

Infos:

Important:
If you are a Zammad Support or hosted customer and experience a technical issue, please refer to: support@zammad.com using your zammad-hostname / or company contract.

  • Used Zammad version: 2.8.x
  • Used Zammad installation source: package
  • Operating system: Ubuntu 16.04
  • Browser + version: Chrome 71.0.3578.80

Expected behavior:

  • Ticket is created with attachment

Actual behavior:

  • Get response 500 - Internal Server Error
    “error”:“undefined method `each_with_index’ for #\u003cActionController::Parameters:0x000055658914e620\u003e”

Steps to reproduce the behavior:

  • Send API request to Zammad server wth ticket info in JSON format including fake attachment:
function CallServer(attachments) {
	var	theTicket = {};
	theTicket["title"] = $("#ticketTitle").val();
	theTicket["group"] = "Help Desk";

	var theArticle = {};
	theArticle["subject"] = "פניה";
	theArticle["body"] = $("#ticketBody").val();
	theArticle["type"] = "note";
	theArticle["internal"] = false;

	var firstAttachment = {};
	firstAttachment["filename"] = "filename.txt"; //fileInput.files[0].name;
	firstAttachment["data"] = "content in base64";
	firstAttachment["mime-type"] = "text/plain";

	var theAttachments = {};
	theAttachments[0] = firstAttachment;

	theTicket["article"] = theArticle;
	theTicket["customer"] = "customer";
	theTicket["priority"] = $("#ticketPriority").val();
	theTicket["category"] = $("#ticketCategory").val();
	theTicket["note"] = "some note";

    $.ajax({
        beforeSend: function (xhr) {
            xhr.setRequestHeader("Authorization", "Basic " + btoa("username:password"));
        },
        type: "POST",
        url: "http://server/api/v1/tickets",
        dataType: "json",
        data: theTicket,
        success: function (data) {
            alert("created");
        },
        error: function (errMsg) {
            alert(errMsg.responseJSON.error);
        }
    });

    return false;
}
2

Please provide the whole script (it feels like stuff is missing, maybe the script language would be good to know too or the used wrapper).

Also, please provide your production.log. It’s enough for us to see the moments when you try to run your script.

3

I am working with JavaScript and JQuery.

Below is the:

  • HTML for a small test
  • JavaScript for the test
  • Production.log from when the test was run

When I comment out the addition of the attachment to the article in the JSON I am sending the ticket is created. When I have the attachment I get the error and a ticket is created but without the article (or the attachment).

Thank you for your help.

HTML:

<!DOCTYPE html>

<html id="" class="no-js" dir="rtl" lang="he">
<head>

	<meta http-equiv="X-UA-Compatible" content="IE=edge">

	<meta http-equiv="content-type" content="text/html; charset=UTF-8">

    <meta charset="utf-8">


    <script src="https://ajax.googleapis.com/ajax/libs/jquery/1.12.4/jquery.min.js"></script>

    <script src="https://ajax.googleapis.com/ajax/libs/jqueryui/1.12.1/jquery-ui.min.js"></script>

    <script src="Test.js"></script>

</head>

<body>

	<form id="open-ticket" onsubmit="OpenTicket();">

		<button type="submit" value="Submit">Open Ticket</button>

	</form>

</body>

</html>

JavaScript:

// Prevent Default handling of form submission as we want to stay on the same page
$(document).bind("submit", function (e) {
    e.preventDefault();
    console.log("submit intercepted");
});

function OpenTicket() {
	var	theTicket = {};
	theTicket["title"] = "Ticket Title";
	theTicket["group"] = "Help Desk";

	var theArticle = {};
	theArticle["subject"] = "Article Title";
	theArticle["body"] = "Note Body";
	theArticle["type"] = "note";
	theArticle["internal"] = false;

	var firstAttachment = {};
	firstAttachment["filename"] = "filename.txt";
	firstAttachment["data"] = "content in base64";
	firstAttachment["mime-type"] = "text/plain";

	var theAttachments = {};
	theAttachments[0] = firstAttachment;
	theArticle["attachments"] = theAttachments;  //comment out this line and it works.

	theTicket["article"] = theArticle;
	theTicket["customer"] = "shimons@most.gov.il";
	theTicket["note"] = "some note";

    $.ajax({
        beforeSend: function (xhr) {
            xhr.setRequestHeader("Authorization", "Token token=<myToken>");
        },
        type: "POST",
        url: "http://server/api/v1/tickets",
        dataType: "json",
        data: theTicket,
        success: function (data) {
			alert("Ticket Opened!");
        },
        error: function (errMsg) {
            alert(errMsg.responseJSON.error);
        }
    });

    return false;
}

Production.log:

I, [2018-12-17T12:28:49.410599 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:28:49.419312 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:49+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63855) COMPLETED after 0.1125
I, [2018-12-17T12:28:49.427040 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:49+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63858) RUNNING
I, [2018-12-17T12:28:49.519654 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/User/5" \
I, [2018-12-17T12:28:49.533523 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:28:49.538077 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:49+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63858) COMPLETED after 0.1108
I, [2018-12-17T12:28:49.544250 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:49+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63859) RUNNING
I, [2018-12-17T12:28:49.627109 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/Organization/2" \
I, [2018-12-17T12:28:49.641772 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:28:49.645937 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:49+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63859) COMPLETED after 0.1015
I, [2018-12-17T12:28:55.064904 #2755-47290443435800]  INFO -- : execute Ticket.process_escalation (try_count 0)...
I, [2018-12-17T12:28:57.728766 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:57+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63861) RUNNING
I, [2018-12-17T12:28:57.829663 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/Ticket/46" \
I, [2018-12-17T12:28:57.960177 #2755-47290442799460]  INFO -- : # 201
I, [2018-12-17T12:28:57.968658 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:57+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63861) COMPLETED after 0.2397
I, [2018-12-17T12:28:57.974093 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:57+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63862) RUNNING
I, [2018-12-17T12:28:58.076056 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/User/5" \
I, [2018-12-17T12:28:58.116585 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:28:58.120147 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:58+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63862) COMPLETED after 0.1459
I, [2018-12-17T12:28:58.127065 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:58+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63863) RUNNING
I, [2018-12-17T12:28:58.293062 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/Organization/2" \
I, [2018-12-17T12:28:58.313648 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:28:58.317739 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:58+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63863) COMPLETED after 0.1905
I, [2018-12-17T12:28:58.321273 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:58+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63868) RUNNING
I, [2018-12-17T12:28:58.405354 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/User/5" \
I, [2018-12-17T12:28:58.426291 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:28:58.430362 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:58+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63868) COMPLETED after 0.1089
I, [2018-12-17T12:28:58.434466 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:58+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63869) RUNNING
I, [2018-12-17T12:28:58.486103 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/Organization/2" \
I, [2018-12-17T12:28:58.499142 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:28:58.505048 #2755-47290442799460]  INFO -- : 2018-12-17T12:28:58+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63869) COMPLETED after 0.0704
I, [2018-12-17T12:28:59.524276 #2755-70139402579020]  INFO -- : execute Channel.fetch (try_count 0)...
I, [2018-12-17T12:29:08.773654 #2755-70139668208660]  INFO -- : execute Job.run (try_count 0)...
I, [2018-12-17T12:29:10.017725 #2764-46947288246080]  INFO -- : Started POST "/api/v1/tickets" for 127.0.0.1 at 2018-12-17 12:29:10 +0200
I, [2018-12-17T12:29:10.038141 #2764-46947288246080]  INFO -- : Processing by TicketsController#create as JSON
I, [2018-12-17T12:29:10.038298 #2764-46947288246080]  INFO -- :   Parameters: {"title"=>"Ticket Title", "group"=>"Help Desk", "article"=>{"subject"=>"Article Title", "body"=>"Note Body", "type"=>"note", "internal"=>"false", "attachments"=>{"0"=>{"filename"=>"filename.txt", "data"=>"content in base64", "mime-type"=>"text/plain"}}}, "customer"=>"shimons@most.gov.il", "note"=>"some note"}
E, [2018-12-17T12:29:14.162580 #2764-46947288246080] ERROR -- : undefined method `each_with_index' for #<ActionController::Parameters:0x00007f6868dcb8b0> (NoMethodError)
/opt/zammad/app/controllers/concerns/creates_ticket_articles.rb:82:in `article_create'
/opt/zammad/app/controllers/tickets_controller.rb:166:in `block in create'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/locking/pessimistic.rb:81:in `block in with_lock'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/connection_adapters/abstract/database_statements.rb:235:in `block in transaction'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/connection_adapters/abstract/transaction.rb:194:in `block in within_new_transaction'
/opt/zammad/vendor/ruby-2.4.4/lib/ruby/2.4.0/monitor.rb:214:in `mon_synchronize'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/connection_adapters/abstract/transaction.rb:191:in `within_new_transaction'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/connection_adapters/abstract/database_statements.rb:235:in `transaction'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/transactions.rb:210:in `transaction'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/transactions.rb:299:in `transaction'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/locking/pessimistic.rb:79:in `with_lock'
/opt/zammad/app/controllers/tickets_controller.rb:154:in `create'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_controller/metal/basic_implicit_render.rb:4:in `send_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/abstract_controller/base.rb:186:in `process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_controller/metal/rendering.rb:30:in `process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/abstract_controller/callbacks.rb:20:in `block in process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/callbacks.rb:131:in `run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/abstract_controller/callbacks.rb:19:in `process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_controller/metal/rescue.rb:20:in `process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_controller/metal/instrumentation.rb:32:in `block in process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/notifications.rb:166:in `block in instrument'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/notifications/instrumenter.rb:21:in `instrument'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/notifications.rb:166:in `instrument'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_controller/metal/instrumentation.rb:30:in `process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_controller/metal/params_wrapper.rb:252:in `process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activerecord-5.1.6.1/lib/active_record/railties/controller_runtime.rb:22:in `process_action'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/abstract_controller/base.rb:124:in `process'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionview-5.1.6.1/lib/action_view/rendering.rb:30:in `process'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_controller/metal.rb:189:in `dispatch'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_controller/metal.rb:253:in `dispatch'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/routing/route_set.rb:49:in `dispatch'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/routing/route_set.rb:31:in `serve'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/journey/router.rb:50:in `block in serve'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/journey/router.rb:33:in `each'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/journey/router.rb:33:in `serve'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/routing/route_set.rb:844:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:190:in `call!'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/strategy.rb:168:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/omniauth-1.7.1/lib/omniauth/builder.rb:63:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/etag.rb:25:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/conditional_get.rb:38:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/head.rb:12:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/session/abstract/id.rb:232:in `context'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/session/abstract/id.rb:226:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/cookies.rb:613:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/callbacks.rb:26:in `block in call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/callbacks.rb:97:in `run_callbacks'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/callbacks.rb:24:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/debug_exceptions.rb:59:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/show_exceptions.rb:31:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:36:in `call_app'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:24:in `block in call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:69:in `block in tagged'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:26:in `tagged'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/tagged_logging.rb:69:in `tagged'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/rack/logger.rb:24:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/remote_ip.rb:79:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/request_id.rb:25:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/method_override.rb:22:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/runtime.rb:22:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/activesupport-5.1.6.1/lib/active_support/cache/strategy/local_cache_middleware.rb:27:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/executor.rb:12:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/actionpack-5.1.6.1/lib/action_dispatch/middleware/static.rb:125:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/rack-2.0.6/lib/rack/sendfile.rb:111:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/railties-5.1.6.1/lib/rails/engine.rb:522:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/configuration.rb:225:in `call'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:624:in `handle_request'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:438:in `process_client'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/server.rb:302:in `block in run'
/opt/zammad/vendor/bundle/ruby/2.4.0/gems/puma-3.11.0/lib/puma/thread_pool.rb:120:in `block in spawn_thread'
/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'
I, [2018-12-17T12:29:14.240352 #2764-46947288246080]  INFO -- : Completed 500 Internal Server Error in 4202ms (Views: 0.4ms | ActiveRecord: 3091.3ms)
I, [2018-12-17T12:29:14.611342 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:14+0200: [Worker(host:Zammad pid:2755)] Job Observer::UserDeviceLogJob (id=63887) RUNNING
I, [2018-12-17T12:29:14.920109 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:14+0200: [Worker(host:Zammad pid:2755)] Job Observer::UserDeviceLogJob (id=63887) COMPLETED after 0.3086
I, [2018-12-17T12:29:14.926443 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:14+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=62870) RUNNING
I, [2018-12-17T12:29:15.030524 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/StatsStore/1" \
I, [2018-12-17T12:29:15.072262 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:29:15.077882 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:15+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=62870) COMPLETED after 0.1511
I, [2018-12-17T12:29:15.114138 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:15+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=62871) RUNNING
I, [2018-12-17T12:29:15.240416 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/StatsStore/2" \
I, [2018-12-17T12:29:15.269670 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:29:15.273512 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:15+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=62871) COMPLETED after 0.1592
I, [2018-12-17T12:29:15.277612 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:15+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=62872) RUNNING
I, [2018-12-17T12:29:15.421362 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/StatsStore/4" \
I, [2018-12-17T12:29:16.616364 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:29:16.627538 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:16+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=62872) COMPLETED after 1.3497
I, [2018-12-17T12:29:16.631684 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:16+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63888) RUNNING
I, [2018-12-17T12:29:16.859173 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/Ticket/47" \
I, [2018-12-17T12:29:16.893759 #2755-47290442799460]  INFO -- : # 201
I, [2018-12-17T12:29:16.897072 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:16+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63888) COMPLETED after 0.2652
I, [2018-12-17T12:29:16.900844 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:16+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63889) RUNNING
I, [2018-12-17T12:29:17.009021 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/User/5" \
I, [2018-12-17T12:29:17.055278 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:29:17.057481 #2764-46947275981140]  INFO -- : Started GET "/api/v1/ticket_customer?customer_id=5&_=1544957250252" for 127.0.0.1 at 2018-12-17 12:29:17 +0200
I, [2018-12-17T12:29:17.060891 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:17+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63889) COMPLETED after 0.1599
I, [2018-12-17T12:29:17.078944 #2764-46947275028020]  INFO -- : Started GET "/api/v1/ticket_customer?customer_id=5&_=1545042413535" for 127.0.0.1 at 2018-12-17 12:29:17 +0200
I, [2018-12-17T12:29:17.083340 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:17+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63890) RUNNING
I, [2018-12-17T12:29:17.123720 #2764-46947275028020]  INFO -- : Processing by TicketsController#ticket_customer as JSON
I, [2018-12-17T12:29:17.124309 #2764-46947275028020]  INFO -- :   Parameters: {"customer_id"=>"5", "_"=>"1545042413535"}
I, [2018-12-17T12:29:17.212110 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/Organization/2" \
I, [2018-12-17T12:29:17.240627 #2764-46947275028020]  INFO -- : Completed 200 OK in 116ms (Views: 24.1ms | ActiveRecord: 27.7ms)
I, [2018-12-17T12:29:17.279522 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:29:17.280718 #2764-46947275981140]  INFO -- : Processing by TicketsController#ticket_customer as JSON
I, [2018-12-17T12:29:17.280823 #2764-46947275981140]  INFO -- :   Parameters: {"customer_id"=>"5", "_"=>"1544957250252"}
I, [2018-12-17T12:29:17.301375 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:17+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63890) COMPLETED after 0.2178
I, [2018-12-17T12:29:17.304936 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:17+0200: [Worker(host:Zammad pid:2755)] Job Observer::Ticket::UserTicketCounter::BackgroundJob (id=63891) RUNNING
I, [2018-12-17T12:29:17.899838 #2764-46947275981140]  INFO -- : Completed 200 OK in 619ms (Views: 404.7ms | ActiveRecord: 43.7ms)
I, [2018-12-17T12:29:19.349941 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:19+0200: [Worker(host:Zammad pid:2755)] Job Observer::Ticket::UserTicketCounter::BackgroundJob (id=63891) COMPLETED after 2.0448
I, [2018-12-17T12:29:19.356154 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:19+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63895) RUNNING
I, [2018-12-17T12:29:19.691080 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/User/5" \
I, [2018-12-17T12:29:19.780488 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:29:19.802278 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:19+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63895) COMPLETED after 0.4458
I, [2018-12-17T12:29:19.806695 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:19+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63896) RUNNING
I, [2018-12-17T12:29:20.078013 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/Organization/2" \
I, [2018-12-17T12:29:20.109922 #2755-47290442799460]  INFO -- : # 200
I, [2018-12-17T12:29:20.124801 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:20+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63896) COMPLETED after 0.3151
I, [2018-12-17T12:29:20.536645 #2764-46947284693060]  INFO -- : Started GET "/api/v1/users/5?full=true&_=1545042413536" for 127.0.0.1 at 2018-12-17 12:29:20 +0200
I, [2018-12-17T12:29:20.542303 #2764-46947284275420]  INFO -- : Started GET "/api/v1/organizations/2?full=true&_=1545042413537" for 127.0.0.1 at 2018-12-17 12:29:20 +0200
I, [2018-12-17T12:29:20.555957 #2764-46947284275420]  INFO -- : Processing by OrganizationsController#show as JSON
I, [2018-12-17T12:29:20.556088 #2764-46947284275420]  INFO -- :   Parameters: {"full"=>"true", "_"=>"1545042413537", "id"=>"2"}
I, [2018-12-17T12:29:20.577118 #2764-46947288246080]  INFO -- : Started GET "/api/v1/ticket_customer?customer_id=5&_=1545042413538" for 127.0.0.1 at 2018-12-17 12:29:20 +0200
I, [2018-12-17T12:29:20.591962 #2764-46947288246080]  INFO -- : Processing by TicketsController#ticket_customer as JSON
I, [2018-12-17T12:29:20.592150 #2764-46947288246080]  INFO -- :   Parameters: {"customer_id"=>"5", "_"=>"1545042413538"}
I, [2018-12-17T12:29:21.325787 #2764-46947284275420]  INFO -- : Completed 200 OK in 769ms (Views: 58.5ms | ActiveRecord: 554.7ms)
I, [2018-12-17T12:29:21.388606 #2764-46947284693060]  INFO -- : Processing by UsersController#show as JSON
I, [2018-12-17T12:29:21.404918 #2764-46947284693060]  INFO -- :   Parameters: {"full"=>"true", "_"=>"1545042413536", "id"=>"5"}
I, [2018-12-17T12:29:21.910717 #2764-46947288246080]  INFO -- : Execute trigger (auto reply (on new tickets)/1) for this object (Ticket:47/Loop:1)
I, [2018-12-17T12:29:21.915285 #2764-46947284693060]  INFO -- : Completed 200 OK in 510ms (Views: 12.3ms | ActiveRecord: 59.8ms)
I, [2018-12-17T12:29:22.263614 #2764-46947288246080]  INFO -- : Unable to send trigger based notification to shimons@most.gov.il because no email address is set for group 'Help Desk'
I, [2018-12-17T12:29:22.290573 #2764-46947288246080]  INFO -- : Completed 200 OK in 1698ms (Views: 54.6ms | ActiveRecord: 754.1ms)
I, [2018-12-17T12:29:24.176440 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:24+0200: [Worker(host:Zammad pid:2755)] Job Transaction::BackgroundJob (id=63897) RUNNING
I, [2018-12-17T12:29:25.521991 #2755-47290442799460]  INFO -- : Send notification to: gideonn@most.gov.il (from:Notification Master <noreply@zammad.most.local>/subject:New Ticket (Ticket Title) [#    ׳§׳¨׳™׳׳”27046])
I, [2018-12-17T12:29:26.277249 #2755-47290442799460]  INFO -- : Send notification to: shimons@most.gov.il (from:Notification Master <noreply@zammad.most.local>/subject:New Ticket (Ticket Title) [#    ׳§׳¨׳™׳׳”27046])
I, [2018-12-17T12:29:26.897340 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:26+0200: [Worker(host:Zammad pid:2755)] Job Transaction::BackgroundJob (id=63897) COMPLETED after 2.7206
I, [2018-12-17T12:29:28.550684 #2764-46947275028020]  INFO -- : Started GET "/api/v1/online_notifications/?full=true&_=1545042413539" for 127.0.0.1 at 2018-12-17 12:29:28 +0200
I, [2018-12-17T12:29:28.567327 #2764-46947275028020]  INFO -- : Processing by OnlineNotificationsController#index as JSON
I, [2018-12-17T12:29:28.567523 #2764-46947275028020]  INFO -- :   Parameters: {"full"=>"true", "_"=>"1545042413539"}
I, [2018-12-17T12:29:28.869127 #2764-46947275028020]  INFO -- : Completed 200 OK in 301ms (Views: 146.4ms | ActiveRecord: 21.7ms)
I, [2018-12-17T12:29:29.549046 #2755-70139402579020]  INFO -- : execute Channel.fetch (try_count 0)...
I, [2018-12-17T12:29:31.217210 #2755-47290412035920]  INFO -- : Scheduler running...
I, [2018-12-17T12:29:31.228275 #2755-47290412035920]  INFO -- : Running job thread for 'Check streams for Channel' (Channel.stream) status is: sleep
I, [2018-12-17T12:29:31.228451 #2755-47290412035920]  INFO -- : Running job thread for 'Check Channels' (Channel.fetch) status is: sleep
I, [2018-12-17T12:29:31.228567 #2755-47290412035920]  INFO -- : Running job thread for 'Import OTRS diff load' (Import::OTRS.diff_worker) status is: sleep
I, [2018-12-17T12:29:31.229043 #2755-47290412035920]  INFO -- : Running job thread for 'Generate Session data' (Sessions.jobs) status is: sleep
I, [2018-12-17T12:29:31.229304 #2755-47290412035920]  INFO -- : Running job thread for 'Process escalation tickets' (Ticket.process_escalation) status is: sleep
I, [2018-12-17T12:29:31.231099 #2755-47290412035920]  INFO -- : Running job thread for 'Execute jobs' (Job.run) status is: sleep
I, [2018-12-17T12:29:55.018103 #2755-47290442799460]  INFO -- : 2018-12-17T12:29:55+0200: [Worker(host:Zammad pid:2755)] Job BackgroundJobSearchIndex (id=63871) RUNNING
I, [2018-12-17T12:29:55.094413 #2755-47290442799460]  INFO -- : # curl -X POST "http://127.0.0.1:9200/zammad_production/StatsStore/1" \
4

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