OsCommerce - 504 Gateway Timeout i 500 Internal Server Error

[mghost.pl]

To jest VPS, serwer dedykowany, hosting współdzielony?

A "Pan od serwerów", o którym wspominałeś w pierwszym poście, nie może przeanalizować logów serwera?

[piotr.k]

Po włączeniu Logowania niestety nic nie zapisuje do pliku. Coś nie działa ta opcja

Generalnie opcja działa i bywa przydatna, ale w ciemno to zgadywanka to jest nie tak u Ciebie.

Z wróżenia sugeruję sprawdzić ścieżkę i prawa dostępu...

[Imperator]

To jest VPS, serwer dedykowany, hosting współdzielony?

Współdzielony

A "Pan od serwerów", o którym wspominałeś w pierwszym poście, nie może przeanalizować logów serwera?

Za każdym razem twierdzi że to wina softu i że serwery działają świetnie

Generalnie opcja działa i bywa przydatna

Nie wątpie, ale niestety nie zapisuje do pliku tych logów. Powinien zapisać do /tmp/page_parse_time.log a katalog tmp jest pusty

sugeruję sprawdzić ścieżkę i prawa dostępu...

A coś więcej

[mghost.pl]

Skoro wie, że to wina skryptu, to młotkuj go o jakiś fragment logu, który to potwierdza.

Jak go dostaniesz, to łatwiej będzie coś podpowiedzieć.

Ewentualnie jeśli masz możliwość, spróbuj chociaż kopię sklepu przenieść na inny hosting i sprawdź, czy problem też występuje.

Obciążenie możesz spróbować zasymulować z użyciem loadimpact.com

[Imperator]

Skoro wie, że to wina skryptu, to młotkuj go o jakiś fragment logu, który to potwierdza.

No to młotkowałem go właśnie (bo błąd trwał 20 minut) i dostałem fragment logu:

Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?page=1&oID=40855&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?selected_box=orders&status=21&page=1&oID=40850&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?selected_box=orders&status=21&page=1&oID=40850&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?status=20&page=1&oID=40849&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?selected_box=orders&status=21&page=1&oID=40850&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?selected_box=orders&status=21&page=1&oID=40850&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?status=20&page=1&oID=40849&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?selected_box=orders&status=21&page=1&oID=40850&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?status=20&page=1&oID=40849&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?status=20&page=1&oID=40849&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?selected_box=orders&status=21&page=1&oID=40850&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?status=18&page=1&oID=40854&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?status=18&page=1&oID=40854&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?selected_box=orders&status=21&page=1&oID=40850&action=edit
Script timed out before returning headers: php-cgi, referer: https://www.xxx.pl/admin/orders.php?status=20&page=1&oID=40849&action=edit

Wraz z komentarzem:

- oprogramowanie sklepu czeka na dane z bazy, a baza mieli dane i sie zapętla

- jak dostanie timeout to wywala blad

-programista musi zestawic te dane z logow z danymi z bazy danych

-najlepiej jak bedzie sledzil moment w ktorym taki blad wystapi i sprawdzi baze danych (phpmyadmin) i logi z www

Przyznam że nic z tego nie rozumiem bo się na tym nie znam

[mghost.pl]

Skoro to problem z bazą, poproś admina o włączenie logowania slow_queries.

Będziesz wiedział, które zapytania faktycznie długo się wykonują.

A może baza działa na defaultowych ustawieniach i po prostu jej konfiguracja wymaga poprawy?

Generalnie na hostingu współdzielonym jesteś zdany na łaskę i niełaskę administratora, więc wiele nie zdziałasz.

Możesz spróbować się przenieść na inny hosting żeby sprawdzić, czy problem również wystąpi.

A wybacz, ale odpowiedź admina jest trochę z d...

[Imperator]

A wybacz, ale odpowiedź admina jest trochę z d...

Dzisiaj admin napisał że zrestartowanie bazy pomaga i wysłał mi listę procesów które zawiesiły ponoć naszą stronę:

+---------+-----------------+----------------------+-----------------------+---------+------+--------------+------------------------------------------------------------------------------------------------------+

| Id | User | Host | db | Command | Time | State | Info |

+---------+-----------------+----------------------+-----------------------+---------+------+--------------+------------------------------------------------------------------------------------------------------+

| 4237823 | u1016726_sklep2 | 192.168.133.12:44337 | db1016726_sklep2 | Query | 2858 | Sending data | select count(*) as total from customers c left join orders o on c.customers_id = o.customers_id lef |

| 4237896 | u1016726_sklep2 | 192.168.133.12:44533 | db1016726_sklep2 | Query | 2836 | Sending data | select count(*) as total from customers c left join orders o on c.customers_id = o.customers_id lef |

| 4238109 | u1016726_sklep2 | 192.168.133.12:45140 | db1016726_sklep2 | Query | 2787 | Locked | update orders set orders_status = '21', last_modified = now() where orders_id = '41024' |

| 4238161 | u1016726_sklep2 | 192.168.133.12:45262 | db1016726_sklep2 | Query | 2774 | Locked | update orders set orders_status = '14', last_modified = now() where orders_id = '41028' |

| 4238737 | u1016726_sklep2 | 192.168.133.12:46799 | db1016726_sklep2 | Query | 2643 | Locked | update orders set orders_status = '14', last_modified = now() where orders_id = '41022' |

| 4238892 | u1016726_sklep2 | 192.168.133.12:47129 | db1016726_sklep2 | Query | 2609 | Locked | update orders set orders_status = '21', last_modified = now() where orders_id = '41024' |

| 4238911 | u1016726_sklep2 | 192.168.133.12:47165 | db1016726_sklep2 | Query | 2605 | Locked | update orders set orders_status = '14', last_modified = now() where orders_id = '41028' |

| 4240263 | u1016726_sklep2 | 192.168.133.12:50791 | db1016726_sklep2 | Query | 2338 | Locked | update orders set orders_status = '21', last_modified = now() where orders_id = '41024' |

| 4240584 | u1016726_sklep2 | 192.168.133.12:51370 | db1016726_sklep2 | Query | 2280 | Locked | update orders set orders_status = '14', last_modified = now() where orders_id = '41022' |

| 4241956 | u1016726_sklep2 | 192.168.133.12:55275 | db1016726_sklep2 | Query | 1990 | Locked | update orders set orders_status = '14', last_modified = now() where orders_id = '41022' |

| 4242227 | u1016726_sklep2 | 192.168.133.12:55566 | db1016726_sklep2 | Query | 1947 | Locked | update orders set orders_status = '14', last_modified = now() where orders_id = '41028' |

| 4243145 | u1016726_sklep2 | 192.168.133.12:56896 | db1016726_sklep2 | Query | 1820 | Locked | update orders set orders_status = '14', last_modified = now() where orders_id = '41028' |

| 4245327 | u1016726_sklep2 | 192.168.133.12:32804 | db1016726_sklep2 | Query | 1379 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41024' |

| 4246361 | u1016726_sklep2 | 192.168.133.12:34529 | db1016726_sklep2 | Query | 1162 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41024' |

| 4246807 | u1016726_sklep2 | 192.168.133.12:35056 | db1016726_sklep2 | Query | 1073 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41023' |

| 4246988 | u1016726_sklep2 | 192.168.133.12:35576 | db1016726_sklep2 | Query | 1034 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41024' |

| 4246994 | u1016726_sklep2 | 192.168.133.12:35597 | db1016726_sklep2 | Query | 1032 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247230 | u1016726_sklep2 | 192.168.133.12:36402 | db1016726_sklep2 | Query | 963 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247256 | u1016726_sklep2 | 192.168.133.12:36465 | db1016726_sklep2 | Query | 951 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247309 | u1016726_sklep2 | 192.168.133.12:36986 | db1016726_sklep2 | Query | 929 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41023' |

| 4247450 | u1016726_sklep2 | 192.168.133.12:37535 | db1016726_sklep2 | Query | 881 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247601 | u1016726_sklep2 | 192.168.133.12:38187 | db1016726_sklep2 | Query | 834 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41026' |

| 4247647 | u1016726_sklep2 | 192.168.133.12:38323 | db1016726_sklep2 | Query | 822 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247691 | u1016726_sklep2 | 192.168.133.12:38471 | db1016726_sklep2 | Query | 811 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41024' |

| 4247739 | u1016726_sklep2 | 192.168.133.12:38600 | db1016726_sklep2 | Query | 798 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247758 | u1016726_sklep2 | 192.168.133.12:38623 | db1016726_sklep2 | Query | 793 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247789 | u1016726_sklep2 | 192.168.133.12:38728 | db1016726_sklep2 | Query | 784 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247883 | u1016726_sklep2 | 192.168.133.12:38980 | db1016726_sklep2 | Query | 749 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247892 | u1016726_sklep2 | 192.168.133.12:38999 | db1016726_sklep2 | Query | 746 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247898 | u1016726_sklep2 | 192.168.133.12:39008 | db1016726_sklep2 | Query | 745 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247902 | u1016726_sklep2 | 192.168.133.12:39010 | db1016726_sklep2 | Query | 744 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4247904 | u1016726_sklep2 | 192.168.133.12:39011 | db1016726_sklep2 | Query | 744 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4248009 | u1016726_sklep2 | 192.168.133.12:39594 | db1016726_sklep2 | Query | 696 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41026' |

| 4248022 | u1016726_sklep2 | 192.168.133.12:39607 | db1016726_sklep2 | Query | 694 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41023' |

| 4248037 | u1016726_sklep2 | 192.168.133.12:39660 | db1016726_sklep2 | Query | 687 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41024' |

| 4248062 | u1016726_sklep2 | 192.168.133.12:39788 | db1016726_sklep2 | Query | 678 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4248427 | u1016726_sklep2 | 192.168.133.12:40843 | db1016726_sklep2 | Query | 590 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41027' |

| 4248677 | u1016726_sklep2 | 192.168.133.12:41230 | db1016726_sklep2 | Query | 554 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41026' |

| 4248691 | u1016726_sklep2 | 192.168.133.12:41267 | db1016726_sklep2 | Query | 552 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41023' |

| 4248708 | u1016726_sklep2 | 192.168.133.12:41335 | db1016726_sklep2 | Query | 550 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41024' |

| 4248893 | u1016726_sklep2 | 192.168.133.12:41872 | db1016726_sklep2 | Query | 515 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

| 4250683 | u1016726_sklep2 | 192.168.133.12:43580 | db1016726_sklep2 | Query | 176 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41024' |

| 4250692 | u1016726_sklep2 | 192.168.133.12:43609 | db1016726_sklep2 | Query | 172 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41023' |

| 4250700 | u1016726_sklep2 | 192.168.133.12:43623 | db1016726_sklep2 | Query | 169 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41026' |

| 4250709 | u1016726_sklep2 | 192.168.133.12:43641 | db1016726_sklep2 | Query | 166 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41027' |

| 4250835 | u1016726_sklep2 | 192.168.133.12:44187 | db1016726_sklep2 | Query | 107 | Locked | update orders set orders_status = '28', last_modified = now() where orders_id = '41027' |

| 4250845 | u1016726_sklep2 | 192.168.133.12:44284 | db1016726_sklep2 | Query | 99 | Locked | update customers_info set customers_info_date_of_last_logon = now(), customers_info_number_of_logons |

Może teraz ktoś mi coś więcej powie o co chodzi?