Verzögerter E-Mail Versand

[FFSSBZ]

Servus,

hab ein Problem mit dem Versand von e-Mails im Zusammenhang mit dem Plugin "Verzögerung".

Ein Bild von der Pipeline ist im Anhang.

Eigentlich soll die erste E-Mail direkt nach Alarmierung versendet werden und die zweite (Mail-Expert) dann 105 Sekunden später

Im Verzögerungsplugin ist eine Zeit von 105 Sekunden eingestellt. Die erste E-Mail die nach 105 Sekunden versendet wird ist die Mail-Expert e-mail, dann nach nochmals 105 Sekunden (zumindest wird mir in den Mails angezeigt das die nochmal 2 Minuten später kommt) wird die erste Mail vom Mail Plugin versendet ... somit also fast 4 Minuten zu spät.

Liegt das an der Konstellation von meine Pipeline oder ist das ein Bug?

Log:
2013-04-14 23:37:45,724 DEBUG (AlarmFMS32Input.java:79) - Eingehende Alarmierung
akzeptiert.
2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:95) - Line: <event>
2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:95) - Line: <name>FE TEST
TEST</name>
2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:95) - Line: <address>12345
</address>
2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:95) - Line: <type>5</type>

2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:95) - Line: <timestamp>136
5975465209</timestamp>
2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:95) - Line: <flags>0</flag
s>
2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:95) - Line: <longdescripti
on>FF TEST TEST</longdescription>
2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:95) - Line: </event>
2013-04-14 23:37:45,740 DEBUG (AlarmFMS32Input.java:121) - Alarm f++r 12345 wird
gesendet
2013-04-14 23:37:45,740 INFO (AlarmPool.java:183) - Neuer eingehender Alarm f++
r "12345"
2013-04-14 23:37:45,740 INFO (AlarmPool.java:461) - Kein Sammelalarm
2013-04-14 23:37:45,740 DEBUG (AlarmPool.java:289) - Alarm hinzugef++gt und Cont
roller aufgeweckt
2013-04-14 23:37:45,740 DEBUG (AlarmPool.java:317) - Alarm-ID wird f++r diesen A
larm nicht erstellt
2013-04-14 23:37:45,771 DEBUG (AlarmFMS32Input.java:126) - Eingehende Alarmierun
g weitergereicht
2013-04-14 23:37:45,802 INFO (PipelineController.java:95) - Pipeline gestartet
f++r 12345
2013-04-14 23:37:45,802 INFO (Pipeline.java:110) - Pipeline von Feuerwehr (123
5) wurde mit AlarmData () gestartet
2013-04-14 23:37:45,818 INFO (PluginController.java:74) - PluginController mit
Plugin customParams_customAlarm.CustomParams_customAlarm (Admin0) mit AlarmData
()wurde gestartet
2013-04-14 23:37:45,818 INFO (CustomParams_customAlarm.java:106) - Key "pluginM
essage" gesetzt auf: Alarmierung f++r TEST TEST TEST
um 23:37:45
am 14.04.2013
2013-04-14 23:37:45,818 INFO (CustomParams_customAlarm.java:111) - Key "subject
" gesetzt auf: TEST Alarmierung TEST TEST TEST
2013-04-14 23:37:45,818 INFO (PluginController.java:135) - PluginController mit
Plugin customParams_customAlarm.CustomParams_customAlarm (Admin0) mit AlarmData
() nach 0 ms beendet
2013-04-14 23:37:45,818 INFO (PluginController.java:74) - PluginController mit
Plugin customParams_customAlarm.CustomParams_customAlarm (Admin2) mit AlarmData
()wurde gestartet
2013-04-14 23:37:45,818 INFO (CustomParams_customAlarm.java:106) - Key "pluginM
essage" gesetzt auf: TEST TEST TEST TEST
2013-04-14 23:37:45,818 INFO (CustomParams_customAlarm.java:111) - Key "subject
" gesetzt auf: TEST TEST TEST - TEST
2013-04-14 23:37:45,818 INFO (PluginController.java:135) - PluginController mit
Plugin customParams_customAlarm.CustomParams_customAlarm (Admin2) mit AlarmData
() nach 0 ms beendet
2013-04-14 23:37:45,912 INFO (PluginController.java:74) - PluginController mit
Plugin delay.Delay (Admin3) mit AlarmData ()wurde gestartet
2013-04-14 23:37:45,912 INFO (Delay.java:26) - Verz+Âgerung gestartet
2013-04-14 23:37:45,912 DEBUG (Delay.java:35) - Verz+Âgere: 105 Sekunden
2013-04-14 23:39:30,912 INFO (Delay.java:41) - Verz+Âgerung beendet
2013-04-14 23:39:30,912 INFO (PluginController.java:135) - PluginController mit
Plugin delay.Delay (Admin3) mit AlarmData () nach 105000 ms beendet
2013-04-14 23:39:31,021 INFO (PluginController.java:74) - PluginController mit
Plugin mail_expert.MailExpert (Admin4) mit AlarmData ()wurde gestartet
2013-04-14 23:39:31,021 DEBUG (PluginController.java:269) - Das GUIElement perso
n-mail ben+Âtigt email-Information der Person
2013-04-14 23:39:31,021 DEBUG (MailExpert.java:96) - ISO-8859-15 Charset eingest
ellt
2013-04-14 23:39:31,021 DEBUG (MailExpert.java:152) - Mail Liste enth+ñlt 1 Eint
r+ñge
2013-04-14 23:39:31,021 DEBUG (MailExpert.java:160) - Empf+ñnger ****
****@googlemail.com als BCC hinzugef++gt
2013-04-14 23:39:31,052 DEBUG (MailExpert.java:220) - Verbinde zu smtp.googlemai
l.com
2013-04-14 23:41:09,021 DEBUG (MailExpert.java:229) - Email-Versand beendet!
2013-04-14 23:41:09,021 INFO (PluginController.java:135) - PluginController mit
Plugin mail_expert.MailExpert (Admin4) mit AlarmData () nach 98000 ms beendet
2013-04-14 23:41:09,240 INFO (PluginController.java:74) - PluginController mit
Plugin mail.Mail (Admin1) mit AlarmData ()wurde gestartet
2013-04-14 23:41:09,240 DEBUG (PluginController.java:269) - Das GUIElement email
ben+Âtigt email-Information der Person
2013-04-14 23:41:09,240 DEBUG (Mail.java:55) - Absender: fschoellkrippen@googlem
ail.com
2013-04-14 23:41:09,240 DEBUG (Mail.java:56) - Mail-Einstellungen geladen
2013-04-14 23:41:09,240 DEBUG (Mail.java:101) - Mail Liste enth+ñlt 1 Eintr+ñge
2013-04-14 23:41:09,240 DEBUG (Mail.java:110) - Empf+ñnger ****@go
oglemail.com als TO hinzugef++gt
2013-04-14 23:41:09,240 DEBUG (Mail.java:127) - Betreff: TEST TEST TEST TEST
2013-04-14 23:41:09,240 DEBUG (Mail.java:136) - Sende an: [Ljavax.mail.internet.
InternetAddress;@5af885
2013-04-14 23:41:09,240 DEBUG (Mail.java:140) - Verbinde zu smtp.googlemail.com
2013-04-14 23:41:11,412 DEBUG (Mail.java:149) - Email-Versand beendet!
2013-04-14 23:41:11,412 INFO (PluginController.java:135) - PluginController mit
Plugin mail.Mail (Admin1) mit AlarmData () nach 2172 ms beendet
2013-04-14 23:41:11,427 DEBUG (Pipeline.java:298) - Erstelle AlarmHistory
2013-04-14 23:41:11,427 DEBUG (FeedbackController.java:218) - Feedback wird nich
t verwendet (deaktiviert in den Einstellungen)
2013-04-14 23:41:11,427 DEBUG (Pipeline.java:318) - AlarmHistory erfolgreich ers
tellt. F++ge hinzu
2013-04-14 23:41:11,427 DEBUG (DataManagement.java:440) - Hinzuf++gen von AlarmH
istory f++r Admin
2013-04-14 23:41:11,427 DEBUG (DataManagement.java:446) - Synchronize Start
2013-04-14 23:41:11,427 DEBUG (DataManagement.java:448) - Synchronize Started
2013-04-14 23:41:11,427 DEBUG (DataManagement.java:454) - Datum der AlarmHistory
Sun Apr 14 00:00:00 CEST 2013
2013-04-14 23:41:11,427 DEBUG (DataManagement.java:461) - File einlesen C:\Progr
amme\Alamos UG\firEmergency1.3.0.7\firEmergency1.3.0.7\Config\ AlarmHistory\Histo
ry_of_04_14_13.fdb
2013-04-14 23:41:11,443 DEBUG (DataManagement.java:497) - History-Count 2
2013-04-14 23:41:11,443 DEBUG (DataManagement.java:506) - Existierende AlarmHist
ory als Backup speichern
2013-04-14 23:41:11,459 DEBUG (DataManagement.java:530) - Daten in Datei schreib
enC:\Programme\Alamos UG\firEmergency1.3.0.7\firEmergency1.3.0.7\Config\ AlarmHis
tory\History_of_04_14_13.fdb
2013-04-14 23:41:11,459 DEBUG (DataManagement.java:534) - History erolgreich ges
chrieben. Backup-Datei l+Âschen
2013-04-14 23:41:11,474 DEBUG (DataManagement.java:548) - Synchronize End
2013-04-14 23:41:11,474 DEBUG (DataManagement.java:550) - Synchronize Ended
2013-04-14 23:41:11,474 DEBUG (Launcher.java:485) - 0 Oberfl+ñchen wurden ++ber
neue Alarmierungen informiert
2013-04-14 23:41:11,474 INFO (Pipeline.java:326) - Alarmabarbeitung beendet
2013-04-14 23:41:11,474 INFO (Pipeline.java:170) - Pipeline von Feuerwehr (1234
5) wurde nach 205672 ms beendet, isError?: false
2013-04-14 23:41:11,646 DEBUG (PipelineController.java:158) - Server legt sich s
chlafen!


Gruß,
FFSSBZ

[zatsch]

Mein letzter Wissenstand war, dass es da ein Problem mit dem Verzögerungs-Plugin gibt, dies sollte mit einem Update irgendwann mal behoben werden.

Ich habe das aus ähnlichem Grund aus meinen Pipes vorerst heraus geschmissen.

Ansonsten... vielleicht ist es besser wenn du die Mail Adressen nicht offen zu lesen im Log hast.

[FFSSBZ]

Habs jetzt auch erstmal rausgenommen, bzw. das Zeitregel Plugin vorgeschaltet, dass den weiteren Alarm immer blockt.

Hat sich eigentlich schon erledigt, Simon hat in einem anderen Thread gepostet das es immernoch sehr kritisch ist es zu verwenden ... nen Versuch war es wert, musste halt alles wieder per Hand auf die alten .bat Dateien umstellen ...

Gruß,
Andre

[FFSSBZ]

Exakt das gleiche Problem besteht mit der neuen FE Version immernoch. Die Funkaufzeichnung wird 210 Sekunden nach Alarm verschickt und die e-mail die normal als erstes verschickt werden soll wir dann 420 Sekunden nach Alarmeingang verschickt.

Die Pipeline sieht genauso aus wie die die ich im ersten Beitrag gepostet habe, das Verzögerungsplugin hab ich einmal rausgelöscht und wieder neu eingefügt.

Hier die Log:

23.05.2013 - 17:09:09.571 INFO AlarmPool - Neuer eingehender Alarm f³r "28XXX"
23.05.2013 - 17:09:09.571 INFO AlarmPool - Kein Sammelalarm
23.05.2013 - 17:09:09.571 INFO PipelineController - Pipeline gestartet f³r 28XXX
23.05.2013 - 17:09:09.571 INFO Pipeline - Pipeline von Feuerwehr (28XXX) wurde
mit AlarmData () gestartet
23.05.2013 - 17:09:09.581 INFO PluginController - PluginController mit Plugin c
ustomParams_customAlarm.CustomParams_customAlarm (Admin2) mit AlarmData ()wurde
gestartet
23.05.2013 - 17:09:09.581 INFO PluginController - PluginController mit Plugin c
ustomParams_customAlarm.CustomParams_customAlarm (Admin0) mit AlarmData ()wurde
gestartet
23.05.2013 - 17:09:09.581 INFO CustomParams_customAlarm - Key "pluginMessage" g
esetzt auf: TEST Funkaufzeichnung TEST
23.05.2013 - 17:09:09.591 INFO CustomParams_customAlarm - Key "subject" gesetzt
auf: TEST TEST - Funkaufzeichnung
23.05.2013 - 17:09:09.591 INFO CustomParams_customAlarm - Key "pluginMessage" g
esetzt auf: Alarmierung f³r TEST TEST
um 17:09:09
am 23.05.2013
23.05.2013 - 17:09:09.601 INFO PluginController - PluginController mit Plugin c
ustomParams_customAlarm.CustomParams_customAlarm (Admin2) mit AlarmData () nach
20 ms beendet
23.05.2013 - 17:09:09.601 INFO CustomParams_customAlarm - Key "subject" gesetzt
auf: TEST Alarmierung TEST TEST
23.05.2013 - 17:09:09.601 INFO PluginController - PluginController mit Plugin c
ustomParams_customAlarm.CustomParams_customAlarm (Admin0) mit AlarmData () nach
10 ms beendet
23.05.2013 - 17:09:09.681 INFO PluginController - PluginController mit Plugin d
elay.Delay (Admin3) mit AlarmData ()wurde gestartet
23.05.2013 - 17:09:09.681 INFO Delay - Verz÷gerung gestartet
23.05.2013 - 17:10:54.681 INFO Delay - Verz÷gerung beendet
23.05.2013 - 17:10:54.681 INFO PluginController - PluginController mit Plugin d
elay.Delay (Admin3) mit AlarmData () nach 105000 ms beendet
23.05.2013 - 17:10:54.781 INFO PluginController - PluginController mit Plugin m
ail_expert.MailExpert (Admin4) mit AlarmData ()wurde gestartet
23.05.2013 - 17:12:36.931 INFO PluginController - PluginController mit Plugin m
ail_expert.MailExpert (Admin4) mit AlarmData () nach 102150 ms beendet
23.05.2013 - 17:12:37.081 INFO PluginController - PluginController mit Plugin m
ail.Mail (Admin1) mit AlarmData ()wurde gestartet
23.05.2013 - 17:12:39.311 INFO PluginController - PluginController mit Plugin m
ail.Mail (Admin1) mit AlarmData () nach 2230 ms beendet
23.05.2013 - 17:12:39.391 INFO Pipeline - Alarmabarbeitung beendet
23.05.2013 - 17:12:39.391 INFO Pipeline - Pipeline von Feuerwehr (28XXX) wurde
nach 209820 ms beendet, isError?: false


Gruß,
FSSBZ

[firEmergency]

Ok, wird geprüft.

Wir sind bisher immer davon ausgegangen, dass der Fehler der ist, wenn mehrere Alarmierungen gleichzeitig eingehen (also direkt am Input-Plugin).

Das mit der Verzögerung innerhalb der Pipeline hatten wir gar nicht mehr betrachtet.

[firEmergency]

So. Getestet.
Folgendes Ergebnis mit angehängter Pipeline:

27.05.2013 - 10:13:37.144 INFO AlarmPool - Neuer eingehender Alarm für "1234567"
...
27.05.2013 - 10:13:37.288 DEBUG Mail - Sende an 1 Empfänger
27.05.2013 - 10:13:42.410 DEBUG Mail - Email-Versand beendet!
...
27.05.2013 - 10:13:42.584 INFO Delay - Verzögerung gestartet
27.05.2013 - 10:13:42.584 DEBUG Delay - Verzögere: 15 Sekunden
...
27.05.2013 - 10:13:57.586 INFO Delay - Verzögerung beendet
...
27.05.2013 - 10:14:02.729 DEBUG Mail - Email-Versand beendet!

Hatt also korrekt verzögert.
Dein Screenshot ist ja von einer älteren Version. Wie hattest du denn genau getestet?