Skip to content
Snippets Groups Projects

rails-production-server-boot-puma-example

Passed Started by
Simon Tomlinson
@stomlinson
Log timestamps in UTC.
121:07:40Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
221:07:40 on blue-5.private.runners-manager.gitlab.com/gitlab.com/gitlab-org PYwgZLQB, system ID: s_4bff81aff7f7
321:07:40 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
421:07:40Resolving secrets
5 21:07:40 Preparing the "docker+machine" executor 00:08
621:07:40Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
721:07:42Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
821:07:42Authenticating with credentials from job payload (GitLab Registry)
921:07:42Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1021:07:42Using docker image sha256:82f132c22567f8ce17cc132c14b2f9aa3eaf9bb5424d91c2d966912433f0257d for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:c55e1364ee4327b244a9cfa1750f19feea14fc774eaf792b725967d146eb45f5 ...
1121:07:43WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1221:07:43WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1321:07:43Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1421:07:43Authenticating with credentials from job payload (GitLab Registry)
1521:07:43Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1621:07:43Using docker image sha256:5c5ba5b83d6c60cb0b225bd670b7a43be8c238cc72347d24af57e5027c3648f3 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:46aceab84c42d0e819c8e7940f2f98ce9e184785cd7b22d95872024a35f52407 ...
1721:07:43Starting service redis:6.2-alpine...
1821:07:43Pulling docker image redis:6.2-alpine ...
1921:07:44Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...
2021:07:44Waiting for services to be up and running (timeout 30 seconds)...
2121:07:48Authenticating with credentials from job payload (GitLab Registry)
2221:07:48Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...
2321:07:48Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...
24 21:07:48 Preparing environment 00:00
2521:07:48Running on runner-pywgzlqb-project-278964-concurrent-0 via runner-pywgzlqb-private-1731616221-2778073d...
26 21:07:48 Getting source from Git repository 00:39
2721:07:49Fetching changes with git depth set to 20...
2821:07:49Initialized empty Git repository in /builds/gitlab-org/gitlab/.git/
2921:07:49Created fresh repository.
3021:07:49remote: Enumerating objects: 204285, done.
3121:07:49remote: Counting objects: 100% (204285/204285), done.
3221:07:51remote: Compressing objects: 100% (129804/129804), done.
3321:07:52remote: Total 204285 (delta 102704), reused 144049 (delta 66999), pack-reused 0 (from 0)
3421:07:58Receiving objects: 100% (204285/204285), 159.92 MiB | 28.85 MiB/s, done.
3521:07:58Resolving deltas: 100% (102704/102704), done.
3621:08:09From https://us-east1-c.ci-gateway.int.gprd.gitlab.net:8989/gitlab-org/gitlab
3721:08:09 * [new ref] refs/pipelines/1543613215 -> refs/pipelines/1543613215
3821:08:09Checking out 305c2543 as detached HEAD (ref is refs/merge-requests/172446/merge)...
3921:08:22Skipping Git submodules setup
4021:08:22$ git remote set-url origin "${CI_REPOSITORY_URL}"
41 21:08:27 Restoring cache 00:16
4221:08:27Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20...
4321:08:27Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 ETag="19021ff660774ac886a027bf8fc2c15e"
4421:08:38Successfully extracted cache
45 21:08:43 Executing "step_script" stage of the job script 02:17
4621:08:43Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...
4721:08:44$ echo $FOSS_ONLY
4821:08:44$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
4921:08:44$ export GOPATH=$CI_PROJECT_DIR/.go
5021:08:44$ mkdir -p $GOPATH
5121:08:44$ source scripts/utils.sh
5221:08:44$ log_disk_usage "true"
5321:08:44*******************************************************
5421:08:44This runner currently has 81G free disk space.
5521:08:44*******************************************************
56 21:08:44 Disk usage detail 00:01
10621:08:45$ source scripts/prepare_build.sh
107 21:08:45 Installing gems 00:20
13821:09:05Using decomposed database config (config/database.yml.decomposed-postgresql)
13921:09:05Geo DB won't be set up.
14021:09:05Embedding DB won't be set up.
141 21:09:05 Setting up DBs 00:54
15721:09:59$ cp config/puma.rb.example config/puma.rb
15821:09:59$ sed --in-place "s:/home/git/gitlab:${PWD}:" config/puma.rb
15921:09:59$ echo 'bind "tcp://127.0.0.1:3000"' >> config/puma.rb
16021:09:59$ bundle exec puma --environment production --config config/puma.rb &
16121:09:59$ sleep 50
16221:10:01{"timestamp":"2024-11-14T21:10:01.817Z","pid":281,"message":"Puma starting in cluster mode..."}
16321:10:01{"timestamp":"2024-11-14T21:10:01.835Z","pid":281,"message":"* Puma version: 6.4.3 (ruby 3.2.5-p208) (\"The Eagle of Durango\")"}
16421:10:01{"timestamp":"2024-11-14T21:10:01.835Z","pid":281,"message":"* Min threads: 1"}
16521:10:01{"timestamp":"2024-11-14T21:10:01.835Z","pid":281,"message":"* Max threads: 16"}
16621:10:01{"timestamp":"2024-11-14T21:10:01.835Z","pid":281,"message":"* Environment: production"}
16721:10:01{"timestamp":"2024-11-14T21:10:01.835Z","pid":281,"message":"* Master PID: 281"}
16821:10:01{"timestamp":"2024-11-14T21:10:01.835Z","pid":281,"message":"* Workers: 3"}
16921:10:01{"timestamp":"2024-11-14T21:10:01.835Z","pid":281,"message":"* Restarts: (✔) hot (✖) phased"}
17021:10:01{"timestamp":"2024-11-14T21:10:01.835Z","pid":281,"message":"* Preloading application"}
17121:09:59DEPRECATION WARNING: Support for Rails versions < 7.1 is deprecated and will be removed from ViewComponent 4.0.0 (ViewComponent v4 will remove support for Rails versions < 7.1 no earlier than April 1, 2025) (called from <top (required)> at /builds/gitlab-org/gitlab/config/environment.rb:7)
17221:10:48WARNING: Active Record does not support composite primary key.
17321:10:48security_findings has composite primary key. Composite primary key is ignored.
17421:10:49$ retry_times_sleep 10 5 "curl http://127.0.0.1:3000"
17521:10:49 % Total % Received % Xferd Average Speed Time Time Time Current
17621:10:49 Dload Upload Total Spent Left Speed
17721:10:49 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
17821:10:49curl: (7) Failed to connect to 127.0.0.1 port 3000 after 0 ms: Couldn't connect to server
17921:10:55[21:10:55] Retry attempts left: 10...
18021:10:55 % Total % Received % Xferd Average Speed Time Time Time Current
18121:10:55 Dload Upload Total Spent Left Speed
18221:10:55 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0
18321:10:55curl: (7) Failed to connect to 127.0.0.1 port 3000 after 0 ms: Couldn't connect to server
18421:10:57DEPRECATION WARNING: The asset "no_avatar.png" is not present in the asset pipeline.
18521:10:57Falling back to an asset that may be in the public folder.
18621:10:57This behavior is deprecated and will be removed.
18721:10:57To bypass the asset pipeline and preserve this behavior,
18821:10:57use the `skip_pipeline: true` option.
18921:10:57 (called from default_avatar_url at /builds/gitlab-org/gitlab/lib/gitlab/gon_helper.rb:143)
19021:10:57DEPRECATION WARNING: The asset "gitlab_logo.png" is not present in the asset pipeline.
19121:10:57Falling back to an asset that may be in the public folder.
19221:10:57This behavior is deprecated and will be removed.
19321:10:57To bypass the asset pipeline and preserve this behavior,
19421:10:57use the `skip_pipeline: true` option.
19521:10:57 (called from add_gon_variables at /builds/gitlab-org/gitlab/lib/gitlab/gon_helper.rb:41)
19621:10:57DEPRECATION WARNING: The asset "icons.svg" is not present in the asset pipeline.
19721:10:57Falling back to an asset that may be in the public folder.
19821:10:57This behavior is deprecated and will be removed.
19921:10:57To bypass the asset pipeline and preserve this behavior,
20021:10:57use the `skip_pipeline: true` option.
20121:10:57 (called from sprite_icon_path at /builds/gitlab-org/gitlab/app/helpers/icons_helper.rb:21)
20221:10:57DEPRECATION WARNING: The asset "file_icons/file_icons.svg" is not present in the asset pipeline.
20321:10:57Falling back to an asset that may be in the public folder.
20421:10:57This behavior is deprecated and will be removed.
20521:10:57To bypass the asset pipeline and preserve this behavior,
20621:10:57use the `skip_pipeline: true` option.
20721:10:57 (called from sprite_file_icons_path at /builds/gitlab-org/gitlab/app/helpers/icons_helper.rb:29)
20821:10:57DEPRECATION WARNING: The asset "emoji_sprites.css" is not present in the asset pipeline.
20921:10:57Falling back to an asset that may be in the public folder.
21021:10:57This behavior is deprecated and will be removed.
21121:10:57To bypass the asset pipeline and preserve this behavior,
21221:10:57use the `skip_pipeline: true` option.
21321:10:57 (called from universal_path_to_stylesheet at /builds/gitlab-org/gitlab/app/helpers/vite_helper.rb:50)
21421:10:57DEPRECATION WARNING: The asset "lazy_bundles/gridstack.css" is not present in the asset pipeline.
21521:10:57Falling back to an asset that may be in the public folder.
21621:10:57This behavior is deprecated and will be removed.
21721:10:57To bypass the asset pipeline and preserve this behavior,
21821:10:57use the `skip_pipeline: true` option.
21921:10:57 (called from universal_path_to_stylesheet at /builds/gitlab-org/gitlab/app/helpers/vite_helper.rb:50)
22021:10:57{"timestamp":"2024-11-14T21:10:57.339Z","pid":281,"message":"* Listening on unix:///builds/gitlab-org/gitlab/tmp/sockets/gitlab.socket"}
22121:10:57{"timestamp":"2024-11-14T21:10:57.340Z","pid":281,"message":"* Listening on http://127.0.0.1:3000"}
22221:10:57{"timestamp":"2024-11-14T21:10:57.340Z","pid":281,"message":"! WARNING: Detected 2 Thread(s) started in app boot:"}
22321:10:57{"timestamp":"2024-11-14T21:10:57.340Z","pid":281,"message":"! #\u003cThread:0x00007b384b2e7558 /builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/rack-timeout-0.7.0/lib/rack/timeout/support/scheduler.rb:72 sleep\u003e - /builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/rack-timeout-0.7.0/lib/rack/timeout/support/scheduler.rb:90:in `sleep'"}
22421:10:57{"timestamp":"2024-11-14T21:10:57.340Z","pid":281,"message":"! #\u003cThread:0x00007b383b61d2f0 /builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/sentry-ruby-5.21.0/lib/sentry/threaded_periodic_worker.rb:18 sleep\u003e - /builds/gitlab-org/gitlab/vendor/ruby/3.2.0/gems/sentry-ruby-5.21.0/lib/sentry/threaded_periodic_worker.rb:20:in `sleep'"}
22521:10:57{"timestamp":"2024-11-14T21:10:57.341Z","pid":281,"message":"Use Ctrl-C to stop"}
22621:11:00[21:11:00] Retry attempts left: 9...
22721:11:00 % Total % Received % Xferd Average Speed Time Time Time Current
22821:11:00 Dload Upload Total Spent Left Speed
22921:11:00100 101 0 101 0 0 1847 0 --:--:-- --:--:-- --:--:-- 1870
23021:11:00<html><body>You are being <a href="http://127.0.0.1:3000/users/sign_in">redirected</a>.</body></html>$ kill $(jobs -p)
231 21:11:00 Saving cache for successful job 00:00
23221:11:00Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 due to policy
233 21:11:00 Uploading artifacts for successful job 00:03
23421:11:01Uploading artifacts...
23521:11:01log/: found 14 matching artifact files and directories
23621:11:01WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8368443109/artifacts?artifact_format=zip&artifact_type=archive&expire_in=7d new-url=https://gitlab.com
23721:11:01WARNING: Retrying... context=artifacts-uploader error=request redirected
23821:11:03Uploading artifacts as "archive" to coordinator... 201 Created id=8368443109 responseStatus=201 Created token=glcbt-66
239 21:11:03 Cleaning up project directory and file based variables 00:01
24021:11:11Job succeeded