häufige Shopausfälle: Server oder Shopware?

Shopware 5 Sonstiges
1

Hallo,

seit einer Serverumstellung auf einen vServer von einem Hostingpaket und gleichzeitigem Umstieg von Shopware 4 auf 5 haben wir leider häufiger Ausfälle des Shops und wissen leider nicht mehr wo wir ansetzen können um den Fehler zu lokalisieren. Ebenfalls umgestellt wurde, dass SSL nun überall verwendet wird, was vorher nicht der Fall war.

Beim letzten Ausfall über etwa 2 Stunden haben war der „Header“ der Seite im Frontend zu sehen (Logo etc.), der Rest der Seite wurde jedoch nicht geladen. Das Backend war nicht aufrufbar. Darauf haben wir den Server in Plesk neugestartet und die Seite lief ohne weiteres Zutun wieder (also kein Löschen des Caches oder so).

Unzählige ähnliche Fehlermeldungen zu diesem Zeitpunkt sahen wie folgt aus:
 

Stack trace:
#0 /var/www/vhosts/xxx.de/httpdocs/xxx/var/cache/production_201807181357/html/en/80/b7/ed4e2f9b9561299bfe5f45cd8f2546af42664f3eb2982188a5556310c00b(94): Symfony\Component\HttpKernel\HttpCache\Esi->handle(Object(Shopware\Components\HttpCache\AppCache), '/?module=widget...', '', false)
#1 /var/www/vhosts/xxx.de/httpdocs/xxx/vendor/symfony/http-kernel/HttpCache/HttpCache.php(623): include('/var/www/vhosts...')
#2 /var/www/vhosts/xxx.de/httpdocs/xxx/vendor/symfony/http-kernel/HttpCache/HttpCache.php(208): Symfony\Component\HttpKernel\HttpCache\HttpCache->restoreResponseBody(Object(Symfony\Component\HttpFoundation\Request), Object(Symfony\Component\HttpFoundation\Response))
#3 /var/www/vhosts/xxx.de/httpdocs/xxx/engine/Shopw in /var/www/vhosts/xxx.de/httpdocs/xxx/vendor/symfony/http-kernel/HttpCache/Esi.php on line 268
2019-02-10 16:55:30.124164 [NOTICE] [xx.xxx.xxx.xxx:xxxxx:HTTP2-13] [STDERR] PHP Fatal error: Uncaught RuntimeException: Error when rendering "https://www.xxx.de/?action=info&controller=checkout&module=widgets" (Status code is 503). in /var/www/vhosts/xxx.de/httpdocs/xxx/vendor/symfony/http-kernel/HttpCache/Esi.php:268

Woran könnte so etwas nun liegen? Kann Jemand was zu der Fehlermeldung sagen, oder wonach müsste ich in den Log Files ggf. ausschau halten?

Wir haben jedoch auch häufig Ausfälle, wo die Seite gänzlich nicht zu erreichen ist (also 503 Fehlerseite ohne Logo + Header des Shops). Auch hier geht die Seite nach einem Serverneustart (nginx mit Litespeed). Wir haben keine weiteren Seiten auf dem vServer und auch keine hohen Zugriffszahlen.

Der Hoster gibt leider nur an, dass es nicht an Serverausfällen liegen würde. Teilweise kommen wir jedoch auch zu diesen Zeitpunkten nicht mehr in Plesk rein. Da der Hoster hier jedoch dann Auszüge aus den Access Logs sucht, sagt er hier wurde auf den Server zugegriffen, darum kann es nicht an Serverausfällen liegen. Eine weitere Aussage vom Hoster war: „es war ein Routingthema bei unserem Upstream Provider“ - woraus wir jedoch leider auch nicht schlau werden.

2

Nun wurde ich etwas verwundert darauf aufmerksam gemacht, dass die Domain nicht die richtige ist. Ich habe unsere einfach durch xxx ersetzt und das ohne darüber nachzudenken, was es mit xxx aufsich haben könnte. Die Erotikseite ist natürlich nicht von uns :wink:

 

Dennoch wären wir natürlich über Ideen / Hilfe zur beasgten Fehlermeldung dankbar.

3

Unzählige ähnliche Fehlermeldungen zu diesem Zeitpunkt sahen wie folgt aus:

Die Fehlermeldung hast du perfekt abgeschnitten :/  . Man sieht nur noch, wo sie aufgetreten ist.

Also: Die Zeile vor der Stacktrace umbedingt mitkopieren und hier posten.

VG

4

debugge mal den shop damit du mehr details bekommst - Debugging Shopware

503 und checkout controller sieht ja schwer nach einem plugin aus

5

übrigens - was hast du denn da ? apache, litespeed oder nginx ? nginx mit litespeed gibt es nicht. entweder du hast apache, nginx, nginx mit reverse proxy cache oder halt litespeed. 

prüfe mal dein error_log und shopware production log auf fehler. wo du die findest - im zweifel den hoster fragen. alles bisherige sagt eher dass du hilfe benötigst von jemandem der sich mit shopware und hosting auskennt

6

@SB schrieb:

übrigens - was hast du denn da ? apache, litespeed oder nginx ? nginx mit litespeed gibt es nicht. entweder du hast apache, nginx, nginx mit reverse proxy cache oder halt litespeed. sieht wie so häufig hier wieder nach einer glaskugel frage aus.

prüfe mal dein error_log und shopware production log auf fehler. wo du die findest - im zweifel den hoster fragen. alles bisherige sagt eher dass du hilfe benötigst von jemandem der sich mit shopware und hosting auskennt

Danke SB - du hast vollkommen recht, wir benötigen hier bei dem Problem definitiv Hilfe. Da wir vorher ein nginx Hosting hatten und bei dem Wechsel auf einen vServer uns vom Hoster haben beraten lassen und dieser uns Litespeed empfohlen hat bin ich einfach davon ausgegangen das Litespeed nun dazu gekommen ist. Wir haben also einen Managed ROOT SSD vServer mit Litespeed.

Wir haben das Problem leider nicht nachstellen können. Ist das Debuggen dann die richtige Lösung, wenn wir diese Fehler nur sporadisch alle paar Wochen (manchmal alle paar Tage) erhalten? Die Webseitengeschwindigkeit steht bei uns natürlich für Google selbst an höchster Stelle.

Was wir derzeit in der config.php stehen haben ist zur Datenbank noch folgendes:
 

  'csrfProtection' => [
    'frontend' => false,
    'backend' => true
],
//Zeige Low-Level PHP-Fehler
'phpsettings' => [
    'display_errors' => 1,
],
// Model-Cache
'model' => [
    'cacheProvider' => 'Array' // supports Apc, Array, Wincache and Xcache
],

csrfProtection wurde uns von Shopware schon empfohlen so zu hinterlegen, da wir hier auch schon Fehler hatten.

7

@simkli schrieb:

Also: Die Zeile vor der Stacktrace umbedingt mitkopieren und hier posten.

VG

 Danke: ich habe einfach mal einen größeren Ausschnitt genommen, in der Hoffnung dass man hier nun mehr erkennen kann.

2019-02-10 14:44:38.862803 [INFO] [93.245.214.33:57314:HTTP2-1] Lsapi connection state: 3, watching event: 25, respState: 1, m_iTotalPending:0, m_iPacketLeft:0, m_iCurRespHeader:7, req sent for 113 seconds,Total processing time: 113.
2019-02-10 14:44:38.862934 [INFO] [93.245.214.33:57314:HTTP2-1] Abort request processing by PID:658, kill: 1, begin time: 113, sent time: 113, req processed: 0
2019-02-10 14:44:54.960599 [INFO] [78.43.40.96:5674:HTTP2-131] Abort request processing by PID:913, kill: 1, begin time: 3, sent time: 3, req processed: 0
2019-02-10 14:45:11.120704 [INFO] [207.46.13.116:8453] Abort request processing by PID:874, kill: 1, begin time: 33, sent time: 33, req processed: 0
2019-02-10 14:45:13.286004 [INFO] [93.245.214.33:57370:HTTP2-1] Abort request processing by PID:878, kill: 1, begin time: 34, sent time: 34, req processed: 0
2019-02-10 14:45:56.292481 [INFO] [87.146.198.162:46818:HTTP2-1] Abort request processing by PID:999, kill: 1, begin time: 18, sent time: 18, req processed: 0
2019-02-10 14:45:57.166384 [INFO] [37.5.243.159:53566:HTTP2-269] Abort request processing by PID:1083, kill: 1, begin time: 2, sent time: 2, req processed: 0
2019-02-10 14:46:04.138788 [INFO] [37.5.243.159:53566:HTTP2-319] Abort request processing by PID:1101, kill: 1, begin time: 5, sent time: 5, req processed: 0
2019-02-10 14:46:16.436124 [INFO] [91.41.24.21:62116:HTTP2-1] Connection idle time: 121 while in state: 7 stream flag: 8449,close!
2019-02-10 14:46:16.436160 [NOTICE] [91.41.24.21:62116:HTTP2-1] Content len: 0, Request line: 'GET /bauanleitung-kofferanhaenger-regal-system-selbst-bauen/ HTTP/1.1'
2019-02-10 14:46:16.436166 [INFO] [91.41.24.21:62116:HTTP2-1] Cookie len: 926, _gid=GA1.2.1045823384.1549805732; _ga=GA1.2.1635406301.1549805732; _et_coid=4c8260095467cd61d34455212a72; BT_pdc=eyJldGNjX2N1c3QiOjAsImVjX29yZGjX25ld3NsZXR0ZXIiOjB9; besucht=true; session-1=668aeceb71676fcfdaa9a709ad2278bad5d7df4081; x-ua-device=desktop; BT_ctst=; BT_sdc=KRYPTISCHERSCHLUESSEL; __csrf_token-1=c0IAhMjFdTEG; noWS_kysIbx=true
2019-02-10 14:46:16.436171 [NOTICE] [91.41.24.21:62116:HTTP2-1] Redirect: #1, URL: /shopware.php
2019-02-10 14:46:16.436176 [INFO] [91.41.24.21:62116:HTTP2-1] HttpExtConnector state: 8, request body sent: 0, response body size: -2, response body sent:0, left in buffer: 0, attempts: 0.
2019-02-10 14:46:16.436183 [INFO] [91.41.24.21:62116:HTTP2-1] Lsapi connection state: 3, watching event: 25, respState: 1, m_iTotalPending:0, m_iPacketLeft:0, m_iCurRespHeader:6, req sent for 121 seconds,Total processing time: 121.
2019-02-10 14:46:16.436277 [INFO] [91.41.24.21:62116:HTTP2-1] Abort request processing by PID:831, kill: 1, begin time: 121, sent time: 121, req processed: 0
2019-02-10 14:46:22.317564 [INFO] [88.70.226.252:61122:HTTP2-3] Abort request processing by PID:1147, kill: 1, begin time: 2, sent time: 2, req processed: 1
2019-02-10 14:46:25.384451 [INFO] [37.5.243.159:53566:HTTP2-359] Abort request processing by PID:1120, kill: 1, begin time: 20, sent time: 20, req processed: 0
2019-02-10 14:46:25.388147 [INFO] [37.5.243.159:53566:HTTP2-383] Abort request processing by PID:1131, kill: 1, begin time: 17, sent time: 17, req processed: 0
2019-02-10 14:46:32.923334 [INFO] [87.161.77.55:40744:HTTP2-29] Abort request processing by PID:1173, kill: 1, begin time: 2, sent time: 2, req processed: 0
2019-02-10 14:46:32.923383 [INFO] [87.161.77.55:40744:HTTP2-23] Abort request processing by PID:1167, kill: 1, begin time: 2, sent time: 2, req processed: 1
2019-02-10 14:46:32.923419 [INFO] [87.161.77.55:40744:HTTP2-31] Abort request processing by PID:1174, kill: 1, begin time: 2, sent time: 2, req processed: 0
2019-02-10 14:46:39.113090 [INFO] [207.46.13.116:1723] Abort request processing by PID:1133, kill: 1, begin time: 30, sent time: 30, req processed: 0
2019-02-10 14:46:53.241861 [INFO] [84.119.234.31:60822:HTTP2-1] Abort request processing by PID:1056, kill: 1, begin time: 61, sent time: 61, req processed: 1
2019-02-10 14:47:03.376678 [INFO] [37.5.243.159:53612:HTTP2-35] Abort request processing by PID:1251, kill: 1, begin time: 8, sent time: 8, req processed: 0
2019-02-10 14:47:11.496422 [INFO] [37.5.243.159:53612:HTTP2-225] Abort request processing by PID:1325, kill: 1, begin time: 3, sent time: 3, req processed: 0
2019-02-10 14:47:15.475274 [INFO] [207.46.13.50:11759] Abort request processing by PID:1227, kill: 1, begin time: 31, sent time: 31, req processed: 0
2019-02-10 14:47:33.124821 [INFO] [217.86.94.48:58857:HTTP2-3] Abort request processing by PID:1355, kill: 1, begin time: 16, sent time: 16, req processed: 1
2019-02-10 14:47:54.233388 [INFO] [84.119.234.31:60822:HTTP2-3] Abort request processing by PID:1239, kill: 1, begin time: 61, sent time: 61, req processed: 0
2019-02-10 14:48:10.665208 [INFO] [84.31.8.227:53896] Abort request processing by PID:1418, kill: 1, begin time: 23, sent time: 23, req processed: 0
2019-02-10 14:48:23.487497 [INFO] [88.70.226.252:61122:HTTP2-7] Connection idle time: 121 while in state: 7 stream flag: 257,close!
2019-02-10 14:48:23.487528 [NOTICE] [88.70.226.252:61122:HTTP2-7] Content len: 0, Request line: 'GET /widgets/index/refreshStatistic?requestPage=/aluprofile-25-x-25-mm/&requestController=listing HTTP/1.1'
2019-02-10 14:48:23.487534 [INFO] [88.70.226.252:61122:HTTP2-7] Cookie len: 247, __csrf_token-1=KRYPTISCHERSCHLUESSEL; besucht=true; nocache=detail-1; session-1=d10affd97bf87855a5c53d84c0c3779a85b3915871877630a0c94b; x-ua-device=desktop; _ga=GA1.2.454963501.154796217; _gat=1; _gid=GA1.2.31153.15496217
2019-02-10 14:48:23.487538 [NOTICE] [88.70.226.252:61122:HTTP2-7] Redirect: #1, URL: /shopware.php
2019-02-10 14:48:23.487544 [INFO] [88.70.226.252:61122:HTTP2-7] HttpExtConnector state: 8, request body sent: 0, response body size: -2, response body sent:0, left in buffer: 0, attempts: 0.
2019-02-10 14:48:23.487550 [INFO] [88.70.226.252:61122:HTTP2-7] Lsapi connection state: 3, watching event: 25, respState: 1, m_iTotalPending:0, m_iPacketLeft:0, m_iCurRespHeader:6, req sent for 121 seconds,Total processing time: 121.
2019-02-10 14:48:23.487653 [INFO] [88.70.226.252:61122:HTTP2-7] Abort request processing by PID:1155, kill: 1, begin time: 121, sent time: 121, req processed: 1
2019-02-10 14:48:23.990417 [INFO] [37.5.243.159:53612:HTTP2-273] Abort request processing by PID:1480, kill: 1, begin time: 0, sent time: 0, req processed: 0
2019-02-10 14:48:26.363716 [INFO] [84.119.234.31:60822:HTTP2-5] Abort request processing by PID:1371, kill: 1, begin time: 61, sent time: 61, req processed: 1
2019-02-10 14:48:35.567042 [INFO] [88.73.129.69:48987:HTTP2-1] Abort request processing by PID:1426, kill: 1, begin time: 44, sent time: 44, req processed: 0
2019-02-10 14:48:46.267150 [INFO] [37.5.243.159:53612:HTTP2-275] Abort request processing by PID:1489, kill: 1, begin time: 20, sent time: 20, req processed: 0
2019-02-10 14:48:47.480097 [INFO] [207.46.13.50:3076] Abort request processing by PID:1464, kill: 1, begin time: 33, sent time: 33, req processed: 0
2019-02-10 14:48:54.027392 [NOTICE] [62.224.57.246:60742:HTTP2-1] [STDERR] PHP Fatal error: Uncaught RuntimeException: Error when rendering "https://www.WEBSEITE.de/?action=info&controller=checkout&module=widgets" (Status code is 503). in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php:268
Stack trace:
#0 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/var/cache/production_201807181357/html/en/7c/72/83197e423b9ef49d18b67e1bf9c27d7b7bf271f2e88ec663be68(97): Symfony\Component\HttpKernel\HttpCache\Esi->handle(Object(Shopware\Components\HttpCache\AppCache), '/?module=widget...', '', false)
#1 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/HttpCache.php(623): include('/var/www/vhosts...')
#2 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/HttpCache.php(208): Symfony\Component\HttpKernel\HttpCache\HttpCache->restoreResponseBody(Object(Symfony\Component\HttpFoundation\Request), Object(Symfony\Component\HttpFoundation\Response))
#3 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/engine/Shopw in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php on line 268
2019-02-10 14:48:56.373854 [NOTICE] [85.93.93.123:51572] [STDERR] PHP Fatal error: Uncaught RuntimeException: Error when rendering "https://www.WEBSEITE.de/?action=info&controller=checkout&module=widgets" (Status code is 503). in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php:268

 Den Errorlog musste ich etwas kürzen und habe viele doppelte und dreifache Einträge zeilenweise rausgenommen, da die Nachricht sonst hier zu lang war für das Forum.

8 
#0 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/var/cache/production_201807181357/html/en/80/b7/ed4e2f9b9561299bfe5f45cd8f2546af42664f3eb2982188a5556310c00b(94): Symfony\Component\HttpKernel\HttpCache\Esi->handle(Object(Shopware\Components\HttpCache\AppCache), '/?module=widget...', '', false)
#1 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/HttpCache.php(623): include('/var/www/vhosts...')
#2 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/HttpCache.php(208): Symfony\Component\HttpKernel\HttpCache\HttpCache->restoreResponseBody(Object(Symfony\Component\HttpFoundation\Request), Object(Symfony\Component\HttpFoundation\Response))
#3 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/engine/Shopw in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php on line 268
2019-02-10 16:54:54.672359 [INFO] [95.90.217.190:60018:HTTP2-1] Abort request processing by PID:15494, kill: 1, begin time: 46, sent time: 46, req processed: 0
2019-02-10 16:54:57.697365 [NOTICE] [63.143.42.250:7947] [STDERR] PHP Fatal error: Uncaught RuntimeException: Error when rendering "https://www.WEBSEITE.de/?action=info&controller=checkout&module=widgets" (Status code is 503). in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php:268
Stack trace:
#0 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/var/cache/production_201807181357/html/en/80/b7/ed4e2f9b9561299bfe5f45cd8f2546af42664f3eb2982188a5556310c00b(94): Symfony\Component\HttpKernel\HttpCache\Esi->handle(Object(Shopware\Components\HttpCache\AppCache), '/?module=widget...', '', false)
#1 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/HttpCache.php(623): include('/var/www/vhosts...')
#2 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/HttpCache.php(208): Symfony\Component\HttpKernel\HttpCache\HttpCache->restoreResponseBody(Object(Symfony\Component\HttpFoundation\Request), Object(Symfony\Component\HttpFoundation\Response))
#3 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/engine/Shopw in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php on line 268
2019-02-10 16:55:06.962397 [INFO] [157.55.39.212:8660] Abort request processing by PID:15546, kill: 1, begin time: 30, sent time: 30, req processed: 0
2019-02-10 16:55:09.862278 [INFO] [95.90.217.190:59991:HTTP2-1] Abort request processing by PID:15586, kill: 1, begin time: 15, sent time: 15, req processed: 0
2019-02-10 16:55:10.900777 [NOTICE] [96.47.225.18:51454] [STDERR] PHP Fatal error: Uncaught RuntimeException: Error when rendering "https://www.WEBSEITE.de/?action=info&controller=checkout&module=widgets" (Status code is 503). in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php:268
Stack trace:
#0 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/var/cache/production_201807181357/html/en/80/b7/ed4e2f9b9561299bfe5f45cd8f2546af42664f3eb2982188a5556310c00b(94): Symfony\Component\HttpKernel\HttpCache\Esi->handle(Object(Shopware\Components\HttpCache\AppCache), '/?module=widget...', '', false)
#1 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/HttpCache.php(623): include('/var/www/vhosts...')
#2 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/HttpCache.php(208): Symfony\Component\HttpKernel\HttpCache\HttpCache->restoreResponseBody(Object(Symfony\Component\HttpFoundation\Request), Object(Symfony\Component\HttpFoundation\Response))
#3 /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/engine/Shopw in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php on line 268
2019-02-10 16:55:30.124164 [NOTICE] [91.115.164.162:52475:HTTP2-13] [STDERR] PHP Fatal error: Uncaught RuntimeException: Error when rendering "https://www.WEBSEITE.de/?action=info&controller=checkout&module=widgets" (Status code is 503). in /var/www/vhosts/WEBSEITE.de/httpdocs/WEBSEITE/vendor/symfony/http-kernel/HttpCache/Esi.php:268

 

9

Das Du wohl auch nicht mehr in Plesk reinkommst, würde ich Sagen der Fehler liegt wohl eher an einer falschen Serverkonfiguration.

Das hier zu Diskutieren wäre aber wie die Nadel im Heuhaufen zu suchen.

Du solltest Dir daher Serveradmin suchen der der Sache auf den Grund geht, alles andere wäre blödsinn.

1 „Gefällt mir“
10

Ja, da stimmt etwas nicht.

Abort request processing by PID:1489, kill: 1, b

Scheint, als würde PHP abstürzen. Hast du die neuste Minor-Version? Evtl. ein bekannter Bug? Das müsste der Hoster mal debuggen.

Viele Grüße

11

Cache Ordner löschen manuell per FTP

Und schau mal ob dein Speicher voll ist.

Probiere mal testweise ohne Cache zu arbeiten, den abzustellen im Backend.