EAD Export timeouts

[Robert M Smith]

I have a situation when trying to export certain EADs I get a 500 timeout.  My server specs are:

• Ubuntu 24.04
• 16 vCPU
• 24GB RAM
• Archivesspace 4.1.1 running zip installer
• Local Solr 9.10.1
• Mariadb 10.6
• innodb_buffer_pool_size=4G
• ASPACE_JAVA_XMX="-Xmx6144m"
• Solr heap ="Xmx6g"

The error I get is;


HTTP ERROR 500 Server Error
URI: /resources/1395/download_ead
STATUS: 500
MESSAGE: Server Error
SERVLET: default


Are there any other settings I can tweak?  Thanks for the help.

[Blake Carver]

I would think a 500 error is more of a "something busted" not "can't get it done in time" error? Could be different on your server though.

I'd check both the proxy logs and ArchivesSpace logs for errors, there's probably something there. ArchivesSpace tends to do a good job logging 500s.

---

From: 'Robert M Smith' via Archivesspace_Users_Group <Archivesspac...@lyrasislists.org>
Sent: Tuesday, April 7, 2026 1:08 PM
To: Archivesspace_Users_Group <archivesspac...@lyrasislists.org>
Subject: [ArchivesSpace Users Group] EAD Export timeouts

 

--
You received this message because you are subscribed to the Google Groups "Archivesspace_Users_Group" group.
To unsubscribe from this group and stop receiving emails from it, send an email to Archivesspace_User...@lyrasislists.org.
To view this discussion visit https://groups.google.com/a/lyrasislists.org/d/msgid/Archivesspace_Users_Group/aea3fe16-da07-41ab-921a-a06ebb84eb15n%40lyrasislists.org.

[Robert M Smith]

Thank you for the quick response.  From the archivesspace.out log file I'm seeing:

WARNING: error app failed to handle exception: org.jruby.exceptions.RuntimeError: (ReadTimeout) Net::ReadTimeout
org.jruby.exceptions.NoMethodError: (NoMethodError) undefined method `bytesize' for Rack::Utils:Module
at RUBY.call(uri:classloader:/jruby/rack/error_app/show_status.rb:30)
at org.jruby.rack.ext.Servlet.call(org/jruby/rack/ext/Servlet.java:83)

[Blake Carver]

Welp, I was wrong, that's a "can't get it done in time" error, assuming there's not something else happening around those errors.

Are those giant size resources? 

---

From: Robert M Smith <rsmi...@stanford.edu>
Sent: Tuesday, April 7, 2026 5:01 PM
To: Archivesspace_Users_Group <archivesspac...@lyrasislists.org>
Cc: Blake Carver <blake....@lyrasis.org>
Subject: Re: [ArchivesSpace Users Group] EAD Export timeouts

 

You don't often get email from rsmi...@stanford.edu. Learn why this is important

[Blake Carver]

 You'll probably want to bump up indexer_solr_timeout_seconds way up in your settings and see if that helps

---

From: 'Blake Carver' via Archivesspace_Users_Group <Archivesspac...@lyrasislists.org>
Sent: Tuesday, April 7, 2026 5:15 PM
To: Archivesspace_Users_Group <archivesspac...@lyrasislists.org>

To view this discussion visit https://groups.google.com/a/lyrasislists.org/d/msgid/Archivesspace_Users_Group/DM6PR22MB230964853070E707D3E3F8E59F5AA%40DM6PR22MB2309.namprd22.prod.outlook.com.

[Robert M Smith]

Yes, the ones that timeout are pretty big.  I'll try to up the indexer_solr_timeout.  Thanks for the suggestion.  

[Robert M Smith]

Just tried bumping it to 5000 seconds, but still hit the timeout.  Seems to be right at the 2 minute mark where the 500 message happens.

[Joshua D. Shaw]

We have an insanely large Solr timeout set.

For my local, non-containerized instance, I've got it set to 7200. This  is everything running on my mac from the local disk.

For our production, containerized instance..... wait for it.... 57600  Kubernetes with separate deployments for mariaDB, Solr, & AS itself. We think the timeout need to be quite so high (and it needed a dramatic increase with the AS 4.x versions) is down to the network and disk access lags in that setup. We may also be able to dial it back down when we update to 4.2 with some of the indexer changes made in that release.

For reference, we have about 20k resources, 750-800k AOs, & 40k top containers... plus the locations, dos, etc.

jds

---

From: 'Robert M Smith' via Archivesspace_Users_Group <Archivesspac...@lyrasislists.org>

Sent: Tuesday, April 7, 2026 5:38 PM
To: Archivesspace_Users_Group <archivesspac...@lyrasislists.org>
Cc: Robert M Smith <rsmi...@stanford.edu>; Blake Carver <blake....@lyrasis.org>

To view this discussion visit https://groups.google.com/a/lyrasislists.org/d/msgid/Archivesspace_Users_Group/e387264e-71d8-4c9a-9337-d83374c7c097n%40lyrasislists.org.

[Robert M Smith]

Wow, that is really large.  Thanks for the info.

I tried setting my stage server to your production setting but still hit the same timeout screen at exactly 2 minutes.  

[Joshua D. Shaw]

Is everything running on the same machine with no other networking in the way? Because it sounds like your error is really the app saying 'haven't gotten data in a while'. I've found that the Solr timeout is usually an issue during the indexing phase - not something like exporting an EAD file, so I'm thinking maybe something else going on.

Are there any other entries in the logs before that error that seem suspicious? Maybe check about a 2-3 minute window before that error? 

jds

---

From: Robert M Smith <rsmi...@stanford.edu>

Sent: Tuesday, April 7, 2026 6:40 PM
To: Archivesspace_Users_Group <archivesspac...@lyrasislists.org>
Cc: Joshua D. Shaw <Joshua...@dartmouth.edu>

[Robert M Smith]

Thanks for the continued help.  Yes, everything is running on the same machine.  No proxy running in front of AS, just connecting straight to it.  It's consistently timing out after 2 minutes.  Here's the log trace, and unfortunately nothing is showing up there.

I, [2026-04-08T09:37:17.733834 #4132]  INFO -- : Started GET "/resources/1395/download_ead?ead3=false&include_daos=true&include_unpublished=false&include_uris=true&numbered_cs=false" for 10.130.80.106 at 2026-04-08 09:37:17 -0700
I, [2026-04-08T09:37:17.738339 #4132]  INFO -- : Processing by ExportsController#download_ead as HTML
I, [2026-04-08T09:37:17.738548 #4132]  INFO -- :   Parameters: {"ead3"=>"false", "include_daos"=>"true", "include_unpublished"=>"false", "include_uris"=>"true", "numbered_cs"=>"false", "id"=>"1395"}
I, [2026-04-08T09:37:17.771521 #4132]  INFO -- : Completed 200 OK in 33ms (Allocations: 0)
I, [2026-04-08T09:37:27.050554 #4132]  INFO -- : Thread-6892: PUI Indexer [2026-04-08 09:37:27 -0700] Running index round
I, [2026-04-08T09:37:36.496656 #4132]  INFO -- : Thread-6892: PUI Indexer [2026-04-08 09:37:36 -0700] Index round complete
I, [2026-04-08T09:37:43.135316 #4132]  INFO -- : Thread-5984: Staff Indexer [2026-04-08 09:37:43 -0700] Running index round
I, [2026-04-08T09:37:52.059592 #4132]  INFO -- : Thread-5984: Staff Indexer [2026-04-08 09:37:52 -0700] Index round complete
I, [2026-04-08T09:38:06.497116 #4132]  INFO -- : Thread-6892: PUI Indexer [2026-04-08 09:38:06 -0700] Running index round
I, [2026-04-08T09:38:17.033231 #4132]  INFO -- : Thread-6892: PUI Indexer [2026-04-08 09:38:17 -0700] Index round complete
I, [2026-04-08T09:38:22.060165 #4132]  INFO -- : Thread-5984: Staff Indexer [2026-04-08 09:38:22 -0700] Running index round
I, [2026-04-08T09:38:32.383055 #4132]  INFO -- : Thread-5984: Staff Indexer [2026-04-08 09:38:32 -0700] Index round complete
I, [2026-04-08T09:38:47.033733 #4132]  INFO -- : Thread-6892: PUI Indexer [2026-04-08 09:38:47 -0700] Running index round
I, [2026-04-08T09:38:58.573242 #4132]  INFO -- : Thread-6892: PUI Indexer [2026-04-08 09:38:58 -0700] Index round complete
I, [2026-04-08T09:39:02.383500 #4132]  INFO -- : Thread-5984: Staff Indexer [2026-04-08 09:39:02 -0700] Running index round
I, [2026-04-08T09:39:12.633286 #4132]  INFO -- : Thread-5984: Staff Indexer [2026-04-08 09:39:12 -0700] Index round complete
Apr 08, 2026 9:39:17 AM org.eclipse.jetty.server.handler.ContextHandler$Context log
INFO: resetting rack response due exception
uri:classloader:/jruby/rack/error_app/show_status.rb:19: warning: Rack::Utils::HeaderHash is deprecated and will be removed in Rack 3.1, switch to Rack::Headers
Apr 08, 2026 9:39:17 AM org.eclipse.jetty.server.handler.ContextHandler$Context log

WARNING: error app failed to handle exception: org.jruby.exceptions.RuntimeError: (ReadTimeout) Net::ReadTimeout
org.jruby.exceptions.NoMethodError: (NoMethodError) undefined method `bytesize' for Rack::Utils:Module
at RUBY.call(uri:classloader:/jruby/rack/error_app/show_status.rb:30)
at org.jruby.rack.ext.Servlet.call(org/jruby/rack/ext/Servlet.java:83)

As a test, I also stood up a docker instance and imported the database into it, but still getting the same results.

[Blake Carver]

Can you run the logging on DEBUG and see if we can get something more useful in the logs?

---

From: 'Robert M Smith' via Archivesspace_Users_Group <Archivesspac...@lyrasislists.org>

Sent: Wednesday, April 8, 2026 12:44 PM

To view this discussion visit https://groups.google.com/a/lyrasislists.org/d/msgid/Archivesspace_Users_Group/09ffdf62-5ac2-42ac-874f-8ed8fd407672n%40lyrasislists.org.

[Robert M Smith]

Is there a way to get the logs just for that transaction?  When turning debug on it just returns thousands of lines similar to 

D, [2026-04-08T10:24:01.449932 #283335] DEBUG -- : Thread-6136: GET /repositories/20/archival_objects?all_ids=true&modified_since=0 [session: #<Session:0x63c65fcb @store={:user=>"search_indexer", :login_time=>2026-04-08 10:19:51.061883 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:00 UTC, @id="a2eed9d4e96e847ab0e871e05fe635bcdfb604e9754ab832aecc5edb1f48dfc7">]
D, [2026-04-08T10:24:01.452766 #283335] DEBUG -- : Thread-6136: Post-processed params: {"all_ids"=>true, "modified_since"=>0, "repo_id"=>20, "sort_field"=>:id, "sort_direction"=>:asc}
D, [2026-04-08T10:24:01.464882 #283335] DEBUG -- : Thread-6136: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["[]\n"]]... in 18ms

And they are going past the screen too fast to read, so it's hard to tell if anything new or useful comes out.

[Blake Carver]

Probably better to just look for trouble, something like:

grep -E -C 12 'ERROR|FATAL' archivesspace.out

---

From: Robert M Smith <rsmi...@stanford.edu>

Sent: Wednesday, April 8, 2026 1:33 PM

[Robert M Smith]

Thanks for the tip.  Nothing to report other than from during startup:

/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:39: warning: already initialized constant Rack::GET
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:40: warning: already initialized constant Rack::POST
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:41: warning: already initialized constant Rack::PUT
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:42: warning: already initialized constant Rack::PATCH
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:43: warning: already initialized constant Rack::DELETE
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:44: warning: already initialized constant Rack::HEAD
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:45: warning: already initialized constant Rack::OPTIONS
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:46: warning: already initialized constant Rack::LINK
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:47: warning: already initialized constant Rack::UNLINK
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:48: warning: already initialized constant Rack::TRACE
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:51: warning: already initialized constant Rack::RACK_VERSION
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:52: warning: already initialized constant Rack::RACK_TEMPFILES
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:53: warning: already initialized constant Rack::RACK_ERRORS
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:54: warning: already initialized constant Rack::RACK_LOGGER
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:55: warning: already initialized constant Rack::RACK_INPUT
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:56: warning: already initialized constant Rack::RACK_SESSION
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:57: warning: already initialized constant Rack::RACK_SESSION_OPTIONS
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:58: warning: already initialized constant Rack::RACK_SHOWSTATUS_DETAIL
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:62: warning: already initialized constant Rack::RACK_URL_SCHEME
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:63: warning: already initialized constant Rack::RACK_HIJACK
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:64: warning: already initialized constant Rack::RACK_IS_HIJACK
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:66: warning: already initialized constant Rack::RACK_RECURSIVE_INCLUDE
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:67: warning: already initialized constant Rack::RACK_MULTIPART_BUFFER_SIZE
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:68: warning: already initialized constant Rack::RACK_MULTIPART_TEMPFILE_FACTORY
/opt/app/asuser/archivesspace-v4.1.1/archivesspace/gems/gems/rack-2.2.9/lib/rack.rb:69: warning: already initialized constant Rack::RACK_REQUEST_FORM_INPUT

[Blake Carver]

Is it not doing the ReadTimeout thing now?

grep -E -C 25 'ReadTimeout' archivesspace.out

grep -E -C 12 'ERROR|FATAL' archivesspace.out

From: Robert M Smith <rsmi...@stanford.edu>

Sent: Wednesday, April 8, 2026 1:59 PM

[Robert M Smith]

No, it's still there:

D, [2026-04-08T10:24:01.474078 #283335] DEBUG -- : Thread-6684: GET /repositories/20/digital_object_components?all_ids=true&modified_since=0 [session: #<Session:0x7d12adc4 @store={:user=>"search_indexer", :login_time=>2026-04-08 10:19:51.061883 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:00 UTC, @id="a2eed9d4e96e847ab0e871e05fe635bcdfb604e9754ab832aecc5edb1f48dfc7">]
D, [2026-04-08T10:24:01.477624 #283335] DEBUG -- : Thread-6684: Post-processed params: {"all_ids"=>true, "modified_since"=>0, "repo_id"=>20, "sort_field"=>:id, "sort_direction"=>:asc}
D, [2026-04-08T10:24:01.489942 #283335] DEBUG -- : Thread-6684: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["[]\n"]]... in 19ms
D, [2026-04-08T10:24:01.497523 #283335] DEBUG -- : Thread-6136: GET /repositories/20/classification_terms?all_ids=true&modified_since=0 [session: #<Session:0x4e64dce5 @store={:user=>"search_indexer", :login_time=>2026-04-08 10:19:51.061883 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:00 UTC, @id="a2eed9d4e96e847ab0e871e05fe635bcdfb604e9754ab832aecc5edb1f48dfc7">]
D, [2026-04-08T10:24:01.500396 #283335] DEBUG -- : Thread-6136: Post-processed params: {"all_ids"=>true, "modified_since"=>0, "repo_id"=>20, "sort_field"=>:id, "sort_direction"=>:asc}
D, [2026-04-08T10:24:01.508916 #283335] DEBUG -- : Thread-6136: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["[]\n"]]... in 14ms
D, [2026-04-08T10:24:01.517304 #283335] DEBUG -- : Thread-6684: GET /repositories/20/assessments?all_ids=true&modified_since=0 [session: #<Session:0x2bf5d267 @store={:user=>"search_indexer", :login_time=>2026-04-08 10:19:51.061883 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:00 UTC, @id="a2eed9d4e96e847ab0e871e05fe635bcdfb604e9754ab832aecc5edb1f48dfc7">]
D, [2026-04-08T10:24:01.520659 #283335] DEBUG -- : Thread-6684: Post-processed params: {"all_ids"=>true, "modified_since"=>0, "repo_id"=>20, "sort_field"=>:id, "sort_direction"=>:asc}
D, [2026-04-08T10:24:01.530045 #283335] DEBUG -- : Thread-6684: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["[]\n"]]... in 15ms
D, [2026-04-08T10:24:01.538269 #283335] DEBUG -- : Thread-6136: GET /repositories/20/jobs?all_ids=true&modified_since=0 [session: #<Session:0xa126430 @store={:user=>"search_indexer", :login_time=>2026-04-08 10:19:51.061883 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:00 UTC, @id="a2eed9d4e96e847ab0e871e05fe635bcdfb604e9754ab832aecc5edb1f48dfc7">]
D, [2026-04-08T10:24:01.542514 #283335] DEBUG -- : Thread-6136: Post-processed params: {"all_ids"=>true, "modified_since"=>0, "repo_id"=>20, "sort_field"=>:id, "sort_direction"=>:asc}
D, [2026-04-08T10:24:01.554897 #283335] DEBUG -- : Thread-6136: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["[]\n"]]... in 19ms
D, [2026-04-08T10:24:01.564685 #283335] DEBUG -- : Thread-6684: GET /delete-feed?modified_since=1775598544&page=1&page_size=25 [session: #<Session:0x1b0ed59e @store={:user=>"search_indexer", :login_time=>2026-04-08 10:19:51.061883 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:00 UTC, @id="a2eed9d4e96e847ab0e871e05fe635bcdfb604e9754ab832aecc5edb1f48dfc7">]
D, [2026-04-08T10:24:01.568181 #283335] DEBUG -- : Thread-6684: Post-processed params: {"modified_since"=>1775598544, "page"=>1, "page_size"=>25, "sort_field"=>:id, "sort_direction"=>:asc}
D, [2026-04-08T10:24:01.688015 #283335] DEBUG -- : Thread-6684: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"58"}, ["{\"first_page\":1,\"last_page\":1,\"this_page\":1,\"results\":[]}\n"]]... in 127ms
I, [2026-04-08T10:24:01.689710 #283335]  INFO -- : Thread-5984: Staff Indexer [2026-04-08 10:24:01 -0700] Index round complete
D, [2026-04-08T10:24:04.455562 #283335] DEBUG -- : Thread-6944: POST /update_monitor [session: #<Session:0x58963221 @store={:user=>"staff_system", :login_time=>2026-04-08 10:20:14.636053 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:23:55 UTC, @id="b2a7e593d32628fa6080131957e0f05f31c3c13b84412bbae3cbc076841fda38">]
D, [2026-04-08T10:24:04.459230 #283335] DEBUG -- : Thread-6944: Post-processed params: {"active_edits"=>#<JSONModel(:active_edits) {"jsonmodel_type"=>"active_edits", "active_edits"=>[]}>}
D, [2026-04-08T10:24:04.465748 #283335] DEBUG -- : Thread-6944: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["{}\n"]]... in 13ms
Apr 08, 2026 10:24:06 AM org.eclipse.jetty.server.handler.ContextHandler$Context log

INFO: resetting rack response due exception

uri:classloader:/vendor/rack-3.0.11/rack/chunked.rb:6: warning: Rack::Chunked is deprecated and will be removed in Rack 3.1
uri:classloader:/vendor/rack-3.0.11/rack/file.rb:5: warning: Rack::File is deprecated and will be removed in Rack 3.1

uri:classloader:/jruby/rack/error_app/show_status.rb:19: warning: Rack::Utils::HeaderHash is deprecated and will be removed in Rack 3.1, switch to Rack::Headers

Apr 08, 2026 10:24:07 AM org.eclipse.jetty.server.handler.ContextHandler$Context log

WARNING: error app failed to handle exception: org.jruby.exceptions.RuntimeError: (ReadTimeout) Net::ReadTimeout
org.jruby.exceptions.NoMethodError: (NoMethodError) undefined method `bytesize' for Rack::Utils:Module
at RUBY.call(uri:classloader:/jruby/rack/error_app/show_status.rb:30)
at org.jruby.rack.ext.Servlet.call(org/jruby/rack/ext/Servlet.java:83)

D, [2026-04-08T10:24:14.454635 #283335] DEBUG -- : Thread-5996: POST /update_monitor [session: #<Session:0x4bb8a81c @store={:user=>"staff_system", :login_time=>2026-04-08 10:20:14.636053 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:05 UTC, @id="b2a7e593d32628fa6080131957e0f05f31c3c13b84412bbae3cbc076841fda38">]
D, [2026-04-08T10:24:14.460798 #283335] DEBUG -- : Thread-5996: Post-processed params: {"active_edits"=>#<JSONModel(:active_edits) {"jsonmodel_type"=>"active_edits", "active_edits"=>[]}>}
D, [2026-04-08T10:24:14.468782 #283335] DEBUG -- : Thread-5996: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["{}\n"]]... in 17ms
D, [2026-04-08T10:24:14.851840 #283335] DEBUG -- : Thread-6844: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["[]\n"]]... in 60018ms
D, [2026-04-08T10:24:14.863551 #283335] DEBUG -- : Thread-6156: GET /update-feed?last_sequence=1775668797&resolve[]=location_profile&resolve[]=container_profile&resolve[]=container_locations&resolve[]=subjects&resolve[]=places&resolve[]=linked_agents&resolve[]=linked_records&resolve[]=classifications&resolve[]=digital_object&resolve[]=agent_representation&resolve[]=repository&resolve[]=repository::agent_representation&resolve[]=related_agents&resolve[]=top_container&resolve[]=top_container::container_profile&resolve[]=related_agents&resolve[]=records&resolve[]=collections&resolve[]=surveyed_by&resolve[]=reviewer&resolve[]=creator&resolve[]=related_accessions&resolve[]=notes [session: #<Session:0x70a55cb2 @store={:user=>"search_indexer", :login_time=>2026-04-08 10:19:56.08298 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:23:15 UTC, @id="92944596b18f1757aa8a9ed1ded25fe509f645ce93b41cb45f6a5384d898eb15">]
D, [2026-04-08T10:24:14.866707 #283335] DEBUG -- : Thread-6156: Post-processed params: {"last_sequence"=>1775668797, "resolve"=>["location_profile", "container_profile", "container_locations", "subjects", "places", "linked_agents", "linked_records", "classifications", "digital_object", "agent_representation", "repository", "repository::agent_representation", "related_agents", "top_container", "top_container::container_profile", "related_agents", "records", "collections", "surveyed_by", "reviewer", "creator", "related_accessions", "notes"]}
D, [2026-04-08T10:24:17.278899 #283335] DEBUG -- : Thread-6848: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["[]\n"]]... in 60007ms
I, [2026-04-08T10:24:20.214511 #283335]  INFO -- : Thread-6832: PUI Indexer [2026-04-08 10:24:20 -0700] Running index round
D, [2026-04-08T10:24:20.224035 #283335] DEBUG -- : Thread-5988: GET /repositories?resolve%5B%5D=location_profile&resolve%5B%5D=container_profile&resolve%5B%5D=container_locations&resolve%5B%5D=subjects&resolve%5B%5D=places&resolve%5B%5D=linked_agents&resolve%5B%5D=linked_records&resolve%5B%5D=classifications&resolve%5B%5D=digital_object&resolve%5B%5D=agent_representation&resolve%5B%5D=repository&resolve%5B%5D=repository%3A%3Aagent_representation&resolve%5B%5D=related_agents&resolve%5B%5D=top_container&resolve%5B%5D=top_container%3A%3Acontainer_profile&resolve%5B%5D=related_agents&resolve%5B%5D=records&resolve%5B%5D=collections&resolve%5B%5D=surveyed_by&resolve%5B%5D=reviewer&resolve%5B%5D=creator&resolve%5B%5D=related_accessions&resolve%5B%5D=notes&resolve%5B%5D=ancestors&resolve%5B%5D=ancestors%3A%3Alinked_agents&resolve%5B%5D=ancestors%3A%3Asubjects [session: #<Session:0x255ff482 @store={:user=>"search_indexer", :login_time=>2026-04-08 10:20:21.009911 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:23:50 UTC, @id="f29f33ba8cecebd59d203dde3c18f7a9a45a5136c8b9bd4ce48c9f89187ec1f2">]
D, [2026-04-08T10:24:20.227629 #283335] DEBUG -- : Thread-5988: Post-processed params: {"resolve"=>["location_profile", "container_profile", "container_locations", "subjects", "places", "linked_agents", "linked_records", "classifications", "digital_object", "agent_representation", "repository", "repository::agent_representation", "related_agents", "top_container", "top_container::container_profile", "related_agents", "records", "collections", "surveyed_by", "reviewer", "creator", "related_accessions", "notes", "ancestors", "ancestors::linked_agents", "ancestors::subjects"]}
D, [2026-04-08T10:24:22.293192 #283335] DEBUG -- : Thread-7084: GET /notifications?last_sequence=1775668782 [session: nil]
D, [2026-04-08T10:24:22.298028 #283335] DEBUG -- : Thread-7084: Post-processed params: {"last_sequence"=>1775668782}
D, [2026-04-08T10:24:24.457195 #283335] DEBUG -- : Thread-5996: POST /update_monitor [session: #<Session:0x2540eda9 @store={:user=>"staff_system", :login_time=>2026-04-08 10:20:14.636053 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:15 UTC, @id="b2a7e593d32628fa6080131957e0f05f31c3c13b84412bbae3cbc076841fda38">]
D, [2026-04-08T10:24:24.461650 #283335] DEBUG -- : Thread-5996: Post-processed params: {"active_edits"=>#<JSONModel(:active_edits) {"jsonmodel_type"=>"active_edits", "active_edits"=>[]}>}
D, [2026-04-08T10:24:24.467164 #283335] DEBUG -- : Thread-5996: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["{}\n"]]... in 12ms
D, [2026-04-08T10:24:24.708588 #283335] DEBUG -- : Thread-5988: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"46760"}, ["[{\"lock_version\":6,\"repo_code\":\"SPECCOLL\",\"name\":\"Department of Special Collections and University Archives\",\"org_code\":\"CSt\",\"parent_institution_name\":\"Stanford University. Libraries.\",\"url\":\"https://library.stanford.edu/libraries/special-collections\",\"created_by\":\"admin\",\"last_modified_by\":\"arclight_sul_app_prod\",\"create_time\":\"2015-08-27T01:12:11... in 4487ms
D, [2026-04-08T10:24:24.728053 #283335] DEBUG -- : Thread-6848: GET /repositories/2/archival_objects?all_ids=true&modified_since=1775597923 [session: #<Session:0x552c1bee @store={:user=>"search_indexer", :login_time=>2026-04-08 10:20:21.009911 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:20 UTC, @id="f29f33ba8cecebd59d203dde3c18f7a9a45a5136c8b9bd4ce48c9f89187ec1f2">]
D, [2026-04-08T10:24:24.730705 #283335] DEBUG -- : Thread-6848: Post-processed params: {"all_ids"=>true, "modified_since"=>1775597923, "repo_id"=>2, "sort_field"=>:id, "sort_direction"=>:asc}
D, [2026-04-08T10:24:24.738655 #283335] DEBUG -- : Thread-6848: Responded with [200, {"cache-control"=>"private, must-revalidate, max-age=0", "content-type"=>"application/json", "content-length"=>"3"}, ["[]\n"]]... in 13ms
D, [2026-04-08T10:24:24.745756 #283335] DEBUG -- : Thread-5988: GET /repositories/2/resources?all_ids=true&modified_since=1775597923 [session: #<Session:0x7ad9b7c3 @store={:user=>"search_indexer", :login_time=>2026-04-08 10:20:21.009911 -0700, :expirable=>false}, @system_mtime=2026-04-08 17:24:20 UTC, @id="f29f33ba8cecebd59d203dde3c18f7a9a45a5136c8b9bd4ce48c9f89187ec1f2">]
D, [2026-04-08T10:24:24.749209 #283335] DEBUG -- : Thread-5988: Post-processed params: {"all_ids"=>true, "modified_since"=>1775597923, "repo_id"=>2, "sort_field"=>:id, "sort_direction"=>:asc}

On Wednesday, April 8, 2026 at 11:06:42 AM UTC-7 Blake Carver wrote:

Is it not doing the ReadTimeout thing now?

grep -E -C 25 'ReadTimeout' archivesspace.out

grep -E -C 12 'ERROR|FATAL' archivesspace.out

[Blake Carver]

Time to check Solr and your Proxy. 

Any errors in Solr? Crash, fatal, error?

What about in your proxy? If it is always failing at 2 minutes, I bet the proxy has a 2 minute timeout.

---

From: Robert M Smith <rsmi...@stanford.edu>

Sent: Wednesday, April 8, 2026 2:34 PM

[Robert M Smith]

Nothing comes up in Solr logs.  I'm running the zip installer without any proxy in front of it.  Is there built-in Jetty configs I can look at?

[Joshua D. Shaw]

How about the mariadb logs? Anything in those? Usually there's a bunch more logging in the AS logs around the specific error, so I'm surprised that its just that single error popping up.

Is this a huge collection? Perhaps its a weird OOM issue?

jds

---

From: 'Robert M Smith' via Archivesspace_Users_Group <Archivesspac...@lyrasislists.org>

Sent: Wednesday, April 8, 2026 5:55 PM

To view this discussion visit https://groups.google.com/a/lyrasislists.org/d/msgid/Archivesspace_Users_Group/da02f247-1096-4808-9de6-06c3de72e644n%40lyrasislists.org.

[Robert M Smith]

Unfortunately no clues given in the mariadb logs.  I even tried increasing the jdbc connection timeout using &connectTimeout=180000&socketTimeout=180000 but it still went to error after 2 minutes.

I'm only the sysadmin for the system, not a user, but it does appear it's a rather large collection.  I don't think I'm giving anything away by showing the collection info at https://oac.cdlib.org/findaid/ark:/13030/kt4p3021rd

[Laurin Penland]

How are you exporting the EAD?

To view this discussion visit https://groups.google.com/a/lyrasislists.org/d/msgid/Archivesspace_Users_Group/484fe164-7d9e-44fe-8ae1-302fece7c1c5n%40lyrasislists.org.

[Robert M Smith]

I'm doing the EAD export from the GUI.  Going to the collection page, clicking export, then choosing EAD.

[Blake Carver]

It always timesout at exactly 2 minutes?

---

From: Robert M Smith <rsmi...@stanford.edu>

Sent: Thursday, April 9, 2026 12:50 PM
To: Archivesspace_Users_Group <archivesspac...@lyrasislists.org>
Cc: Laurin Penland <lpen...@brynmawr.edu>; Joshua D. Shaw <Joshua...@dartmouth.edu>; Blake Carver <blake....@lyrasis.org>; Robert M Smith <Archivesspac...@lyrasislists.org>; Robert M Smith <rsmi...@stanford.edu>

[Robert M Smith]

Yes, 2 minutes exactly and then the 500 page with the timeout message in the logs.

[Jessica Crouch]

Hi Robert,

I think it makes sense to go ahead and move this over a tech support ticket so someone (probably Blake) can work with you directly on this.  You’ll see that as a separate email in just a minute. 

If you have any other questions or if anyone else has tech support needs, you can always reach the program team directly at Archives...@lyrasis.org. 

Thanks,

Jessica Crouch

Jessica Dowd Crouch

Community Engagement Lead for ArchivesSpace

jessica...@lyrasis.org

 

 

 

To view this discussion visit https://groups.google.com/a/lyrasislists.org/d/msgid/Archivesspace_Users_Group/28d49520-c995-4363-ab91-742dd63e25f0n%40lyrasislists.org.