Debug | Loaded extensions

[airled]

Hi. I run Puma process with PUMA_DEBUG=1. In my puma logs I see that puma loads extensions. Here is the log:

Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] Puma starting in cluster mode...
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] * Puma version: 6.4.0 (ruby 3.1.0-p0) ("The Eagle of Durango")
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] *  Min threads: 2
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] *  Max threads: 4
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] *  Environment: staging
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] *   Master PID: 1305489
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] *      Workers: 2
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] *     Restarts: (✔) hot (✔) phased
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] * Listening on unix://tmp/sockets/puma.sock
Nov 18 05:51:55 conft-stage bundle[1305489]: [1305489] Use Ctrl-C to stop
Nov 18 05:51:55 conft-stage bundle[1305528]: [1305528] + Gemfile in context: /home/deploy/stage/releases/551/Gemfile
Nov 18 05:51:55 conft-stage bundle[1305530]: [1305530] + Gemfile in context: /home/deploy/stage/releases/551/Gemfile
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] - Worker 1 (PID: 1305530) booted in 2.92s, phase: 0
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] - Worker 0 (PID: 1305528) booted in 2.93s, phase: 0
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] % ────────────────────────────────── Loaded Extensions - worker 0:
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     enumerator.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     fiber.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     rational.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     complex.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/enc/encdb.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/enc/trans/transdb.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/monitor.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/socket.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/io/wait.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/etc.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/stringio.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/puma-6.4.0/lib/puma/puma_http11.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/pathname.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/msgpack-1.7.2/lib/msgpack/msgpack.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/bootsnap-1.17.0/lib/bootsnap/bootsnap.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/zlib.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/psych.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/strscan.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/date-3.3.4/lib/date_core.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/json/ext/parser.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/json/ext/generator.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/cgi/escape.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/digest.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/openssl-3.2.0/lib/openssl.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/io/nonblock.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/bigdecimal.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/racc-1.7.3/lib/racc/cparse.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/rbsecp256k1-6.0.0/ext/rbsecp256k1/rbsecp256k1.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/ffi-1.16.3/lib/ffi_c.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/keccak-1.3.1/lib/digest/keccak.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/oj-3.16.1/lib/oj/oj.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/pg-1.5.4/lib/pg_ext.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/nokogiri-1.15.5-x86_64-linux/lib/nokogiri/3.1/nokogiri.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/digest/sha1.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/digest/sha2.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/enc/utf_16le.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/enc/utf_16be.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/io/console.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/ripper.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/digest/md5.so
Nov 18 05:51:58 conft-stage bundle[1305528]: [1305528] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/nio4r-2.6.0/lib/nio4r_ext.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] % ────────────────────────────────── Loaded Extensions - master:
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     enumerator.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     fiber.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     rational.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     complex.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/enc/encdb.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/enc/trans/transdb.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/monitor.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/socket.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/io/wait.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/etc.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     /home/deploy/.rbenv/versions/3.1.0/lib/ruby/3.1.0/x86_64-linux/stringio.so
Nov 18 05:51:58 conft-stage bundle[1305489]: [1305489] %     /home/deploy/stage/shared/vendor/bundle/ruby/3.1.0/gems/puma-6.4.0/lib/puma/puma_http11.so

Puma tells me, that master process and one worker (0) loaded the extensions. But there is no such message for second worker (1). Is it correct behaviour or do we have some forking/threading issues here?

[dentarg]

It is the correct behaviour:

puma/lib/puma/cluster/worker.rb

Lines 119 to 121 in 32e011a

	if @log_writer.debug? && index == 0
	debug_loaded_extensions "Loaded Extensions - worker 0:"
	end

This was added in #3036. It was done this way because it would look the same for all workers (so it would just be noise).

[airled]

Got it. Thank you very much for help.