[Koha-bugs] [Bug 35697] Checking out books causes an error 500

bugzilla-daemon at bugs.koha-community.org bugzilla-daemon at bugs.koha-community.org
Wed Jan 17 09:40:42 CET 2024 

https://bugs.koha-community.org/bugzilla3/show_bug.cgi?id=35697

--- Comment #9 from Pradeep <agastilib at gmail.com> ---
(In reply to Katrin Fischer from comment #6)
> (In reply to Pradeep from comment #5)
> > I have checked $y, $a and $b item have all information. Also checked log.
> > Problem occurred after upgrade (23.11)
> > Now we are using Koha Offline circulation Firefox plugin to do check out.
> > Renew and Check in is working very well.
> 
> What did you see in the log? With an error 500 there should be a more
> helpful error in the logs.
> You can see what happens during the checkout with tail -f
> /var/log/koha/<instance>/*.log

Please ignore earlier reply. Following are the results of tail -f
/var/log/koha/library/*.log command while checking out book.
library at AgastiKohaTest:~$ tail -f /var/log/koha/library/*.log
==> /var/log/koha/library/api-error.log <==

==> /var/log/koha/library/indexer-error.log <==
20240109 00:55:46 library-koha-indexer: client (pid 1288) killed by signal 15,
stopping
20240109 09:10:38 library-koha-indexer: client (pid 1345) killed by signal 15,
stopping
20240109 10:17:30 library-koha-indexer: client (pid 1436) killed by signal 15,
stopping
20240109 10:31:13 library-koha-indexer: client (pid 1191) killed by signal 15,
stopping
20240109 14:11:36 library-koha-indexer: client (pid 1054) killed by signal 15,
stopping
20240109 14:58:01 library-koha-indexer: client (pid 1022) killed by signal 15,
stopping
20240110 16:13:39 library-koha-indexer: client (pid 1150) killed by signal 15,
stopping
20240113 13:37:08 library-koha-indexer: client (pid 1363) killed by signal 15,
stopping
20240116 15:37:47 library-koha-indexer: client (pid 1256) killed by signal 15,
stopping
20240117 13:53:31 library-koha-indexer: client (pid 1342) killed by signal 15,
stopping

==> /var/log/koha/library/indexer-output.log <==
Warning: script running in daemon mode, without recommended caching system
(memcached).
DBIx::Class::Storage::DBI::catch {...} (): DBI Connection failed: DBI
connect('database=koha_library;host=localhost;port=3306','koha_library',...)
failed: Can't connect to local MySQL server through socket
'/run/mysqld/mysqld.sock' (2) at /usr/share/koha/lib/Koha/Database.pm line 91.
at /usr/share/koha/lib/Koha/Database.pm line 139

Connection to the memcached servers '127.0.0.1:11211' failed. Are the unix
socket permissions set properly? Is the host reachable?
If you ignore this warning, you will face performance issues

Connection to the memcached servers '127.0.0.1:11211' failed. Are the unix
socket permissions set properly? Is the host reachable?
If you ignore this warning, you will face performance issues
Warning: script running in daemon mode, without recommended caching system
(memcached).
DBIx::Class::Storage::DBI::catch {...} (): DBI Connection failed: DBI
connect('database=koha_library;host=localhost;port=3306','koha_library',...)
failed: Can't connect to local MySQL server through socket
'/run/mysqld/mysqld.sock' (2) at /usr/share/koha/lib/Koha/Database.pm line 91.
at /usr/share/koha/lib/Koha/Database.pm line 139

==> /var/log/koha/library/intranet-error.log <==
[Wed Jan 17 10:59:25.609413 2024] [proxy:warn] [pid 81883] [client
10.0.2.2:51316] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be
library-intra.myDNSname.org for uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 10:59:25.609485 2024] [proxy:error] [pid 81883] (70014)End of file
found: [client 10.0.2.2:51316] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()
[Wed Jan 17 10:59:25.624587 2024] [proxy:warn] [pid 81884] [client
10.0.2.2:51317] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be
library-intra.myDNSname.org for uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 10:59:25.624645 2024] [proxy:error] [pid 81884] (70014)End of file
found: [client 10.0.2.2:51317] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()
[Wed Jan 17 13:08:27.100595 2024] [proxy:warn] [pid 91004] [client
10.0.2.2:48048] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be
library-intra.myDNSname.org for uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 13:08:27.100667 2024] [proxy:error] [pid 91004] (70014)End of file
found: [client 10.0.2.2:48048] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()
[Wed Jan 17 13:08:42.807839 2024] [proxy:warn] [pid 91020] [client
10.0.2.2:39694] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be
library-intra.myDNSname.org for uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 13:08:42.807894 2024] [proxy:error] [pid 91020] (70014)End of file
found: [client 10.0.2.2:39694] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()
[Wed Jan 17 13:08:52.742987 2024] [proxy:warn] [pid 91029] [client
10.0.2.2:48936] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be
library-intra.myDNSname.org for uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 13:08:52.743037 2024] [proxy:error] [pid 91029] (70014)End of file
found: [client 10.0.2.2:48936] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()

==> /var/log/koha/library/opac-error.log <==
[Wed Jan 17 10:39:21.370475 2024] [proxy:warn] [pid 80510] [client
10.0.2.2:65501] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be library.myDNSname.org for
uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 10:39:21.370541 2024] [proxy:error] [pid 80510] (70014)End of file
found: [client 10.0.2.2:65501] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()
[Wed Jan 17 10:39:21.472670 2024] [proxy:warn] [pid 80511] [client
10.0.2.2:65502] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be library.myDNSname.org for
uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 10:39:21.472737 2024] [proxy:error] [pid 80511] (70014)End of file
found: [client 10.0.2.2:65502] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()
[Wed Jan 17 10:39:21.572230 2024] [proxy:warn] [pid 80512] [client
10.0.2.2:65503] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be library.myDNSname.org for
uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 10:39:21.572281 2024] [proxy:error] [pid 80512] (70014)End of file
found: [client 10.0.2.2:65503] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()
[Wed Jan 17 10:39:21.672859 2024] [php7:error] [pid 80513] [client
10.0.2.2:65504] script '/usr/share/koha/opac/htdocs/wp-login.php' not found or
unable to stat
[Wed Jan 17 10:39:22.656233 2024] [php7:error] [pid 80517] [client
10.0.2.2:65508] script '/usr/share/koha/opac/htdocs/xmlrpc.php' not found or
unable to stat
[Wed Jan 17 12:13:30.951088 2024] [proxy:warn] [pid 87163] [client
10.0.2.2:16133] AH01092: no HTTP 0.9 request (with no host line) on incoming
request and preserve host set forcing hostname to be library.myDNSname.org for
uri /cgi-bin/koha/errors/400.pl
[Wed Jan 17 12:13:30.951159 2024] [proxy:error] [pid 87163] (70014)End of file
found: [client 10.0.2.2:16133] AH01095: prefetch request body failed to
0.0.0.0:0 (httpd-UDS) from 10.0.2.2 ()

==> /var/log/koha/library/plack-api-error.log <==
[2023/09/11 13:12:15] [WARN] Warning: Failed to fetch oauth configuration:
DBIx::Class::Storage::DBI::catch {...} (): DBI Connection failed: DBI
connect('database=koha_library;host=localhost;port=3306','koha_library',...)
failed: Can't connect to local MySQL server through socket
'/run/mysqld/mysqld.sock' (2) at /usr/share/koha/lib/Koha/Database.pm line 91.
at /usr/share/koha/lib/Koha/Database.pm line 139
[2023/09/11 13:12:16] [WARN] Warning: Failed to fetch oauth configuration:
DBIx::Class::Storage::DBI::catch {...} (): DBI Connection failed: DBI
connect('database=koha_library;host=localhost;port=3306','koha_library',...)
failed: Can't connect to local MySQL server through socket
'/run/mysqld/mysqld.sock' (2) at /usr/share/koha/lib/Koha/Database.pm line 91.
at /usr/share/koha/lib/Koha/Database.pm line 139

==> /var/log/koha/library/plack-error.log <==
2024/01/17-13:53:30 Received QUIT. Running a graceful shutdown
Sending children hup signal
2024/01/17-13:53:31 Worker processes cleaned up
2024/01/17-13:53:31 Server closing!
Process Backgrounded
2024/01/17-13:53:44 Starman::Server (type Net::Server::PreFork) starting!
pid(893)
Binding to UNIX socket file "/var/run/koha/library/plack.sock"
Setting gid to "1001 1001"
Setting uid to "1001"
DBIx::Class::Storage::DBI::_dbh_execute(): DBI Exception: DBD::mysql::st
execute failed: Unknown column 'me.booking_id' in 'field list' at
/usr/share/koha/lib/Koha/Objects.pm line 317

==> /var/log/koha/library/plack-intranet-error.log <==
[2024/01/17 10:14:06] [WARN] DBIx::Class::ResultSetColumn::new(): Attempting to
retrieve non-unique column 'biblionumber' on a resultset containing one-to-many
joins will return duplicate results. at /usr/share/koha/lib/Koha/Objects.pm
line 426
[2024/01/17 10:14:06] [WARN] DBIx::Class::ResultSet::_construct_results():
Unable to properly collapse has_many results in iterator mode due to order
criteria - performed an eager cursor slurp underneath. Consider using ->all()
instead at /usr/share/koha/lib/Koha/Objects.pm line 317
[2024/01/17 10:15:36] [WARN] DBIx::Class::ResultSetColumn::new(): Attempting to
retrieve non-unique column 'biblionumber' on a resultset containing one-to-many
joins will return duplicate results. at /usr/share/koha/lib/Koha/Objects.pm
line 426
[2024/01/17 10:59:45] [WARN] An appropriate email address for borrower
pardhirahulshivajicould not be found. at /usr/share/koha/lib/C4/Message.pm line
194.
[2024/01/17 12:29:55] [WARN] Use of uninitialized value in addition (+) at
/usr/share/koha/lib/C4/Biblio.pm line 3051.
[2024/01/17 12:30:05] [WARN] Use of uninitialized value in addition (+) at
/usr/share/koha/lib/C4/Biblio.pm line 3051.
[2024/01/17 12:30:05] [WARN] Use of uninitialized value in addition (+) at
/usr/share/koha/lib/C4/Biblio.pm line 3051.
[2024/01/17 12:30:08] [WARN] Use of uninitialized value in addition (+) at
/usr/share/koha/lib/C4/Biblio.pm line 3051.
[2024/01/17 12:30:10] [WARN] An appropriate email address for borrower
dhongaderohinidhawlacould not be found. at /usr/share/koha/lib/C4/Message.pm
line 194.
[2024/01/17 12:30:12] [WARN] Use of uninitialized value in addition (+) at
/usr/share/koha/lib/C4/Biblio.pm line 3051.

==> /var/log/koha/library/plack.log <==
10.0.2.2 - - [17/Jan/2024:13:57:54 +0530] "GET
/opac/opac-search.pl?q=su:%22nanoporous%20silicon%22 HTTP/1.1" 200 2072258
"http://lib.agasticollege.in:9000/cgi-bin/koha/opac-search.pl?q=su%3A%22antitumoral%20microRNA%20miRNA%22"
"Mozilla/5.0 (Linux; Android 7.0;) AppleWebKit/537.36 (KHTML, like Gecko)
Mobile Safari/537.36 (compatible;
PetalBot;+https://webmaster.petalsearch.com/site/petalbot)"
10.0.2.2 - - [17/Jan/2024:13:57:57 +0530] "GET /intranet/mainpage.pl HTTP/1.1"
200 1447702 "http://192.168.1.45:9090/cgi-bin/koha/circ/circulation.pl"
"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/120.0.0.0 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:57:59 +0530] "GET /intranet/mainpage.pl HTTP/1.1"
200 1439965 "http://192.168.1.45:9090/cgi-bin/koha/mainpage.pl" "Mozilla/5.0
(Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko)
Chrome/120.0.0.0 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:58:00 +0530] "GET
/opac/opac-detail.pl?biblionumber=62675 HTTP/1.1" 200 1507605 "-" "Mozilla/5.0
AppleWebKit/537.36 (KHTML, like Gecko; compatible; bingbot/2.0;
+http://www.bing.com/bingbot.htm) Chrome/116.0.1938.76 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:58:01 +0530] "GET /intranet/mainpage.pl HTTP/1.1"
200 1676277 "http://192.168.1.45:9090/cgi-bin/koha/mainpage.pl" "Mozilla/5.0
(Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko)
Chrome/120.0.0.0 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:58:33 +0530] "GET
/opac/opac-search.pl?q=su:%22Anti-Semitism%22 HTTP/1.1" 200 2158805 "-"
"Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; bingbot/2.0;
+http://www.bing.com/bingbot.htm) Chrome/116.0.1938.76 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:58:36 +0530] "GET
/opac/opac-detail.pl?biblionumber=61248 HTTP/1.1" 200 1488573 "-" "Mozilla/5.0
AppleWebKit/537.36 (KHTML, like Gecko; compatible; bingbot/2.0;
+http://www.bing.com/bingbot.htm) Chrome/116.0.1938.76 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:58:38 +0530] "GET
/opac/opac-detail.pl?biblionumber=33791 HTTP/1.1" 200 1917637 "-" "Mozilla/5.0
AppleWebKit/537.36 (KHTML, like Gecko; compatible; bingbot/2.0;
+http://www.bing.com/bingbot.htm) Chrome/116.0.1938.76 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:58:40 +0530] "GET
/opac/opac-detail.pl?biblionumber=77394 HTTP/1.1" 200 1746786 "-" "Mozilla/5.0
AppleWebKit/537.36 (KHTML, like Gecko; compatible; bingbot/2.0;
+http://www.bing.com/bingbot.htm) Chrome/116.0.1938.76 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:58:40 +0530] "GET /intranet/mainpage.pl HTTP/1.1"
200 1447188 "http://192.168.1.45:9090/cgi-bin/koha/mainpage.pl" "Mozilla/5.0
(Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko)
Chrome/120.0.0.0 Safari/537.36"

==> /var/log/koha/library/plack-opac-error.log <==
[2024/01/17 08:00:29] [WARN] Use of uninitialized value $subfieldvalue in
concatenation (.) or string at /usr/share/koha/lib/C4/Biblio.pm line 950.
[2024/01/17 08:20:39] [WARN] Argument "\x{968}\x{966}\x{968}\x{966}" isn't
numeric in numeric gt (>) at /usr/share/koha/lib/C4/Biblio.pm line 2406.
[2024/01/17 08:20:40] [WARN] Argument "\x{968}\x{966}\x{968}\x{966}" isn't
numeric in numeric gt (>) at /usr/share/koha/lib/C4/Biblio.pm line 2406.
[2024/01/17 08:51:09] [WARN] Use of uninitialized value $hits in numeric eq
(==) at /usr/share/koha/opac/cgi-bin/opac/opac-search.pl line 612.
[2024/01/17 08:51:11] [WARN] Use of uninitialized value $times in subtraction
(-) at /usr/share/koha/lib/C4/Search.pm line 1714.
[2024/01/17 10:27:29] [WARN] Use of uninitialized value $hits in numeric eq
(==) at /usr/share/koha/opac/cgi-bin/opac/opac-search.pl line 612.
[2024/01/17 10:27:29] [WARN] Use of uninitialized value $times in subtraction
(-) at /usr/share/koha/lib/C4/Search.pm line 1714.
[2024/01/17 13:53:30] [WARN] No method count found for Koha::Libraries
DBIx::Class::Storage::DBI::catch {...} (): DBI Connection failed: DBI
connect('database=koha_library;host=localhost;port=3306','koha_library',...)
failed: Can't connect to local MySQL server through socket
'/run/mysqld/mysqld.sock' (2) at /usr/share/koha/lib/Koha/Database.pm line 91.
at /usr/share/koha/lib/Koha/Objects.pm line 583
 at /usr/share/koha/lib/C4/Auth.pm line 472.
[2024/01/17 13:53:30] [WARN] Use of uninitialized value in numeric eq (==) at
/usr/share/koha/lib/C4/Auth.pm line 472.

==> /var/log/koha/library/sip.log <==

==> /var/log/koha/library/worker-error.log <==
20240109 14:58:01 library-koha-worker-long_tasks: client (pid 907) killed by
signal 15, stopping
20240110 16:09:13 library-koha-worker-long_tasks: client (pid 1102) exited with
2 status, respawning
20240110 16:13:39 library-koha-worker: client (pid 1030) killed by signal 15,
stopping
20240110 16:13:39 library-koha-worker-long_tasks: client (pid 116144) killed by
signal 15, stopping
20240113 13:37:08 library-koha-worker: client (pid 1160) killed by signal 15,
stopping
20240113 13:37:08 library-koha-worker-long_tasks: client (pid 1220) killed by
signal 15, stopping
20240116 15:37:47 library-koha-worker: client (pid 1127) killed by signal 15,
stopping
20240116 15:37:47 library-koha-worker-long_tasks: client (pid 1216) killed by
signal 15, stopping
20240117 13:53:31 library-koha-worker: client (pid 1181) killed by signal 15,
stopping
20240117 13:53:31 library-koha-worker-long_tasks: client (pid 1269) killed by
signal 15, stopping

==> /var/log/koha/library/worker-output.log <==
Cannot connect to the message broker, the jobs will be processed anyway (Failed
to connect: Error connecting to localhost:61613: Connection refused at
/usr/share/perl5/Net/Stomp.pm line 27.; giving up at
/usr/share/perl5/Net/Stomp.pm line 27.
) at /usr/share/koha/bin/workers/background_jobs_worker.pl line 92.
Cannot connect to the message broker, the jobs will be processed anyway (Failed
to connect: Error connecting to localhost:61613: Connection refused at
/usr/share/perl5/Net/Stomp.pm line 27.; giving up at
/usr/share/perl5/Net/Stomp.pm line 27.
) at /usr/share/koha/bin/workers/background_jobs_worker.pl line 92.
Cannot connect to the message broker, the jobs will be processed anyway (Failed
to connect: Error connecting to localhost:61613: Connection refused at
/usr/share/perl5/Net/Stomp.pm line 27.; giving up at
/usr/share/perl5/Net/Stomp.pm line 27.
) at /usr/share/koha/bin/workers/background_jobs_worker.pl line 92.
Cannot connect to the message broker, the jobs will be processed anyway (Failed
to connect: Error connecting to localhost:61613: Connection refused at
/usr/share/perl5/Net/Stomp.pm line 27.; giving up at
/usr/share/perl5/Net/Stomp.pm line 27.
) at /usr/share/koha/bin/workers/background_jobs_worker.pl line 92.
Cannot connect to the message broker, the jobs will be processed anyway (Failed
to connect: Error connecting to localhost:61613: Connection refused at
/usr/share/perl5/Net/Stomp.pm line 27.; giving up at
/usr/share/perl5/Net/Stomp.pm line 27.
) at /usr/share/koha/bin/workers/background_jobs_worker.pl line 92.

==> /var/log/koha/library/z3950-error.log <==

==> /var/log/koha/library/zebra-error.log <==

==> /var/log/koha/library/zebra-output.log <==

==> /var/log/koha/library/plack.log <==
10.0.2.2 - - [17/Jan/2024:13:58:52 +0530] "POST /intranet/circ/circulation.pl
HTTP/1.1" 200 1518266 "http://192.168.1.45:9090/cgi-bin/koha/mainpage.pl"
"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/120.0.0.0 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:58:52 +0530] "GET
/intranet/members/patronimage.pl?borrowernumber=4033 HTTP/1.1" 200 34312
"http://192.168.1.45:9090/cgi-bin/koha/circ/circulation.pl" "Mozilla/5.0
(Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko)
Chrome/120.0.0.0 Safari/537.36"

==> /var/log/koha/library/plack-error.log <==
DBIx::Class::Storage::DBI::_dbh_execute(): DBI Exception: DBD::mysql::st
execute failed: Unknown column 'me.booking_id' in 'field list' at
/usr/share/koha/lib/Koha/Objects.pm line 317

==> /var/log/koha/library/plack.log <==
10.0.2.2 - - [17/Jan/2024:13:58:55 +0530] "GET errors/500.pl HTTP/1.1" 500
1412713 "http://192.168.1.45:9090/cgi-bin/koha/circ/circulation.pl"
"Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like
Gecko) Chrome/120.0.0.0 Safari/537.36"
10.0.2.2 - - [17/Jan/2024:13:59:02 +0530] "GET
/opac/opac-detail.pl?biblionumber=38710 HTTP/1.1" 200 1749897 "-" "Mozilla/5.0
AppleWebKit/537.36 (KHTML, like Gecko; compatible; bingbot/2.0;
+http://www.bing.com/bingbot.htm) Chrome/116.0.1938.76 Safari/537.36"

-- 
You are receiving this mail because:
You are watching all bug changes.
You are the assignee for the bug.

More information about the Koha-bugs mailing list