Erstellt vor 22 Monaten

Zuletzt geändert vor 14 Monaten

#2271 new Fehler

Buchen von Einkuafsrechnung bringt "500 Internal Server Error"

Erstellt von: andreas.rudin@… Verantwortlicher:
Priorität: normal Meilenstein:
Komponente: kivitendo ERP Version: 3.0.0
Schweregrad: normal Stichworte:
Beobachter: andreas.rudin@…

Beschreibung

Wenn ich eine Einkaufsrechnung buchen will, bekomme ich "500 Internal Server Error" angezeigt.
Im apache2/error.log finden sich dazu folgende Meldungen:

[Fri May 17 03:34:08 2013] [warn] [client ....] mod_fcgid: stderr: Can't locate bin/mozilla/ir.pl?action=display_form in @INC (@INC contains: /var/www/.../lx-office-erp /var/www/.../lx-office-erp/modules/override /etc/perl /usr/local/lib/perl/5.10.1 /usr/local/share/perl/5.10.1 /usr/lib/perl5 /usr/share/perl5 /usr/lib/perl/5.10 /usr/share/perl/5.10 /usr/local/lib/site_perl . /var/www/.../lx-office-erp/modules/fallback) at SL/Dispatcher.pm line 136., referer: https://.../ir.pl
[Fri May 17 03:34:08 2013] [error] [client ....] Premature end of script headers: dispatcher.fpl, referer: https://.../ir.pl

Rechnung und Zahlung wird aber trotzdem gebucht!

Änderungshistorie (4)

comment:1 Geändert vor 22 Monaten durch s.schoeling@…

Hast Du noch im Kopf ob an der Rechnung etwas besonderes war? Den Bug hatten wir auch schon in unserer internen Version, aber konnten den nie reproduzieren.

comment:2 Geändert vor 22 Monaten durch hli@…

Ich habe diesen Fehler auch immer wieder in der 2.7

Besonderheiten? Hmm... Ist ja nicht immer.
Fremdwährung und mehr als 5,6,7 Positionen ....
Die Rechnung wird aber korrekt übernommen.

comment:3 Geändert vor 14 Monaten durch MacMac

Ich bin ebenfalls heute auf dieses Problem in 3.0.0 stable gestoßen,
und zwar bei Testbuchungen in einer Testumgebung.

An dem Einkaufsbeleg war nichts ungewöhnliches.
Keine Fremdwährung, 8 Positionen.
Aber auch beim vereinfachen des Beleges (nurnoch 1 Position) trat der Fehler weiterhin auf.

Ursprünglich ist der Fehler bei aktiviertem fast-cgi aufgetreten.
Wenn ich den Beleg als Entwurf speichere (das geht ohne weiteres)
und später bei ausgeschaltetem fast-cgi versuche diesen zu verbuchen,
erhalte ich die Meldung:
The requested URL /ir.pl?action=display_form was not found on this server.

error.log:
[Sun Jan 05 12:47:39 2014] [error] [client 192.168.115.100] File does not exist: /var/www/kivitendo/ir.pl?action=display_form, referer: http://server/ir.pl?action=load_draft&id=ir-invoice-1388918570-78726-31038

Aus meinem Webserver-Verständnis heraus sieht schon allein die Meldung komisch aus:
Angeblich fehlt die Datei ir.pl, ausgelöst wird der Fehler aber in der ir.pl...

Falls es irgendwie nutzt - hier noch das Trace-File dazu:

2014-01-05 12:47:37 381 [367] sub0: \  SL::Auth::new in /var/www/kivitendo/SL/Auth.pm:30 called from SL/Dispatcher.pm:107
2014-01-05 12:47:37 381 [367] sub0:  \  SL::Auth::_read_auth_config in /var/www/kivitendo/SL/Auth.pm:103 called from /var/www/kivitendo/SL/Auth.pm:37
2014-01-05 12:47:37 381 [367] sub0:   \  SL::Auth::DB::new in /var/www/kivitendo/SL/Auth/DB.pm:13 called from /var/www/kivitendo/SL/Auth.pm:115
2014-01-05 12:47:37 381 [367] sub0:   /  SL::Auth::DB::new in /var/www/kivitendo/SL/Auth/DB.pm:23
2014-01-05 12:47:37 381 [367] sub0:  /  SL::Auth::_read_auth_config in /var/www/kivitendo/SL/Auth.pm:144
2014-01-05 12:47:37 381 [367] sub0: /  SL::Auth::new in /var/www/kivitendo/SL/Auth.pm:40
2014-01-05 12:47:37 381 [367] sub0: \  SL::Dispatcher::handle_request in SL/Dispatcher.pm:175 called from /var/www/kivitendo/ir.pl:9
2014-01-05 12:47:37 381 [367] sub0:  \  Locale::new in /var/www/kivitendo/SL/Locale.pm:54 called from SL/Dispatcher.pm:182
2014-01-05 12:47:38 381 [367] sub0:  /  Locale::new in /var/www/kivitendo/SL/Locale.pm:71
2014-01-05 12:47:38 381 [367] sub0:  \  Form::new in /var/www/kivitendo/SL/Form.pm:89 called from SL/Dispatcher.pm:183
2014-01-05 12:47:38 381 [367] sub0:  /  Form::new in /var/www/kivitendo/SL/Form.pm:108
2014-01-05 12:47:38 381 [367] sub0:  \  SL::Auth::restore_session in /var/www/kivitendo/SL/Auth.pm:558 called from SL/Dispatcher.pm:190
2014-01-05 12:47:38 381 [367] sub0:  /  SL::Auth::restore_session in /var/www/kivitendo/SL/Auth.pm:617
2014-01-05 12:47:38 381 [367] sub0:  \  SL::Request::read_cgi_input in /var/www/kivitendo/SL/Request.pm:219 called from /var/www/kivitendo/SL/Form.pm:115
2014-01-05 12:47:38 381 [367] sub0:   \  SL::Request::_recode_recursively in /var/www/kivitendo/SL/Request.pm:184 called from /var/www/kivitendo/SL/Request.pm:253
2014-01-05 12:47:38 381 [367] sub0:   /  SL::Request::_recode_recursively in /var/www/kivitendo/SL/Request.pm:215
2014-01-05 12:47:38 381 [367] sub0:  /  SL::Request::read_cgi_input in /var/www/kivitendo/SL/Request.pm:261
2014-01-05 12:47:38 381 [367] sub0:  \  SL::Dispatcher::require_main_code in SL/Dispatcher.pm:131 called from SL/Dispatcher.pm:202
2014-01-05 12:47:38 381 [367] sub0:  /  SL::Dispatcher::require_main_code in SL/Dispatcher.pm:153
2014-01-05 12:47:38 381 [367] sub0:  \  SL::Auth::session_tables_present in /var/www/kivitendo/SL/Auth.pm:965 called from SL/Dispatcher.pm:64
2014-01-05 12:47:38 381 [367] sub0:  /  SL::Auth::session_tables_present in /var/www/kivitendo/SL/Auth.pm:993
2014-01-05 12:47:38 381 [367] sub0:  \  SL::Auth::get_session_value in /var/www/kivitendo/SL/Auth.pm:879 called from /var/www/kivitendo/SL/Dispatcher/AuthHandler/User.pm:11
2014-01-05 12:47:38 381 [367] sub0:  /  SL::Auth::get_session_value in /var/www/kivitendo/SL/Auth.pm:884
2014-01-05 12:47:38 381 [367] sub0:  \  SL::Auth::read_user in /var/www/kivitendo/SL/Auth.pm:463 called from /var/www/kivitendo/SL/Dispatcher/AuthHandler/User.pm:14
2014-01-05 12:47:38 381 [367] sub0:  /  SL::Auth::read_user in /var/www/kivitendo/SL/Auth.pm:504
2014-01-05 12:47:38 381 [367] sub0:  \  Locale::new in /var/www/kivitendo/SL/Locale.pm:54 called from /var/www/kivitendo/SL/Dispatcher/AuthHandler/User.pm:18
2014-01-05 12:47:38 381 [367] sub0:  /  Locale::new in /var/www/kivitendo/SL/Locale.pm:71
2014-01-05 12:47:38 381 [367] sub0:  \  SL::Auth::authenticate in /var/www/kivitendo/SL/Auth.pm:174 called from /var/www/kivitendo/SL/Dispatcher/AuthHandler/User.pm:23
2014-01-05 12:47:38 381 [367] sub0:   \  SL::Auth::get_session_value in /var/www/kivitendo/SL/Auth.pm:879 called from /var/www/kivitendo/SL/Auth.pm:178
2014-01-05 12:47:38 381 [367] sub0:   /  SL::Auth::get_session_value in /var/www/kivitendo/SL/Auth.pm:884
2014-01-05 12:47:38 381 [367] sub0:  /  SL::Auth::authenticate in /var/www/kivitendo/SL/Auth.pm:180
2014-01-05 12:47:38 381 [367] sub0:  \  SL::Auth::delete_session_value in /var/www/kivitendo/SL/Auth.pm:866 called from /var/www/kivitendo/SL/Dispatcher/AuthHandler/User.pm:28
2014-01-05 12:47:38 381 [367] sub0:  /  SL::Auth::delete_session_value in /var/www/kivitendo/SL/Auth.pm:873
2014-01-05 12:47:38 381 [367] sub0:  \  Form::dbconnect_noauto in /var/www/kivitendo/SL/Form.pm:1431 called from /var/www/kivitendo/SL/Form.pm:1460
2014-01-05 12:47:38 381 [367] sub0:  /  Form::dbconnect_noauto in /var/www/kivitendo/SL/Form.pm:1444
2014-01-05 12:47:38 381 [367] sub0:  \  Form::set_standard_title in /var/www/kivitendo/SL/Form.pm:581 called from SL/Dispatcher.pm:254
2014-01-05 12:47:38 381 [367] sub0:  /  Form::set_standard_title in /var/www/kivitendo/SL/Form.pm:588
2014-01-05 12:47:38 381 [367] sub0:  \  Locale::findsub in /var/www/kivitendo/SL/Locale.pm:245 called from SL/Dispatcher.pm:256
2014-01-05 12:47:38 381 [367] sub0:  /  Locale::findsub in /var/www/kivitendo/SL/Locale.pm:272
2014-01-05 12:47:38 381 [367] sub0:  \  main::call_sub in /var/www/kivitendo/bin/mozilla/common.pl:417 called from SL/Dispatcher.pm:256
2014-01-05 12:47:38 381 [367] sub0:   \  main::post in /var/www/kivitendo/bin/mozilla/ir.pl:689 called from /var/www/kivitendo/bin/mozilla/common.pl:436
2014-01-05 12:47:38 381 [367] sub0:    \  SL::Auth::load_rights_for_user in /var/www/kivitendo/SL/Auth.pm:1312 called from /var/www/kivitendo/SL/Auth.pm:1279
2014-01-05 12:47:38 381 [367] sub0:    /  SL::Auth::load_rights_for_user in /var/www/kivitendo/SL/Auth.pm:1336
2014-01-05 12:47:38 381 [367] sub0:    \  Form::get_default_currency in /var/www/kivitendo/SL/Form.pm:1686 called from /var/www/kivitendo/bin/mozilla/ir.pl:697
2014-01-05 12:47:38 381 [367] sub0:     \  Form::get_all_currencies in /var/www/kivitendo/SL/Form.pm:1669 called from /var/www/kivitendo/SL/Form.pm:1689
2014-01-05 12:47:38 381 [367] sub0:     /  Form::get_all_currencies in /var/www/kivitendo/SL/Form.pm:1680
2014-01-05 12:47:38 381 [367] sub0:    /  Form::get_default_currency in /var/www/kivitendo/SL/Form.pm:1691
2014-01-05 12:47:38 381 [367] sub0:    \  Form::isblank in /var/www/kivitendo/SL/Form.pm:364 called from /var/www/kivitendo/bin/mozilla/ir.pl:699
2014-01-05 12:47:38 381 [367] sub0:    /  Form::isblank in /var/www/kivitendo/SL/Form.pm:376
2014-01-05 12:47:38 381 [367] sub0:    \  Form::isblank in /var/www/kivitendo/SL/Form.pm:364 called from /var/www/kivitendo/bin/mozilla/ir.pl:700
2014-01-05 12:47:38 381 [367] sub0:    /  Form::isblank in /var/www/kivitendo/SL/Form.pm:376
2014-01-05 12:47:38 381 [367] sub0:    \  Form::isblank in /var/www/kivitendo/SL/Form.pm:364 called from /var/www/kivitendo/bin/mozilla/ir.pl:701
2014-01-05 12:47:38 381 [367] sub0:    /  Form::isblank in /var/www/kivitendo/SL/Form.pm:376
2014-01-05 12:47:38 381 [367] sub0:    \  main::check_name in /var/www/kivitendo/bin/mozilla/arap.pl:52 called from /var/www/kivitendo/bin/mozilla/ir.pl:707
2014-01-05 12:47:38 381 [367] sub0:     \  Form::language_payment in /var/www/kivitendo/SL/Form.pm:2597 called from /var/www/kivitendo/bin/mozilla/arap.pl:137
2014-01-05 12:47:38 381 [367] sub0:     /  Form::language_payment in /var/www/kivitendo/SL/Form.pm:2629
2014-01-05 12:47:38 381 [367] sub0:    /  main::check_name in /var/www/kivitendo/bin/mozilla/arap.pl:139
2014-01-05 12:47:38 381 [367] sub0:    \  CVar::get_configs in /var/www/kivitendo/SL/CVar.pm:13 called from /var/www/kivitendo/bin/mozilla/io.pl:738
2014-01-05 12:47:38 381 [367] sub0:    /  CVar::get_configs in /var/www/kivitendo/SL/CVar.pm:61
2014-01-05 12:47:38 381 [367] sub0:    \  Form::redo_rows in /var/www/kivitendo/SL/Form.pm:2996 called from /var/www/kivitendo/bin/mozilla/io.pl:749
2014-01-05 12:47:38 381 [367] sub0:    /  Form::redo_rows in /var/www/kivitendo/SL/Form.pm:3018
2014-01-05 12:47:38 381 [367] sub0:    \  main::validate_items in /var/www/kivitendo/bin/mozilla/io.pl:797 called from /var/www/kivitendo/bin/mozilla/ir.pl:719
2014-01-05 12:47:38 381 [367] sub0:     \  Form::isblank in /var/www/kivitendo/SL/Form.pm:364 called from /var/www/kivitendo/bin/mozilla/io.pl:812
2014-01-05 12:47:38 381 [367] sub0:     /  Form::isblank in /var/www/kivitendo/SL/Form.pm:376
2014-01-05 12:47:38 381 [367] sub0:    /  main::validate_items in /var/www/kivitendo/bin/mozilla/io.pl:816
2014-01-05 12:47:38 381 [367] sub0:    \  Form::datetonum in /var/www/kivitendo/SL/Form.pm:1368 called from /var/www/kivitendo/bin/mozilla/ir.pl:721
2014-01-05 12:47:38 381 [367] sub0:    /  Form::datetonum in /var/www/kivitendo/SL/Form.pm:1396
2014-01-05 12:47:38 381 [367] sub0:    \  Form::datetonum in /var/www/kivitendo/SL/Form.pm:1368 called from /var/www/kivitendo/bin/mozilla/ir.pl:722
2014-01-05 12:47:38 381 [367] sub0:    /  Form::datetonum in /var/www/kivitendo/SL/Form.pm:1396
2014-01-05 12:47:38 381 [367] sub0:    \  main::relink_accounts in /var/www/kivitendo/bin/mozilla/io.pl:1743 called from /var/www/kivitendo/bin/mozilla/ir.pl:756
2014-01-05 12:47:38 381 [367] sub0:     \  IC::retrieve_accounts in /var/www/kivitendo/SL/IC.pm:1502 called from /var/www/kivitendo/bin/mozilla/io.pl:1757
2014-01-05 12:47:38 381 [367] sub0:     /  IC::retrieve_accounts in /var/www/kivitendo/SL/IC.pm:1598
2014-01-05 12:47:38 381 [367] sub0:    /  main::relink_accounts in /var/www/kivitendo/bin/mozilla/io.pl:1759
2014-01-05 12:47:38 381 [367] sub0:    \  IR::post_invoice in /var/www/kivitendo/SL/IR.pm:53 called from /var/www/kivitendo/bin/mozilla/ir.pl:757
2014-01-05 12:47:38 381 [367] sub0:     \  Form::dbconnect_noauto in /var/www/kivitendo/SL/Form.pm:1431 called from /var/www/kivitendo/SL/IR.pm:58
2014-01-05 12:47:38 381 [367] sub0:     /  Form::dbconnect_noauto in /var/www/kivitendo/SL/Form.pm:1444
2014-01-05 12:47:38 381 [367] sub0:     \  Form::get_default_currency in /var/www/kivitendo/SL/Form.pm:1686 called from /var/www/kivitendo/SL/IR.pm:59
2014-01-05 12:47:38 381 [367] sub0:      \  Form::get_all_currencies in /var/www/kivitendo/SL/Form.pm:1669 called from /var/www/kivitendo/SL/Form.pm:1689
2014-01-05 12:47:38 381 [367] sub0:      /  Form::get_all_currencies in /var/www/kivitendo/SL/Form.pm:1680
2014-01-05 12:47:38 381 [367] sub0:     /  Form::get_default_currency in /var/www/kivitendo/SL/Form.pm:1691
2014-01-05 12:47:38 381 [367] sub0:     \  CVar::get_configs in /var/www/kivitendo/SL/CVar.pm:13 called from /var/www/kivitendo/SL/IR.pm:61
2014-01-05 12:47:38 381 [367] sub0:     /  CVar::get_configs in /var/www/kivitendo/SL/CVar.pm:61
2014-01-05 12:47:38 381 [367] sub0:     \  AM::retrieve_units in /var/www/kivitendo/SL/AM.pm:1361 called from /var/www/kivitendo/SL/IR.pm:71
2014-01-05 12:47:38 381 [367] sub0:     /  AM::retrieve_units in /var/www/kivitendo/SL/AM.pm:1408
2014-01-05 12:47:38 381 [367] sub0:     \  Form::get_lists in /var/www/kivitendo/SL/Form.pm:2371 called from /var/www/kivitendo/SL/IR.pm:98
2014-01-05 12:47:38 381 [367] sub0:      \  Form::_get_simple in /var/www/kivitendo/SL/Form.pm:2344 called from /var/www/kivitendo/SL/Form.pm:2456
2014-01-05 12:47:38 381 [367] sub0:      /  Form::_get_simple in /var/www/kivitendo/SL/Form.pm:2353
2014-01-05 12:47:38 381 [367] sub0:     /  Form::get_lists in /var/www/kivitendo/SL/Form.pm:2472
2014-01-05 12:47:38 381 [367] sub0:     \  CVar::save_custom_variables in /var/www/kivitendo/SL/CVar.pm:310 called from /var/www/kivitendo/SL/IR.pm:363
2014-01-05 12:47:38 381 [367] sub0:     /  CVar::save_custom_variables in /var/www/kivitendo/SL/CVar.pm:379
2014-01-05 12:47:38 381 [367] sub0:     \  IO::set_datepaid in /var/www/kivitendo/SL/IO.pm:33 called from /var/www/kivitendo/SL/IR.pm:581
2014-01-05 12:47:38 381 [367] sub0:     /  IO::set_datepaid in /var/www/kivitendo/SL/IO.pm:74
2014-01-05 12:47:38 381 [367] sub0:     \  Form::add_shipto in /var/www/kivitendo/SL/Form.pm:1858 called from /var/www/kivitendo/SL/IR.pm:652
2014-01-05 12:47:38 381 [367] sub0:     /  Form::add_shipto in /var/www/kivitendo/SL/Form.pm:1918
2014-01-05 12:47:38 381 [367] sub0:     \  Common::webdav_folder in /var/www/kivitendo/SL/Common.pm:334 called from /var/www/kivitendo/SL/IR.pm:657
2014-01-05 12:47:38 381 [367] sub0:     /  Common::webdav_folder in /var/www/kivitendo/SL/Common.pm:338
2014-01-05 12:47:38 381 [367] sub0:     \  RecordLinks::create_links in /var/www/kivitendo/SL/RecordLinks.pm:12 called from /var/www/kivitendo/SL/IR.pm:660
2014-01-05 12:47:38 381 [367] sub0:     /  RecordLinks::create_links in /var/www/kivitendo/SL/RecordLinks.pm:50
2014-01-05 12:47:38 381 [367] sub0:     \  ARAP::close_orders_if_billed in /var/www/kivitendo/SL/ARAP.pm:12 called from /var/www/kivitendo/SL/IR.pm:684
2014-01-05 12:47:38 381 [367] sub0:      \  RecordLinks::get_links in /var/www/kivitendo/SL/RecordLinks.pm:75 called from /var/www/kivitendo/SL/ARAP.pm:30
2014-01-05 12:47:38 381 [367] sub0:      /  RecordLinks::get_links in /var/www/kivitendo/SL/RecordLinks.pm:112
2014-01-05 12:47:38 381 [367] sub0:      \  RecordLinks::get_links in /var/www/kivitendo/SL/RecordLinks.pm:75 called from /var/www/kivitendo/SL/ARAP.pm:38
2014-01-05 12:47:38 381 [367] sub0:      /  RecordLinks::get_links in /var/www/kivitendo/SL/RecordLinks.pm:112
2014-01-05 12:47:38 381 [367] sub0:     /  ARAP::close_orders_if_billed in /var/www/kivitendo/SL/ARAP.pm:55
2014-01-05 12:47:38 381 [367] sub0:    /  IR::post_invoice in /var/www/kivitendo/SL/IR.pm:715
2014-01-05 12:47:38 381 [367] sub0:    \  Form::save_history in /var/www/kivitendo/SL/Form.pm:3156 called from /var/www/kivitendo/bin/mozilla/ir.pl:763
2014-01-05 12:47:38 381 [367] sub0:    /  Form::save_history in /var/www/kivitendo/SL/Form.pm:3174
2014-01-05 12:47:38 381 [367] sub0:    \  Form::redirect in /var/www/kivitendo/SL/Form.pm:821 called from /var/www/kivitendo/bin/mozilla/ir.pl:767
2014-01-05 12:47:38 381 [367] sub0:     \  SL::Auth::save_session in /var/www/kivitendo/SL/Auth.pm:768 called from SL/Dispatcher.pm:278
2014-01-05 12:47:38 381 [367] sub0:     /  SL::Auth::save_session in /var/www/kivitendo/SL/Auth.pm:834
2014-01-05 12:47:38 381 [367] sub0:     \  SL::Auth::expire_sessions in /var/www/kivitendo/SL/Auth.pm:717 called from SL/Dispatcher.pm:279
2014-01-05 12:47:38 381 [367] sub0:      \  SL::Auth::session_tables_present in /var/www/kivitendo/SL/Auth.pm:965 called from /var/www/kivitendo/SL/Auth.pm:721
2014-01-05 12:47:38 381 [367] sub0:      /  SL::Auth::session_tables_present in /var/www/kivitendo/SL/Auth.pm:972
2014-01-05 12:47:38 381 [367] sub0:     /  SL::Auth::expire_sessions in /var/www/kivitendo/SL/Auth.pm:747
2014-01-05 12:47:38 381 [367] sub0:    /  SL::Dispatcher::handle_request in SL/Dispatcher.pm:292

Zuletzt geändert vor 14 Monaten von MacMac (vorher) (Diff)

comment:4 Geändert vor 14 Monaten durch jbueren

Zwei Punkte:

a) Bitte nochmal gegen die aktuelle unstable probieren, bzw. die nächste Beta

Ich kann das so nicht nachstellen.

Nochmal kurz mit Sven gesprochen, vermutlich ist weiter hinten im "Request" ein Fragezeichen, dass da nicht hingehört, um das nachzustellen, bräuchten man eine ausführliche apache-Log-Datei.

Also: Wenn du Deinen Log-File gesprächig hast und dort dann im GET-Request ein Fragezeichen auftauchen, können wir helfen.
;-(.

Hinweis: Hilfe zur Verwendung von Tickets finden Sie in TracTickets.