Webfront blockiert Haupthread?

jwka · 28. Februar 2011 um 15:55

Hallo,

ich bekommen ab und an folgende Meldung:

Hauptthread war länger als 5 Sekunden blockiert. Dauer: 32832ms

Wie man am nachfolgenden Log sehen kann, läuft zu dieser Zeit ausser dem Webfront und ein paar Variablen-Änderungen vom FHT nichts an Scripten:

28.02.2011 12:23:05.491 | 28.02.2011 12:23:05.541 | 28.02.2011 12:23:25.554 | 28.02.2011 12:23:25.606 | 28.02.2011 12:23:45.619 | 28.02.2011 12:23:45.675 | 28.02.2011 12:24:05.688 | 28.02.2011 12:24:05.744 | 28.02.2011 12:24:25.756 | 28.02.2011 12:24:25.808 | 28.02.2011 12:24:36.134 28.02.2011 12:24:36.139 | 28.02.2011 12:24:36.658 | 28.02.2011 12:24:41.409 28.02.2011 12:24:41.412 | 28.02.2011 12:24:41.473 | 28.02.2011 12:25:01.485 | 28.02.2011 12:25:01.540 | 28.02.2011 12:25:35.024 | 28.02.2011 12:25:53.371 | 28.02.2011 12:25:53.427 | 28.02.2011 12:26:13.455 | 0 | DEBUG | ExecuteThreadID #6 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20013 ms
0 | DEBUG | ExecuteThreadID #9 | Skriptausführung: ips.php ~ Absender: WebInterface
0 | DEBUG | ExecuteThreadID #9 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20014 ms
0 | DEBUG | ExecuteThreadID #4 | Skriptausführung: ips.php ~ Absender: WebInterface
0 | DEBUG | ExecuteThreadID #4 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20014 ms
0 | DEBUG | ExecuteThreadID #11 | Skriptausführung: ips.php ~ Absender: WebInterface
0 | DEBUG | ExecuteThreadID #11 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20014 ms
0 | DEBUG | ExecuteThreadID #11 | Skriptausführung: ips.php ~ Absender: WebInterface
0 | DEBUG | ExecuteThreadID #11 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20013 ms
0 | DEBUG | ExecuteThreadID #11 | Skriptausführung: ips.php ~ Absender: WebInterface
| 14227 | DEBUG | VariableManager | [6-Calefaccion\FHT80b Office [#1][01.01]\Position] = 0
0 | DEBUG | ExecuteThreadID #11 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 10331 ms
0 | DEBUG | ExecuteThreadID #11 | Skriptausführung: ips.php ~ Absender: WebInterface
| 44919 | DEBUG | VariableManager | [6-Calefaccion\FHT80b Essen [#2][01.02]\Position] = 11,328125
0 | DEBUG | ExecuteThreadID #11 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 4754 ms
0 | DEBUG | ExecuteThreadID #5 | Skriptausführung: ips.php ~ Absender: WebInterface
0 | DEBUG | ExecuteThreadID #5 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20013 ms
0 | DEBUG | ExecuteThreadID #5 | Skriptausführung: ips.php ~ Absender: WebInterface
0 | WARNING | Kernel | Hauptthread war länger als 5 Sekunden blockiert. Dauer: 32832ms
0 | DEBUG | ExecuteThreadID #5 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20013 ms
0 | DEBUG | ExecuteThreadID #5 | Skriptausführung: ips.php ~ Absender: WebInterface
0 | DEBUG | ExecuteThreadID #5 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20029 ms

Es ist das original Webfront, als keinesfalls eine irgendwie modifizierte Variante.

Ich kann zu der fraglichen Zeit ferner ausschliessen, dass das Webfront überhaupt bedient wurde.

Kann jemand von der Programmierung hierzu vielleicht ein Statement abgeben?

Danke
jwka

ChristianS · 11. Juli 2011 um 11:34

Hallo!

Bei mir das selbe Problem, habe alle 2-5 Minuten immer „Hauptthread war länger als 5 Sekunden blockiert.“ …
Die PHP-Informationen hab ich ein wenig beobachtet, und im IPS-Log scheints immer daran zu liegen:
11.07.2011 11:00:23.343 | 0 | DEBUG | ExecuteThreadID #9 | Skriptausführung: ips.php ~ Absender: WebInterface
11.07.2011 11:00:43.375 | 0 | DEBUG | ExecuteThreadID #9 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 20037 ms

des weiteren hat sich heute schon 2 mal der Dienst verabschiedet…
Würde mich über eine Hilfe sehr freuen, so kann ich leider nicht wirklich produktiv damit arbeiten…

ChristianS · 11. Juli 2011 um 12:45

Ich werfe mal ein Logfile nach, vielleicht sieht ja jemand etwas auffälliges…

Zeitraum wo der Hauptthread immer wieder mal blockiert war ist 10:38 - 11:22

ab 11:22 ist es dann wieder ohne irgendwelche Änderungen am System verschwunden.

logfile1310379751.zip (97.2 KB)

darx · 11. Juli 2011 um 14:48

habe diese „Blocks“ auch: bei mir zumindest korreliert das mit der automatischen Speicherung der settings.xml - das Intervall der kernel blocks entspricht exakt dem Intervall, in dem die settings.xml gespeichert wird. Verändert man dieses Intervall durch entsprechenden EIntrag in settings.xml, verändert sich entsprechend auch das Intervall, in dem die Blockierungen auftreten. Sehr unschön das ganze! Seltsam ist jedoch auch, dass diese Blockierungen nicht immer auftreten: sie tauchen eine Zeitlang auf, versch3winde3n dann für ein paar Stunden, und kehren wieder zurück.

Viele Grüße
darx

_muckel · 11. Juli 2011 um 16:48

Könnte http://www.ip-symcon.de/forum/project.php?issueid=1067 auch in diese Rubrik fallen?