Hey Thorsten,
than enjoy your vacation, we have no rush here =).
I have seen some progress here:
But it also failed:
zammad-scheduler_1 | I, [2020-03-23T16:31:05.075792 #1-46961640138160] INFO -- : thread#-: PARAMS: {:Subaction=>"Export", :Object=>"Priority", :Limit=>"", :Offset=>"", :Diff=>0, :Action=>"ZammadMigrator", :Key=>"placeholder2"}
zammad-scheduler_1 | I, [2020-03-23T16:31:05.215382 #1-46961640138160] INFO -- : thread#-: update Ticket::Priority.find_by(id: 1)
zammad-scheduler_1 | I, [2020-03-23T16:31:05.234932 #1-46961640138160] INFO -- : thread#-: update Ticket::Priority.find_by(id: 2)
zammad-scheduler_1 | I, [2020-03-23T16:31:05.252227 #1-46961640138160] INFO -- : thread#-: update Ticket::Priority.find_by(id: 3)
zammad-scheduler_1 | I, [2020-03-23T16:31:05.269305 #1-46961640138160] INFO -- : thread#-: add Ticket::Priority.find_by(id: 4)
zammad-scheduler_1 | I, [2020-03-23T16:31:05.306517 #1-46961640138160] INFO -- : thread#-: add Ticket::Priority.find_by(id: 5)
zammad-scheduler_1 | I, [2020-03-23T16:31:05.347776 #1-46961640138160] INFO -- : thread#-: loading Queue...
zammad-scheduler_1 | I, [2020-03-23T16:31:05.348031 #1-46961640138160] INFO -- : thread#-: POST: https://otrs.placeholder.de/otrs/public.pl?Action=ZammadMigrator
zammad-scheduler_1 | I, [2020-03-23T16:31:05.348246 #1-46961640138160] INFO -- : thread#-: PARAMS: {:Subaction=>"Export", :Object=>"Queue", :Limit=>"", :Offset=>"", :Diff=>0, :Action=>"ZammadMigrator", :Key=>"placeholder2"}
zammad-scheduler_1 | I, [2020-03-23T16:31:05.390179 #1-46961640138160] INFO -- : thread#-: ERROR: Server Error: #<Net::HTTPInternalServerError 500 Internal Server Error readbody=true>!
zammad-scheduler_1 | I, [2020-03-23T16:31:08.976053 #1-46961635799780] INFO -- : execute Channel.fetch (try_count 0)...
zammad-scheduler_1 | I, [2020-03-23T16:31:08.981814 #1-46961635799780] INFO -- : ended Channel.fetch took: 0.021406112 seconds.
zammad-scheduler_1 | I, [2020-03-23T16:31:28.780097 #1-46961636816660] INFO -- : execute Import::OTRS.diff_worker (try_count 0)...
zammad-scheduler_1 | I, [2020-03-23T16:31:28.780909 #1-46961636816660] INFO -- : thread#-: Start diff...
zammad-scheduler_1 | I, [2020-03-23T16:31:28.781059 #1-46961636816660] INFO -- : thread#-: loading State...
zammad-scheduler_1 | I, [2020-03-23T16:31:28.781254 #1-46961636816660] INFO -- : thread#-: POST: https://otrs.placeholder.de/otrs/public.pl?Action=ZammadMigrator
zammad-scheduler_1 | I, [2020-03-23T16:31:28.781364 #1-46961636816660] INFO -- : thread#-: PARAMS: {:Subaction=>"Export", :Object=>"State", :Limit=>"", :Offset=>"", :Diff=>0, :Action=>"ZammadMigrator", :Key=>"placeholder2"}
zammad-scheduler_1 | I, [2020-03-23T16:31:28.840790 #1-46961636816660] INFO -- : thread#-: ERROR: Server Error: #<Net::HTTPInternalServerError 500 Internal Server Error readbody=true>!
zammad-scheduler_1 | I, [2020-03-23T16:31:35.390579 #1-46961640138160] INFO -- : thread#-: POST: https://otrs.placeholder.de/otrs/public.pl?Action=ZammadMigrator
zammad-scheduler_1 | I, [2020-03-23T16:31:35.390754 #1-46961640138160] INFO -- : thread#-: PARAMS: {:Subaction=>"Export", :Object=>"Queue", :Limit=>"", :Offset=>"", :Diff=>0, :Action=>"ZammadMigrator", :Key=>"placeholder2"}
zammad-scheduler_1 | I, [2020-03-23T16:31:35.455759 #1-46961640138160] INFO -- : thread#-: ERROR: Server Error: #<Net::HTTPInternalServerError 500 Internal Server Error readbody=true>!
zammad-scheduler_1 | I, [2020-03-23T16:31:38.762412 #1-46961583949240] INFO -- : Scheduler running...
zammad-scheduler_1 | I, [2020-03-23T16:31:38.775594 #1-46961583949240] INFO -- : Running job thread for 'Check streams for Channel' (Channel.stream) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:31:38.775783 #1-46961583949240] INFO -- : Running job thread for 'Generate Session data' (Sessions.jobs) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:31:38.776102 #1-46961583949240] INFO -- : Running job thread for 'Process escalation tickets' (Ticket.process_escalation) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:31:38.776239 #1-46961583949240] INFO -- : Running job thread for 'Check Channels' (Channel.fetch) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:31:38.776369 #1-46961583949240] INFO -- : Running job thread for 'Import OTRS diff load' (Import::OTRS.diff_worker) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:31:38.777949 #1-46961583949240] INFO -- : Running job thread for 'Execute jobs' (Job.run) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:31:39.009452 #1-46961635799780] INFO -- : execute Channel.fetch (try_count 0)...
zammad-scheduler_1 | I, [2020-03-23T16:31:39.010613 #1-46961635799780] INFO -- : ended Channel.fetch took: 0.025267035 seconds.
zammad-scheduler_1 | I, [2020-03-23T16:31:58.841826 #1-46961636816660] INFO -- : thread#-: POST: https://otrs.placeholder.de/otrs/public.pl?Action=ZammadMigrator
zammad-scheduler_1 | I, [2020-03-23T16:31:58.841998 #1-46961636816660] INFO -- : thread#-: PARAMS: {:Subaction=>"Export", :Object=>"State", :Limit=>"", :Offset=>"", :Diff=>0, :Action=>"ZammadMigrator", :Key=>"placeholder2"}
zammad-scheduler_1 | I, [2020-03-23T16:31:58.907020 #1-46961636816660] INFO -- : thread#-: ERROR: Server Error: #<Net::HTTPInternalServerError 500 Internal Server Error readbody=true>!
zammad-scheduler_1 | I, [2020-03-23T16:32:09.045147 #1-46961635799780] INFO -- : execute Channel.fetch (try_count 0)...
zammad-scheduler_1 | I, [2020-03-23T16:32:09.046633 #1-46961635799780] INFO -- : ended Channel.fetch took: 0.034460096 seconds.
zammad-scheduler_1 | I, [2020-03-23T16:32:20.456287 #1-46961640138160] INFO -- : thread#-: POST: https://otrs.placeholder.de/otrs/public.pl?Action=ZammadMigrator
zammad-scheduler_1 | I, [2020-03-23T16:32:20.456361 #1-46961640138160] INFO -- : thread#-: PARAMS: {:Subaction=>"Export", :Object=>"Queue", :Limit=>"", :Offset=>"", :Diff=>0, :Action=>"ZammadMigrator", :Key=>"placeholder2"}
zammad-scheduler_1 | I, [2020-03-23T16:32:20.492529 #1-46961640138160] INFO -- : thread#-: ERROR: Server Error: #<Net::HTTPInternalServerError 500 Internal Server Error readbody=true>!
zammad-scheduler_1 | E, [2020-03-23T16:32:20.492689 #1-46961640138160] ERROR -- : Zammad Migrator returned an error (RuntimeError)
zammad-scheduler_1 | /opt/zammad/lib/import/otrs/requester.rb:130:in `post'
zammad-scheduler_1 | /opt/zammad/lib/import/otrs/requester.rb:90:in `request_json'
zammad-scheduler_1 | /opt/zammad/lib/import/otrs/requester.rb:77:in `request_result'
zammad-scheduler_1 | /opt/zammad/lib/import/otrs/requester.rb:32:in `load'
zammad-scheduler_1 | /opt/zammad/lib/import/otrs.rb:141:in `import_action'
zammad-scheduler_1 | /opt/zammad/lib/import/otrs.rb:71:in `import'
zammad-scheduler_1 | /opt/zammad/lib/import/otrs.rb:162:in `updateable_objects'
zammad-scheduler_1 | /opt/zammad/lib/import/otrs.rb:41:in `start'
zammad-scheduler_1 | /opt/zammad/lib/import/otrs/async.rb:27:in `start_bg'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/performable_method.rb:26:in `perform'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/backend/base.rb:81:in `block in invoke_job'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/lifecycle.rb:61:in `block in initialize'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/lifecycle.rb:66:in `execute'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/lifecycle.rb:40:in `run_callbacks'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/backend/base.rb:78:in `invoke_job'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/worker.rb:230:in `block (2 levels) in run'
zammad-scheduler_1 | /usr/local/lib/ruby/2.5.0/timeout.rb:93:in `block in timeout'
zammad-scheduler_1 | /usr/local/lib/ruby/2.5.0/timeout.rb:103:in `timeout'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/worker.rb:230:in `block in run'
zammad-scheduler_1 | /usr/local/lib/ruby/2.5.0/benchmark.rb:308:in `realtime'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/worker.rb:229:in `run'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/worker.rb:312:in `block in reserve_and_run_one_job'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/lifecycle.rb:61:in `block in initialize'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/lifecycle.rb:66:in `execute'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/lifecycle.rb:40:in `run_callbacks'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/worker.rb:312:in `reserve_and_run_one_job'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/worker.rb:213:in `block in work_off'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/worker.rb:212:in `times'
zammad-scheduler_1 | /usr/local/bundle/gems/delayed_job-4.1.7/lib/delayed/worker.rb:212:in `work_off'
zammad-scheduler_1 | /opt/zammad/app/models/scheduler.rb:372:in `block (3 levels) in worker'
zammad-scheduler_1 | /usr/local/lib/ruby/2.5.0/benchmark.rb:308:in `realtime'
zammad-scheduler_1 | /opt/zammad/app/models/scheduler.rb:370:in `block (2 levels) in worker'
zammad-scheduler_1 | /opt/zammad/app/models/scheduler.rb:366:in `loop'
zammad-scheduler_1 | /opt/zammad/app/models/scheduler.rb:366:in `block in worker'
zammad-scheduler_1 | /usr/local/bundle/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context'
zammad-scheduler_1 | I, [2020-03-23T16:32:20.497912 #1-46961640138160] INFO -- : 2020-03-23T16:32:20+0000: [Worker(host:6bbd14f527a7 pid:1)] Job Module#start_bg (id=15) COMPLETED after 183.4946
zammad-scheduler_1 | I, [2020-03-23T16:32:38.778607 #1-46961583949240] INFO -- : Scheduler running...
zammad-scheduler_1 | I, [2020-03-23T16:32:38.792747 #1-46961583949240] INFO -- : Running job thread for 'Check streams for Channel' (Channel.stream) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:32:38.792880 #1-46961583949240] INFO -- : Running job thread for 'Generate Session data' (Sessions.jobs) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:32:38.793133 #1-46961583949240] INFO -- : Running job thread for 'Process escalation tickets' (Ticket.process_escalation) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:32:38.793285 #1-46961583949240] INFO -- : Running job thread for 'Import OTRS diff load' (Import::OTRS.diff_worker) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:32:38.793400 #1-46961583949240] INFO -- : Running job thread for 'Check Channels' (Channel.fetch) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:32:38.794450 #1-46961583949240] INFO -- : Running job thread for 'Execute jobs' (Job.run) status is: sleep
zammad-scheduler_1 | I, [2020-03-23T16:32:39.068659 #1-46961635799780] INFO -- : execute Channel.fetch (try_count 0)...
zammad-scheduler_1 | I, [2020-03-23T16:32:39.071113 #1-46961635799780] INFO -- : ended Channel.fetch took: 0.022331151 seconds.
zammad-scheduler_1 | I, [2020-03-23T16:32:43.907434 #1-46961636816660] INFO -- : thread#-: POST: https://otrs.placeholder.de/otrs/public.pl?Action=ZammadMigrator
zammad-scheduler_1 | I, [2020-03-23T16:32:43.907531 #1-46961636816660] INFO -- : thread#-: PARAMS: {:Subaction=>"Export", :Object=>"State", :Limit=>"", :Offset=>"", :Diff=>0, :Action=>"ZammadMigrator", :Key=>"placeholder2"}
zammad-scheduler_1 | I, [2020-03-23T16:32:43.934325 #1-46961636816660] INFO -- : thread#-: ERROR: Server Error: #<Net::HTTPInternalServerError 500 Internal Server Error readbody=true>!
zammad-scheduler_1 | E, [2020-03-23T16:32:43.934492 #1-46961636816660] ERROR -- : execute Import::OTRS.diff_worker (try_count 0) exited with error #<RuntimeError: Zammad Migrator returned an error> in: 75.165112606 seconds.
zammad-scheduler_1 | I, [2020-03-23T16:32:44.987706 #1-46961636816660] INFO -- : execute Import::OTRS.diff_worker (try_count 1)...
zammad-scheduler_1 | I, [2020-03-23T16:32:44.988075 #1-46961636816660] INFO -- : thread#-: Start diff...
zammad-scheduler_1 | I, [2020-03-23T16:32:44.988194 #1-46961636816660] INFO -- : thread#-: loading State...
zammad-scheduler_1 | I, [2020-03-23T16:32:44.988399 #1-46961636816660] INFO -- : thread#-: POST: https://otrs.placeholder.de/otrs/public.pl?Action=ZammadMigrator
zammad-scheduler_1 | I, [2020-03-23T16:32:44.988526 #1-46961636816660] INFO -- : thread#-: PARAMS: {:Subaction=>"Export", :Object=>"State", :Limit=>"", :Offset=>"", :Diff=>0, :Action=>"ZammadMigrator", :Key=>"placeholder2"}
zammad-scheduler_1 | I, [2020-03-23T16:32:45.051956 #1-46961636816660] INFO -- : thread#-: ERROR: Server Error: #<Net::HTTPInternalServerError 500 Internal Server Error readbody=true>!
On top of my screenshot now is written: Zammad Migrator returned an error
.
What I see in OTRS log is only
[Mon Mar 23 17:29:12 2020][Error][Kernel::System::CommunicationLog::_LogError][538] CommunicationLog(ID:2254297,AccountType:-,AccountID:-,Direction:Incoming,Transport:Email,ObjectLogType:Connection,ObjectLogID:2272198)::Kernel::System::MailAccount::IMAPTLS => IMAPTLS: Can't connect to mail.placeholder.de: 2 NO [AUTHENTICATIONFAILED] Authentication failed.
Any more hint how to get more debugging output?
Have a nice day,
Bastian