Bug 35697 - Checking out books causes an error 500
Summary: Checking out books causes an error 500
Status: RESOLVED DUPLICATE of bug 35473
Alias: None
Product: Koha
Classification: Unclassified
Component: Circulation (show other bugs)
Version: 23.11
Hardware: All All
: P5 - low enhancement with 1 vote (vote)
Assignee: Bugs List
QA Contact: Testopia
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2024-01-04 06:55 UTC by Jaypee Pinpin
Modified: 2024-01-22 10:03 UTC (History)
3 users (show)

See Also:
Change sponsored?: ---
Patch complexity: ---
Documentation contact:
Documentation submission:
Text to go in the release notes:
Version(s) released in:


Attachments
Error 500 upon check out of book/library material (69.84 KB, image/png)
2024-01-04 06:55 UTC, Jaypee Pinpin
Details
logs from instance (12.04 KB, text/plain)
2024-01-16 10:43 UTC, Pradeep
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Jaypee Pinpin 2024-01-04 06:55:52 UTC
Created attachment 160509 [details]
Error 500 upon check out of book/library material

KOHA VER. 23.11.00 - January update
Checkingout books causes a error 500,
pressing the "Check out" botton with a blank barcode causes to redirect to print issuequick slip.

I tested this on our system, and replicated the same issue on KOHA Demo website.
Comment 1 Katrin Fischer 2024-01-04 09:48:55 UTC
Please verify that your item has complete information. It needs:

* an itemtype (MARC21 $y)
* a homebranch (MARC21 $a)
* a holdingbranch (MARC21 $b)

If the item is complete, it would help to get the full error from the server logs.
Comment 2 Pradeep 2024-01-09 05:48:31 UTC
I also experienced same problem.
all other functions are working fine.

While checking out it gives 500 error.
Comment 3 Katrin Fischer 2024-01-09 07:30:24 UTC
(In reply to Katrin Fischer from comment #1)
> Please verify that your item has complete information. It needs:
> 
> * an itemtype (MARC21 $y)
> * a homebranch (MARC21 $a)
> * a holdingbranch (MARC21 $b)
> 
> If the item is complete, it would help to get the full error from the server
> logs.

Did you check these?
Comment 4 Pradeep 2024-01-10 10:06:27 UTC
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.
Comment 5 Pradeep 2024-01-10 10:08:20 UTC
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.
Comment 6 Katrin Fischer 2024-01-13 16:13:53 UTC
(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
Comment 7 Pradeep 2024-01-16 10:36:18 UTC
Log file is so big but I have copied it from Plack.log. I cannot understand which log to check. 

10.0.2.2 - - [16/Jan/2024:15:49:33 +0530] "GET /api/v1/app.pl/api/v1/patrons?_page=1&_per_page=10&q=%5B%7B%22-and%22%3A%5B%5B%7B%22me.firstname%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.middle_name%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.surname%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.othernames%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.cardnumber%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.userid%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%5D%2C%5B%7B%22me.firstname%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.middle_name%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.surname%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.othernames%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.cardnumber%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.userid%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%5D%5D%7D%2C%7B%22-or%22%3A%5B%7B%22me.firstname%22%3A%7B%22like%22%3A%22ghule%20pravin%20%25%22%7D%7D%2C%7B%22me.middle_name%22%3A%7B%22like%22%3A%22ghule%20pravin%20%25%22%7D%7D%2C%7B%22me.surname%22%3A%7B%22like%22%3A%22ghule%20pravin%20%25%22%7D%7D%2C%7B%22me.othernames%22%3A%7B%22like%22%3A%22ghule%20pravin%20%25%22%7D%7D%2C%7B%22me.cardnumber%22%3A%7B%22like%22%3A%22ghule%20pravin%20%25%22%7D%7D%2C%7B%22me.userid%22%3A%7B%22like%22%3A%22ghule%20pravin%20%25%22%7D%7D%5D%7D%5D&_order_by=%2Bme.surname%2C%2Bme.firstname HTTP/1.1" 200 - "http://117.217.122.41: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 - - [16/Jan/2024:15:49:35 +0530] "GET /api/v1/app.pl/api/v1/patrons?_page=1&_per_page=10&q=%5B%7B%22-and%22%3A%5B%5B%7B%22me.firstname%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.middle_name%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.surname%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.othernames%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.cardnumber%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%2C%7B%22me.userid%22%3A%7B%22like%22%3A%22ghule%25%22%7D%7D%5D%2C%5B%7B%22me.firstname%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.middle_name%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.surname%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.othernames%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.cardnumber%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%2C%7B%22me.userid%22%3A%7B%22like%22%3A%22pravin%25%22%7D%7D%5D%2C%5B%7B%22me.firstname%22%3A%7B%22like%22%3A%22ker%25%22%7D%7D%2C%7B%22me.middle_name%22%3A%7B%22like%22%3A%22ker%25%22%7D%7D%2C%7B%22me.surname%22%3A%7B%22like%22%3A%22ker%25%22%7D%7D%2C%7B%22me.othernames%22%3A%7B%22like%22%3A%22ker%25%22%7D%7D%2C%7B%22me.cardnumber%22%3A%7B%22like%22%3A%22ker%25%22%7D%7D%2C%7B%22me.userid%22%3A%7B%22like%22%3A%22ker%25%22%7D%7D%5D%5D%7D%2C%7B%22-or%22%3A%5B%7B%22me.firstname%22%3A%7B%22like%22%3A%22ghule%20pravin%20ker%25%22%7D%7D%2C%7B%22me.middle_name%22%3A%7B%22like%22%3A%22ghule%20pravin%20ker%25%22%7D%7D%2C%7B%22me.surname%22%3A%7B%22like%22%3A%22ghule%20pravin%20ker%25%22%7D%7D%2C%7B%22me.othernames%22%3A%7B%22like%22%3A%22ghule%20pravin%20ker%25%22%7D%7D%2C%7B%22me.cardnumber%22%3A%7B%22like%22%3A%22ghule%20pravin%20ker%25%22%7D%7D%2C%7B%22me.userid%22%3A%7B%22like%22%3A%22ghule%20pravin%20ker%25%22%7D%7D%5D%7D%5D&_order_by=%2Bme.surname%2C%2Bme.firstname HTTP/1.1" 200 - "http://117.217.122.41: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 - - [16/Jan/2024:15:49:38 +0530] "GET /intranet/circ/circulation.pl?borrowernumber=2 HTTP/1.1" 200 1780413 "http://117.217.122.41: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 - - [16/Jan/2024:15:49:38 +0530] "GET /intranet/members/patronimage.pl?borrowernumber=2 HTTP/1.1" 200 37175 "http://117.217.122.41:9090/cgi-bin/koha/circ/circulation.pl?borrowernumber=2" "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 - - [16/Jan/2024:15:49:42 +0530] "GET /opac/opac-detail.pl?biblionumber=54915 HTTP/1.1" 200 1741066 "-" "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 - - [16/Jan/2024:15:49:43 +0530] "GET errors/500.pl HTTP/1.1" 500 1657645 "http://117.217.122.41:9090/cgi-bin/koha/circ/circulation.pl?borrowernumber=2" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36"
Comment 8 Pradeep 2024-01-16 10:43:22 UTC
Created attachment 161057 [details]
logs from instance

This is from various logs.
Comment 9 Pradeep 2024-01-17 08:40:42 UTC
(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@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"
Comment 10 Katrin Fischer 2024-01-17 10:27:20 UTC
There is definitely some stuff there that doesn't look great.

This here is something we coudl start with:
==> /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

Do you have the room reservation plugin installed or had it in stalled?

Please check if you have a table bookings with a column booking_id.
Comment 11 Pradeep 2024-01-22 09:31:48 UTC
(In reply to Katrin Fischer from comment #10)
> There is definitely some stuff there that doesn't look great.
> 
> This here is something we coudl start with:
> ==> /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
> 
> Do you have the room reservation plugin installed or had it in stalled?
> 
> Please check if you have a table bookings with a column booking_id.

Yes, I had installed room reservation plugin and later deleted it after testing.
I have cleared data from bookings table and also deleted booking table, room reservation table but both attempts failed to solve error 500.
Comment 12 Katrin Fischer 2024-01-22 10:03:11 UTC
This is the problem you are seeing:
Bug 35473 - Core bookings and room reservations plugin tables clash

We added a bookings table to Koha not aware that the plugin didn't follow naming conventions and already added a table of the same name.

My guess is that you need to rerun the database update manually (the SQL from it) that would create the table Koha expects: installer/data/mysql/db_revs/230600061.pl

*** This bug has been marked as a duplicate of bug 35473 ***