travis_fold:start:worker_info
Worker information
hostname: fe73dd6a-b80d-4963-b8ae-5c6432def8c0@1.worker-n2-com-779d777f7b-nfhng.gce-production-1
version: 6.2.22 https://github.com/travis-ci/worker/tree/858cb91994a513269f2fe9782c15fc113e966231
instance: travis-job-b2f85c70-c5c5-4181-9a5f-0e2a954dde99 travis-ci-ubuntu-2004-1707238145-6d1d1b02 (via amqp)
startup: 6.407372632s
travis_fold:end:worker_info
travis_time:start:0fd4ff70
travis_time:end:0fd4ff70:start=1709053021892810531,finish=1709053022219667615,duration=326857084,event=no_world_writable_dirs
travis_time:start:24d2412c
travis_time:end:24d2412c:start=1709053022222947187,finish=1709053022231358830,duration=8411643,event=agent
travis_time:start:00f5cdec
travis_time:end:00f5cdec:start=1709053022234430517,finish=1709053022236410985,duration=1980468,event=check_unsupported
travis_time:start:06e27b6e
travis_fold:start:system_info
Build system information
Build language: ruby
Build dist: focal
Build id: 269148943
Job id: 618400397
Runtime kernel version: 5.15.0-1049-gcp
travis-build version: f3de9fb7
Build image provisioning date and time
Tue 06 Feb 2024 05:15:48 PM UTC
Operating System Details
Distributor ID:	Ubuntu
Description:	Ubuntu 20.04.6 LTS
Release:	20.04
Codename:	focal
Systemd Version
systemd 245 (245.4-4ubuntu3.23)
Cookbooks Version
4a0cff6 https://github.com/travis-ci/travis-cookbooks/tree/4a0cff6
git version
git version 2.43.0
bash version
GNU bash, version 5.0.17(1)-release (x86_64-pc-linux-gnu)
gcc version
gcc (Ubuntu 9.4.0-1ubuntu1~20.04.2) 9.4.0
docker version
Client:
 Version:           24.0.5
 API version:       1.43
 Go version:        go1.20.3
 Git commit:        24.0.5-0ubuntu1~20.04.1
 Built:             Mon Aug 21 19:50:14 2023
 OS/Arch:           linux/amd64
 Context:           default

Server:
 Engine:
  Version:          24.0.5
  API version:      1.43 (minimum version 1.12)
  Go version:       go1.20.3
  Git commit:       24.0.5-0ubuntu1~20.04.1
  Built:            Mon Aug 21 19:50:14 2023
  OS/Arch:          linux/amd64
  Experimental:     false
 containerd:
  Version:          1.7.2
  GitCommit:        
 runc:
  Version:          1.1.7-0ubuntu1~20.04.2
  GitCommit:        
 docker-init:
  Version:          0.19.0
  GitCommit:        
clang version
clang version 16.0.0
jq version
jq-1.7
bats version
Bats 0.4.0
shellcheck version
0.9.0
shfmt version
v3.7.0
ccache version
3.7.7
cmake version
cmake version 3.26.3
heroku version
heroku/8.7.1 linux-x64 node-v16.19.0
imagemagick version
Version: ImageMagick 6.9.10-23 Q16 x86_64 20190101 https://imagemagick.org
md5deep version
4.4
mercurial version
version 6.5.2
mysql version
mysql  Ver 8.0.36-0ubuntu0.20.04.1 for Linux on x86_64 ((Ubuntu))
openssl version
OpenSSL 1.1.1f  31 Mar 2020
packer version
1.9.4
postgresql client version
psql (PostgreSQL) 13.13 (Ubuntu 13.13-1.pgdg20.04+1)
ragel version
Ragel State Machine Compiler version 6.10 March 2017
sudo version
1.8.31
gzip version
gzip 1.10
zip version
Zip 3.0
vim version
VIM - Vi IMproved 8.1 (2018 May 18, compiled Dec 07 2023 15:42:49)
iptables version
iptables v1.8.4 (legacy)
curl version
curl 7.68.0 (x86_64-pc-linux-gnu) libcurl/7.68.0 OpenSSL/1.1.1f zlib/1.2.11 brotli/1.0.7 libidn2/2.2.0 libpsl/0.21.0 (+libidn2/2.2.0) libssh/0.9.3/openssl/zlib nghttp2/1.40.0 librtmp/2.3
wget version
GNU Wget 1.20.3 built on linux-gnu.
rsync version
rsync  version 3.1.3  protocol version 31
gimme version
v1.5.4
nvm version
0.39.7
perlbrew version
/home/travis/perl5/perlbrew/bin/perlbrew  - App::perlbrew/0.95
phpenv version
rbenv 1.2.0
rvm version
rvm 1.29.12 (latest) by Michal Papis, Piotr Kuczynski, Wayne E. Seguin [https://rvm.io]
default ruby version
ruby 2.7.6p219 (2022-04-12 revision c9c2245c0a) [x86_64-linux]
default python version
Python 3.7.17
ElasticSearch version
7.16.3
Installed Firefox version
firefox 99.0
MongoDB version
MongoDB 4.4.28
PhantomJS version
2.1.1
Redis version
redis-server 7.2.4
Pre-installed Go versions
1.11.1
mvn version
Apache Maven 3.9.4 (dfbb324ad4a7c8fb0bf182e6d91b0ae20e3d2dd9)
gradle version
Gradle 8.3!
lein version
Leiningen 2.11.1 on Java 11.0.21 OpenJDK 64-Bit Server VM
Pre-installed Node.js versions
v10.24.1
v12.22.12
v14.21.3
v16.15
v16.20.2
v18.19.0
v18.4.0
v20.11.0
v4.9.1
v6.17.1
v8.17.0
phpenv versions
  system
  7.4
* 7.4.6 (set by /home/travis/.phpenv/version)
  hhvm-stable
  hhvm
composer --version
Composer version 1.10.6 2020-05-06 10:28:10
Pre-installed Ruby versions
ruby-2.5.9
ruby-2.7.6
ruby-3.1.2
travis_fold:end:system_info

travis_time:end:06e27b6e:start=1709053022239141914,finish=1709053022251545211,duration=12403297,event=show_system_info
travis_time:start:0ca3e174
travis_time:end:0ca3e174:start=1709053022254422200,finish=1709053022278392170,duration=23969970,event=rm_riak_source
travis_time:start:130002b4
travis_time:end:130002b4:start=1709053022281755192,finish=1709053022287052503,duration=5297311,event=fix_rwky_redis
travis_time:start:05012024
travis_time:end:05012024:start=1709053022290886721,finish=1709053023131874798,duration=840988077,event=wait_for_network
travis_time:start:26d70ab9
travis_time:end:26d70ab9:start=1709053023135206644,finish=1709053023460359585,duration=325152941,event=update_apt_keys
travis_time:start:118902cc
travis_time:end:118902cc:start=1709053023463201469,finish=1709053023521652084,duration=58450615,event=fix_hhvm_source
travis_time:start:16df8f69
travis_time:end:16df8f69:start=1709053023524534929,finish=1709053023538303951,duration=13769022,event=update_mongo_arch
travis_time:start:1c493378
travis_time:end:1c493378:start=1709053023541318528,finish=1709053023578884080,duration=37565552,event=fix_sudo_enabled_trusty
travis_time:start:03c50e7c
travis_time:end:03c50e7c:start=1709053023581648088,finish=1709053023583648408,duration=2000320,event=update_glibc
travis_time:start:0590f724
travis_time:end:0590f724:start=1709053023586689192,finish=1709053023595750224,duration=9061032,event=clean_up_path
travis_time:start:1fc06528
travis_time:end:1fc06528:start=1709053023598663937,finish=1709053023611213076,duration=12549139,event=fix_resolv_conf
travis_time:start:000ced44
travis_time:end:000ced44:start=1709053023614201211,finish=1709053023640782362,duration=26581151,event=fix_etc_hosts
travis_time:start:1e75bba4
travis_time:end:1e75bba4:start=1709053023643695114,finish=1709053023651222256,duration=7527142,event=fix_mvn_settings_xml
travis_time:start:09a0a2c7
travis_time:end:09a0a2c7:start=1709053023653932360,finish=1709053023665364594,duration=11432234,event=no_ipv6_localhost
travis_time:start:01c3c6c8
travis_time:end:01c3c6c8:start=1709053023668166469,finish=1709053023670277525,duration=2111056,event=fix_etc_mavenrc
travis_time:start:1099e150
OK
travis_time:end:1099e150:start=1709053023673082262,finish=1709053024063001726,duration=389919464,event=fix_perforce_key
travis_time:start:3bd8af54
travis_time:end:3bd8af54:start=1709053024066266278,finish=1709053024069016969,duration=2750691,event=fix_wwdr_certificate
travis_time:start:104d2820
travis_time:end:104d2820:start=1709053024071745710,finish=1709053024116767172,duration=45021462,event=put_localhost_first
travis_time:start:114423fc
travis_time:end:114423fc:start=1709053024119428094,finish=1709053024122060114,duration=2632020,event=home_paths
travis_time:start:07fb4f2b
travis_time:end:07fb4f2b:start=1709053024124707471,finish=1709053024137770455,duration=13062984,event=disable_initramfs
travis_time:start:068d4a74
travis_time:end:068d4a74:start=1709053024140604902,finish=1709053024287500086,duration=146895184,event=disable_ssh_roaming
travis_time:start:08194764
travis_time:end:08194764:start=1709053024290269374,finish=1709053024292293383,duration=2024009,event=debug_tools
travis_time:start:1d0b5af9
travis_time:end:1d0b5af9:start=1709053024295216248,finish=1709053024297860125,duration=2643877,event=uninstall_oclint
travis_time:start:005377f3
travis_time:end:005377f3:start=1709053024300723882,finish=1709053024303464535,duration=2740653,event=rvm_use
travis_time:start:04aa3df8
travis_time:end:04aa3df8:start=1709053024306225822,finish=1709053024314303002,duration=8077180,event=rm_etc_boto_cfg
travis_time:start:1c65606a
travis_time:end:1c65606a:start=1709053024317063289,finish=1709053024318996548,duration=1933259,event=rm_oraclejdk8_symlink
travis_time:start:143483de
travis_time:end:143483de:start=1709053024321809744,finish=1709053024410935215,duration=89125471,event=enable_i386
travis_time:start:0c4db069
travis_time:end:0c4db069:start=1709053024413907827,finish=1709053024417698213,duration=3790386,event=update_rubygems
travis_time:start:02f9d8d0
travis_time:end:02f9d8d0:start=1709053024420477607,finish=1709053025297579036,duration=877101429,event=ensure_path_components
travis_time:start:2b9e19d3
travis_time:end:2b9e19d3:start=1709053025300449276,finish=1709053025302396555,duration=1947279,event=redefine_curl
travis_time:start:0fbe63d2
travis_time:end:0fbe63d2:start=1709053025304967573,finish=1709053025306779253,duration=1811680,event=nonblock_pipe
travis_time:start:19b5bf64
travis_time:end:19b5bf64:start=1709053025309325006,finish=1709053031344332367,duration=6035007361,event=apt_get_update
travis_time:start:03f134cc
travis_time:end:03f134cc:start=1709053031347789323,finish=1709053031350070336,duration=2281013,event=deprecate_xcode_64
travis_time:start:13aa8058
travis_time:end:13aa8058:start=1709053031353047878,finish=1709053035363213148,duration=4010165270,event=update_heroku
travis_time:start:1a6330b5
travis_time:end:1a6330b5:start=1709053035366353623,finish=1709053035368460446,duration=2106823,event=shell_session_update
travis_time:start:00131246
travis_fold:start:docker_mtu_and_registry_mirrors
travis_fold:end:docker_mtu_and_registry_mirrors
travis_time:end:00131246:start=1709053035371243085,finish=1709053037621353384,duration=2250110299,event=set_docker_mtu_and_registry_mirrors
travis_time:start:02b40e5e
travis_fold:start:resolvconf
travis_fold:end:resolvconf
travis_time:end:02b40e5e:start=1709053037624592098,finish=1709053037687275001,duration=62682903,event=resolvconf
travis_time:start:055d138a
travis_time:end:055d138a:start=1709053037690400924,finish=1709053037805242860,duration=114841936,event=maven_central_mirror
travis_time:start:02984f6b
travis_time:end:02984f6b:start=1709053037808186048,finish=1709053037901545742,duration=93359694,event=maven_https
travis_fold:start:apt
travis_time:start:06f4aa00
$ travis_apt_get_update
travis_time:end:06f4aa00:start=1709053037904874043,finish=1709053050128370268,duration=12223496225,event=prepare
Installing APT Packages
PostgreSQL package is detected. Stopping postgresql service. See https://github.com/travis-ci/travis-ci/issues/5737 for more information.
$ sudo systemctl stop postgresql
travis_time:start:02b1efb1
$ sudo -E apt-get -yq --no-install-suggests --no-install-recommends $(travis_apt_get_options) install postgresql-11 postgresql-client-11
Reading package lists...
Building dependency tree...
Reading state information...
The following packages were automatically installed and are no longer required:
  libjs-jquery-metadata libjs-jquery-tablesorter postgresql-client-12
Use 'sudo apt autoremove' to remove them.
Suggested packages:
  postgresql-doc-11
Recommended packages:
  sysstat
The following NEW packages will be installed:
  postgresql-11 postgresql-client-11
0 upgraded, 2 newly installed, 0 to remove and 43 not upgraded.
Need to get 15.8 MB of archives.
After this operation, 54.1 MB of additional disk space will be used.
Get:1 http://apt.postgresql.org/pub/repos/apt focal-pgdg/main amd64 postgresql-client-11 amd64 11.22-2.pgdg20.04+1 [1,494 kB]
Get:2 http://apt.postgresql.org/pub/repos/apt focal-pgdg/main amd64 postgresql-11 amd64 11.22-2.pgdg20.04+1 [14.3 MB]
Fetched 15.8 MB in 2s (10.4 MB/s)
Preconfiguring packages ...
Selecting previously unselected package postgresql-client-11.
(Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 198539 files and directories currently installed.)
Preparing to unpack .../postgresql-client-11_11.22-2.pgdg20.04+1_amd64.deb ...
Unpacking postgresql-client-11 (11.22-2.pgdg20.04+1) ...
Selecting previously unselected package postgresql-11.
Preparing to unpack .../postgresql-11_11.22-2.pgdg20.04+1_amd64.deb ...
Unpacking postgresql-11 (11.22-2.pgdg20.04+1) ...
Setting up postgresql-client-11 (11.22-2.pgdg20.04+1) ...
Setting up postgresql-11 (11.22-2.pgdg20.04+1) ...
Creating new PostgreSQL cluster 11/main ...
/usr/lib/postgresql/11/bin/initdb -D /var/lib/postgresql/11/main --auth-local peer --auth-host md5
The files belonging to this database system will be owned by user "postgres".
This user must also own the server process.

The database cluster will be initialized with locale "en_US.UTF-8".
The default database encoding has accordingly been set to "UTF8".
The default text search configuration will be set to "english".

Data page checksums are disabled.

fixing permissions on existing directory /var/lib/postgresql/11/main ... ok
creating subdirectories ... ok
selecting default max_connections ... 100
selecting default shared_buffers ... 128MB
selecting default timezone ... UTC
selecting dynamic shared memory implementation ... posix
creating configuration files ... ok
running bootstrap script ... ok
performing post-bootstrap initialization ... ok
syncing data to disk ... ok

Success. You can now start the database server using:

    pg_ctlcluster 11 main start

Processing triggers for postgresql-common (256.pgdg20.04+1) ...
Building PostgreSQL dictionaries from installed myspell/hunspell packages...
  en_us
Removing obsolete dictionary files:
travis_time:end:02b1efb1:start=1709053050149985870,finish=1709053062543240221,duration=12393254351,event=prepare
travis_fold:end:apt
travis_time:start:07608edc
travis_fold:start:services
travis_fold:end:services
travis_time:end:07608edc:start=1709053062546598309,finish=1709053065552494556,duration=3005896247,event=services
travis_time:start:0f7dfa2c
travis_time:end:0f7dfa2c:start=1709053065555662942,finish=1709053065557885141,duration=2222199,event=fix_ps4
travis_fold:start:postgresql
travis_time:start:15cce5c0
$ travis_setup_postgresql 11
Starting PostgreSQL v11
sudo systemctl start postgresql@11-main
travis_time:end:15cce5c0:start=1709053065561204529,finish=1709053068342947330,duration=2781742801,event=prepare
travis_fold:end:postgresql
travis_time:start:2c0395c2

travis_fold:start:git.checkout
travis_time:start:0d634d9b
travis_time:end:0d634d9b:start=1709053068349637319,finish=1709053068355276614,duration=5639295,event=checkout
travis_time:start:00f152d9
$ git clone --depth=50 --branch=prd-ruby-upgrade-dev https://github.com/travis-ci/marginalia.git travis-ci/marginalia
Cloning into 'travis-ci/marginalia'...
remote: Enumerating objects: 606, done.
remote: Counting objects:   0% (1/606)
remote: Counting objects:   1% (7/606)
remote: Counting objects:   2% (13/606)
remote: Counting objects:   3% (19/606)
remote: Counting objects:   4% (25/606)
remote: Counting objects:   5% (31/606)
remote: Counting objects:   6% (37/606)
remote: Counting objects:   7% (43/606)
remote: Counting objects:   8% (49/606)
remote: Counting objects:   9% (55/606)
remote: Counting objects:  10% (61/606)
remote: Counting objects:  11% (67/606)
remote: Counting objects:  12% (73/606)
remote: Counting objects:  13% (79/606)
remote: Counting objects:  14% (85/606)
remote: Counting objects:  15% (91/606)
remote: Counting objects:  16% (97/606)
remote: Counting objects:  17% (104/606)
remote: Counting objects:  18% (110/606)
remote: Counting objects:  19% (116/606)
remote: Counting objects:  20% (122/606)
remote: Counting objects:  21% (128/606)
remote: Counting objects:  22% (134/606)
remote: Counting objects:  23% (140/606)
remote: Counting objects:  24% (146/606)
remote: Counting objects:  25% (152/606)
remote: Counting objects:  26% (158/606)
remote: Counting objects:  27% (164/606)
remote: Counting objects:  28% (170/606)
remote: Counting objects:  29% (176/606)
remote: Counting objects:  30% (182/606)
remote: Counting objects:  31% (188/606)
remote: Counting objects:  32% (194/606)
remote: Counting objects:  33% (200/606)
remote: Counting objects:  34% (207/606)
remote: Counting objects:  35% (213/606)
remote: Counting objects:  36% (219/606)
remote: Counting objects:  37% (225/606)
remote: Counting objects:  38% (231/606)
remote: Counting objects:  39% (237/606)
remote: Counting objects:  40% (243/606)
remote: Counting objects:  41% (249/606)
remote: Counting objects:  42% (255/606)
remote: Counting objects:  43% (261/606)
remote: Counting objects:  44% (267/606)
remote: Counting objects:  45% (273/606)
remote: Counting objects:  46% (279/606)
remote: Counting objects:  47% (285/606)
remote: Counting objects:  48% (291/606)
remote: Counting objects:  49% (297/606)
remote: Counting objects:  50% (303/606)
remote: Counting objects:  51% (310/606)
remote: Counting objects:  52% (316/606)
remote: Counting objects:  53% (322/606)
remote: Counting objects:  54% (328/606)
remote: Counting objects:  55% (334/606)
remote: Counting objects:  56% (340/606)
remote: Counting objects:  57% (346/606)
remote: Counting objects:  58% (352/606)
remote: Counting objects:  59% (358/606)
remote: Counting objects:  60% (364/606)
remote: Counting objects:  61% (370/606)
remote: Counting objects:  62% (376/606)
remote: Counting objects:  63% (382/606)
remote: Counting objects:  64% (388/606)
remote: Counting objects:  65% (394/606)
remote: Counting objects:  66% (400/606)
remote: Counting objects:  67% (407/606)
remote: Counting objects:  68% (413/606)
remote: Counting objects:  69% (419/606)
remote: Counting objects:  70% (425/606)
remote: Counting objects:  71% (431/606)
remote: Counting objects:  72% (437/606)
remote: Counting objects:  73% (443/606)
remote: Counting objects:  74% (449/606)
remote: Counting objects:  75% (455/606)
remote: Counting objects:  76% (461/606)
remote: Counting objects:  77% (467/606)
remote: Counting objects:  78% (473/606)
remote: Counting objects:  79% (479/606)
remote: Counting objects:  80% (485/606)
remote: Counting objects:  81% (491/606)
remote: Counting objects:  82% (497/606)
remote: Counting objects:  83% (503/606)
remote: Counting objects:  84% (510/606)
remote: Counting objects:  85% (516/606)
remote: Counting objects:  86% (522/606)
remote: Counting objects:  87% (528/606)
remote: Counting objects:  88% (534/606)
remote: Counting objects:  89% (540/606)
remote: Counting objects:  90% (546/606)
remote: Counting objects:  91% (552/606)
remote: Counting objects:  92% (558/606)
remote: Counting objects:  93% (564/606)
remote: Counting objects:  94% (570/606)
remote: Counting objects:  95% (576/606)
remote: Counting objects:  96% (582/606)
remote: Counting objects:  97% (588/606)
remote: Counting objects:  98% (594/606)
remote: Counting objects:  99% (600/606)
remote: Counting objects: 100% (606/606)
remote: Counting objects: 100% (606/606), done.
remote: Compressing objects:   0% (1/322)
remote: Compressing objects:   1% (4/322)
remote: Compressing objects:   2% (7/322)
remote: Compressing objects:   3% (10/322)
remote: Compressing objects:   4% (13/322)
remote: Compressing objects:   5% (17/322)
remote: Compressing objects:   6% (20/322)
remote: Compressing objects:   7% (23/322)
remote: Compressing objects:   8% (26/322)
remote: Compressing objects:   9% (29/322)
remote: Compressing objects:  10% (33/322)
remote: Compressing objects:  11% (36/322)
remote: Compressing objects:  12% (39/322)
remote: Compressing objects:  13% (42/322)
remote: Compressing objects:  14% (46/322)
remote: Compressing objects:  15% (49/322)
remote: Compressing objects:  16% (52/322)
remote: Compressing objects:  17% (55/322)
remote: Compressing objects:  18% (58/322)
remote: Compressing objects:  19% (62/322)
remote: Compressing objects:  20% (65/322)
remote: Compressing objects:  21% (68/322)
remote: Compressing objects:  22% (71/322)
remote: Compressing objects:  23% (75/322)
remote: Compressing objects:  24% (78/322)
remote: Compressing objects:  25% (81/322)
remote: Compressing objects:  26% (84/322)
remote: Compressing objects:  27% (87/322)
remote: Compressing objects:  28% (91/322)
remote: Compressing objects:  29% (94/322)
remote: Compressing objects:  30% (97/322)
remote: Compressing objects:  31% (100/322)
remote: Compressing objects:  32% (104/322)
remote: Compressing objects:  33% (107/322)
remote: Compressing objects:  34% (110/322)
remote: Compressing objects:  35% (113/322)
remote: Compressing objects:  36% (116/322)
remote: Compressing objects:  37% (120/322)
remote: Compressing objects:  38% (123/322)
remote: Compressing objects:  39% (126/322)
remote: Compressing objects:  40% (129/322)
remote: Compressing objects:  41% (133/322)
remote: Compressing objects:  42% (136/322)
remote: Compressing objects:  43% (139/322)
remote: Compressing objects:  44% (142/322)
remote: Compressing objects:  45% (145/322)
remote: Compressing objects:  46% (149/322)
remote: Compressing objects:  47% (152/322)
remote: Compressing objects:  48% (155/322)
remote: Compressing objects:  49% (158/322)
remote: Compressing objects:  50% (161/322)
remote: Compressing objects:  51% (165/322)
remote: Compressing objects:  52% (168/322)
remote: Compressing objects:  53% (171/322)
remote: Compressing objects:  54% (174/322)
remote: Compressing objects:  55% (178/322)
remote: Compressing objects:  56% (181/322)
remote: Compressing objects:  57% (184/322)
remote: Compressing objects:  58% (187/322)
remote: Compressing objects:  59% (190/322)
remote: Compressing objects:  60% (194/322)
remote: Compressing objects:  61% (197/322)
remote: Compressing objects:  62% (200/322)
remote: Compressing objects:  63% (203/322)
remote: Compressing objects:  64% (207/322)
remote: Compressing objects:  65% (210/322)
remote: Compressing objects:  66% (213/322)
remote: Compressing objects:  67% (216/322)
remote: Compressing objects:  68% (219/322)
remote: Compressing objects:  69% (223/322)
remote: Compressing objects:  70% (226/322)
remote: Compressing objects:  71% (229/322)
remote: Compressing objects:  72% (232/322)
remote: Compressing objects:  73% (236/322)
remote: Compressing objects:  74% (239/322)
remote: Compressing objects:  75% (242/322)
remote: Compressing objects:  76% (245/322)
remote: Compressing objects:  77% (248/322)
remote: Compressing objects:  78% (252/322)
remote: Compressing objects:  79% (255/322)
remote: Compressing objects:  80% (258/322)
remote: Compressing objects:  81% (261/322)
remote: Compressing objects:  82% (265/322)
remote: Compressing objects:  83% (268/322)
remote: Compressing objects:  84% (271/322)
remote: Compressing objects:  85% (274/322)
remote: Compressing objects:  86% (277/322)
remote: Compressing objects:  87% (281/322)
remote: Compressing objects:  88% (284/322)
remote: Compressing objects:  89% (287/322)
remote: Compressing objects:  90% (290/322)
remote: Compressing objects:  91% (294/322)
remote: Compressing objects:  92% (297/322)
remote: Compressing objects:  93% (300/322)
remote: Compressing objects:  94% (303/322)
remote: Compressing objects:  95% (306/322)
remote: Compressing objects:  96% (310/322)
remote: Compressing objects:  97% (313/322)
remote: Compressing objects:  98% (316/322)
remote: Compressing objects:  99% (319/322)
remote: Compressing objects: 100% (322/322)
remote: Compressing objects: 100% (322/322), done.
Receiving objects:   0% (1/606)
Receiving objects:   1% (7/606)
Receiving objects:   2% (13/606)
Receiving objects:   3% (19/606)
Receiving objects:   4% (25/606)
Receiving objects:   5% (31/606)
Receiving objects:   6% (37/606)
Receiving objects:   7% (43/606)
Receiving objects:   8% (49/606)
Receiving objects:   9% (55/606)
Receiving objects:  10% (61/606)
Receiving objects:  11% (67/606)
Receiving objects:  12% (73/606)
Receiving objects:  13% (79/606)
Receiving objects:  14% (85/606)
Receiving objects:  15% (91/606)
Receiving objects:  16% (97/606)
Receiving objects:  17% (104/606)
Receiving objects:  18% (110/606)
Receiving objects:  19% (116/606)
Receiving objects:  20% (122/606)
Receiving objects:  21% (128/606)
Receiving objects:  22% (134/606)
Receiving objects:  23% (140/606)
Receiving objects:  24% (146/606)
Receiving objects:  25% (152/606)
Receiving objects:  26% (158/606)
Receiving objects:  27% (164/606)
Receiving objects:  28% (170/606)
Receiving objects:  29% (176/606)
Receiving objects:  30% (182/606)
Receiving objects:  31% (188/606)
Receiving objects:  32% (194/606)
Receiving objects:  33% (200/606)
Receiving objects:  34% (207/606)
Receiving objects:  35% (213/606)
Receiving objects:  36% (219/606)
Receiving objects:  37% (225/606)
Receiving objects:  38% (231/606)
Receiving objects:  39% (237/606)
Receiving objects:  40% (243/606)
Receiving objects:  41% (249/606)
Receiving objects:  42% (255/606)
Receiving objects:  43% (261/606)
Receiving objects:  44% (267/606)
Receiving objects:  45% (273/606)
Receiving objects:  46% (279/606)
Receiving objects:  47% (285/606)
Receiving objects:  48% (291/606)
Receiving objects:  49% (297/606)
Receiving objects:  50% (303/606)
Receiving objects:  51% (310/606)
Receiving objects:  52% (316/606)
Receiving objects:  53% (322/606)
Receiving objects:  54% (328/606)
Receiving objects:  55% (334/606)
Receiving objects:  56% (340/606)
Receiving objects:  57% (346/606)
Receiving objects:  58% (352/606)
Receiving objects:  59% (358/606)
Receiving objects:  60% (364/606)
Receiving objects:  61% (370/606)
Receiving objects:  62% (376/606)
Receiving objects:  63% (382/606)
Receiving objects:  64% (388/606)
Receiving objects:  65% (394/606)
Receiving objects:  66% (400/606)
Receiving objects:  67% (407/606)
Receiving objects:  68% (413/606)
Receiving objects:  69% (419/606)
Receiving objects:  70% (425/606)
Receiving objects:  71% (431/606)
Receiving objects:  72% (437/606)
Receiving objects:  73% (443/606)
Receiving objects:  74% (449/606)
Receiving objects:  75% (455/606)
Receiving objects:  76% (461/606)
Receiving objects:  77% (467/606)
Receiving objects:  78% (473/606)
Receiving objects:  79% (479/606)
Receiving objects:  80% (485/606)
Receiving objects:  81% (491/606)
Receiving objects:  82% (497/606)
Receiving objects:  83% (503/606)
Receiving objects:  84% (510/606)
Receiving objects:  85% (516/606)
Receiving objects:  86% (522/606)
Receiving objects:  87% (528/606)
Receiving objects:  88% (534/606)
Receiving objects:  89% (540/606)
Receiving objects:  90% (546/606)
remote: Total 606 (delta 300), reused 559 (delta 258), pack-reused 0
Receiving objects:  91% (552/606)
Receiving objects:  92% (558/606)
Receiving objects:  93% (564/606)
Receiving objects:  94% (570/606)
Receiving objects:  95% (576/606)
Receiving objects:  96% (582/606)
Receiving objects:  97% (588/606)
Receiving objects:  98% (594/606)
Receiving objects:  99% (600/606)
Receiving objects: 100% (606/606)
Receiving objects: 100% (606/606), 87.52 KiB | 2.19 MiB/s, done.
Resolving deltas:   0% (0/300)
Resolving deltas:   1% (3/300)
Resolving deltas:   2% (6/300)
Resolving deltas:   3% (9/300)
Resolving deltas:   4% (12/300)
Resolving deltas:   5% (15/300)
Resolving deltas:   6% (18/300)
Resolving deltas:   7% (21/300)
Resolving deltas:   8% (24/300)
Resolving deltas:   9% (27/300)
Resolving deltas:  10% (30/300)
Resolving deltas:  11% (33/300)
Resolving deltas:  12% (36/300)
Resolving deltas:  13% (39/300)
Resolving deltas:  14% (42/300)
Resolving deltas:  15% (45/300)
Resolving deltas:  16% (48/300)
Resolving deltas:  17% (51/300)
Resolving deltas:  18% (54/300)
Resolving deltas:  19% (57/300)
Resolving deltas:  20% (60/300)
Resolving deltas:  21% (63/300)
Resolving deltas:  22% (66/300)
Resolving deltas:  23% (69/300)
Resolving deltas:  24% (72/300)
Resolving deltas:  25% (75/300)
Resolving deltas:  26% (78/300)
Resolving deltas:  27% (81/300)
Resolving deltas:  28% (84/300)
Resolving deltas:  29% (87/300)
Resolving deltas:  30% (90/300)
Resolving deltas:  31% (93/300)
Resolving deltas:  32% (96/300)
Resolving deltas:  33% (99/300)
Resolving deltas:  34% (102/300)
Resolving deltas:  35% (105/300)
Resolving deltas:  36% (108/300)
Resolving deltas:  37% (111/300)
Resolving deltas:  38% (114/300)
Resolving deltas:  39% (117/300)
Resolving deltas:  40% (120/300)
Resolving deltas:  41% (123/300)
Resolving deltas:  42% (126/300)
Resolving deltas:  43% (129/300)
Resolving deltas:  44% (132/300)
Resolving deltas:  45% (135/300)
Resolving deltas:  46% (138/300)
Resolving deltas:  47% (141/300)
Resolving deltas:  48% (144/300)
Resolving deltas:  49% (147/300)
Resolving deltas:  50% (150/300)
Resolving deltas:  51% (153/300)
Resolving deltas:  52% (156/300)
Resolving deltas:  53% (159/300)
Resolving deltas:  54% (162/300)
Resolving deltas:  55% (165/300)
Resolving deltas:  56% (168/300)
Resolving deltas:  57% (171/300)
Resolving deltas:  58% (174/300)
Resolving deltas:  59% (177/300)
Resolving deltas:  60% (180/300)
Resolving deltas:  61% (183/300)
Resolving deltas:  62% (186/300)
Resolving deltas:  63% (189/300)
Resolving deltas:  64% (192/300)
Resolving deltas:  65% (195/300)
Resolving deltas:  66% (198/300)
Resolving deltas:  67% (201/300)
Resolving deltas:  68% (204/300)
Resolving deltas:  69% (207/300)
Resolving deltas:  70% (210/300)
Resolving deltas:  71% (213/300)
Resolving deltas:  72% (216/300)
Resolving deltas:  73% (219/300)
Resolving deltas:  74% (222/300)
Resolving deltas:  75% (225/300)
Resolving deltas:  76% (228/300)
Resolving deltas:  77% (231/300)
Resolving deltas:  78% (234/300)
Resolving deltas:  79% (237/300)
Resolving deltas:  80% (240/300)
Resolving deltas:  81% (243/300)
Resolving deltas:  82% (246/300)
Resolving deltas:  83% (249/300)
Resolving deltas:  84% (252/300)
Resolving deltas:  85% (255/300)
Resolving deltas:  86% (258/300)
Resolving deltas:  87% (261/300)
Resolving deltas:  88% (264/300)
Resolving deltas:  89% (267/300)
Resolving deltas:  90% (270/300)
Resolving deltas:  91% (273/300)
Resolving deltas:  92% (276/300)
Resolving deltas:  93% (279/300)
Resolving deltas:  94% (282/300)
Resolving deltas:  95% (285/300)
Resolving deltas:  96% (288/300)
Resolving deltas:  97% (291/300)
Resolving deltas:  98% (294/300)
Resolving deltas:  99% (297/300)
Resolving deltas: 100% (300/300)
Resolving deltas: 100% (300/300), done.
travis_time:end:00f152d9:start=1709053068358442298,finish=1709053068810470270,duration=452027972,event=checkout
$ cd travis-ci/marginalia
$ git checkout -qf af24f2a6d2c5da19626bfc469194aecf058e7b3f
travis_fold:end:git.checkout

travis_time:end:00f152d9:start=1709053068358442298,finish=1709053068816274090,duration=457831792,event=checkout
travis_time:start:2e594530

Setting environment variables from .travis.yml
$ export PGPORT=5432
$ export PGUSER=travis
$ export MARGINALIA_DB_PORT=5432
$ export MARGINALIA_LOG_FILE=/var/log/postgresql/postgresql-11-main.log

travis_time:end:2e594530:start=1709053068819675475,finish=1709053068828275498,duration=8600023,event=env
travis_fold:start:rvm
travis_time:start:08be15db
$ rvm get head
Downloading https://get.rvm.io
Downloading https://raw.githubusercontent.com/rvm/rvm/master/binscripts/rvm-installer.asc
Verifying /home/travis/.rvm/archives/rvm-installer.asc
gpg: Signature made Tue 21 Feb 2023 11:35:16 PM UTC
gpg:                using RSA key 7D2BAF1CF37B13E2069D6956105BD0E739499BDB
gpg: Good signature from "Piotr Kuczynski <piotr.kuczynski@gmail.com>" [unknown]
gpg: WARNING: This key is not certified with a trusted signature!
gpg:          There is no indication that the signature belongs to the owner.
Primary key fingerprint: 7D2B AF1C F37B 13E2 069D  6956 105B D0E7 3949 9BDB
GPG verified '/home/travis/.rvm/archives/rvm-installer'
Downloading https://github.com/rvm/rvm/archive/master.tar.gz
Upgrading the RVM installation in /home/travis/.rvm/
    RVM PATH line found in /home/travis/.mkshrc /home/travis/.profile /home/travis/.zshrc.
    RVM PATH line not found for Bash, rerun this command with '--auto-dotfiles' flag to fix it.
    RVM sourcing line found in /home/travis/.profile /home/travis/.bashrc /home/travis/.zlogin.
Upgrade of RVM in /home/travis/.rvm/ is complete.

Thanks for installing RVM 🙏
Please consider donating to our open collective to help us maintain RVM.

👉  Donate: https://opencollective.com/rvm/donate


RVM reloaded!
travis_time:end:08be15db:start=1709053068837260632,finish=1709053071831391416,duration=2994130784,event=
travis_time:start:193ce3f8
$ rvm use 3.2.2 --install --binary --fuzzy
curl: (22) The requested URL returned error: 404 
Required ruby-3.2.2 is not installed - installing.
curl: (22) The requested URL returned error: 404 
Searching for binary rubies, this might take some time.
Found remote file https://rubies.travis-ci.org/ubuntu/20.04/x86_64/ruby-3.2.2.tar.bz2
Checking requirements for ubuntu.
Requirements installation successful.
ruby-3.2.2 - #configure
ruby-3.2.2 - #download
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed

  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0

  0 32.4M    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100 32.4M  100 32.4M    0     0  37.1M      0 --:--:-- --:--:-- --:--:-- 61.5M
No checksum for downloaded archive, recording checksum in user configuration.
ruby-3.2.2 - #validate archive
ruby-3.2.2 - #extract
ruby-3.2.2 - #validate binary
ruby-3.2.2 - #setup
ruby-3.2.2 - #gemset created /home/travis/.rvm/gems/ruby-3.2.2@global
ruby-3.2.2 - #importing gemset /home/travis/.rvm/gemsets/global.gems|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|..
ruby-3.2.2 - #generating global wrappers|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/.
ruby-3.2.2 - #uninstalling gem rubygems-bundler-1.4.5|/.
ruby-3.2.2 - #gemset created /home/travis/.rvm/gems/ruby-3.2.2
ruby-3.2.2 - #importing gemset /home/travis/.rvm/gemsets/default.gems|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\.
ruby-3.2.2 - #generating default wrappers|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-.
Using /home/travis/.rvm/gems/ruby-3.2.2
travis_time:end:193ce3f8:start=1709053071835503013,finish=1709053090899354224,duration=19063851211,event=
travis_fold:end:rvm

$ export BUNDLE_GEMFILE=$PWD/Gemfile
travis_fold:start:ruby.versions
$ ruby --version
ruby 3.2.2 (2023-03-30 revision e51014f9c0) [x86_64-linux]
$ rvm --version
rvm 1.29.12-next (master) by Michal Papis, Piotr Kuczynski, Wayne E. Seguin [https://rvm.io]
$ bundle --version
Bundler version 2.4.10
$ gem --version
3.4.10
travis_fold:end:ruby.versions

travis_fold:start:before_install
travis_time:start:16d403e7
$ bash -c "yes | gem uninstall -i /home/travis/.rvm/gems/ruby-3.2.0@global rake"
Gem 'rake' is not installed
travis_time:end:16d403e7:start=1709053091326288967,finish=1709053091490736842,duration=164447875,event=before_install
travis_fold:end:before_install
travis_fold:start:install.bundler
travis_time:start:10c04e20
$ bundle install --jobs=3 --retry=3
Fetching gem metadata from https://rubygems.org/.......
Resolving dependencies...
Fetching rake 13.1.0
Installing rake 13.1.0
Fetching base64 0.2.0
Fetching concurrent-ruby 1.2.3
Fetching bigdecimal 3.1.6
Installing base64 0.2.0
Fetching connection_pool 2.4.1
Installing bigdecimal 3.1.6 with native extensions
Installing connection_pool 2.4.1
Installing concurrent-ruby 1.2.3
Using ruby2_keywords 0.0.5
Fetching minitest 5.22.2
Fetching mutex_m 0.2.0
Installing minitest 5.22.2
Fetching timeout 0.4.1
Installing mutex_m 0.2.0
Using bundler 2.4.10
Fetching pg 1.5.5
Installing timeout 0.4.1
Using power_assert 2.0.3
Fetching drb 2.2.0
Installing pg 1.5.5 with native extensions
Installing drb 2.2.0
Fetching mocha 2.1.0
Installing mocha 2.1.0
Fetching i18n 1.14.1
Installing i18n 1.14.1
Fetching tzinfo 2.0.6
Installing tzinfo 2.0.6
Fetching test-unit 3.6.2
Installing test-unit 3.6.2
Fetching sequel 5.77.0
Fetching activesupport 7.1.3.2
Installing activesupport 7.1.3.2
Installing sequel 5.77.0
Fetching activemodel 7.1.3.2
Installing activemodel 7.1.3.2
Fetching activerecord 7.1.3.2
Installing activerecord 7.1.3.2
Using marginalia 1.5.0 from source at `.`
Bundle complete! 7 Gemfile dependencies, 22 gems now installed.
Use `bundle info [gemname]` to see where a bundled gem is installed.
travis_time:end:10c04e20:start=1709053091495704744,finish=1709053110058908320,duration=18563203576,event=install
travis_fold:end:install.bundler

travis_fold:start:before_script
travis_time:start:1ec79320
$ sudo chmod 0644 $MARGINALIA_LOG_FILE
travis_time:end:1ec79320:start=1709053110064069061,finish=1709053110073039215,duration=8970154,event=before_script
travis_fold:end:before_script
travis_time:start:08074400
$ bundle exec rake db:reset test:postgresql
createdb: could not connect to database template1: connection to server on socket "/var/run/postgresql/.s.PGSQL.5432" failed: No such file or directory
	Is the server running locally and accepting connections on that socket?
set -e; for file in $(find test -type f -name '*_test.rb'); do MARGINALIA_LOG_FILE=/var/log/postgresql/postgresql-11-main.log MARGINALIA_DB_PORT=5432 ruby -Ilib -Itest $file; done
/home/travis/.rvm/gems/ruby-3.2.2/gems/mocha-2.1.0/lib/mocha/test_unit.rb:5:in `<top (required)>': Test::Unit must be loaded *before* `require 'mocha/test_unit'`. (RuntimeError)
	from <internal:/home/travis/.rvm/rubies/ruby-3.2.2/lib/ruby/3.2.0/rubygems/core_ext/kernel_require.rb>:37:in `require'
	from <internal:/home/travis/.rvm/rubies/ruby-3.2.2/lib/ruby/3.2.0/rubygems/core_ext/kernel_require.rb>:37:in `require'
	from test/sequel/query_comments_test.rb:2:in `<main>'
rake aborted!
Command failed with status (1): [set -e; for file in $(find test -type f -name '*_test.rb'); do MARGINALIA_LOG_FILE=/var/log/postgresql/postgresql-11-main.log MARGINALIA_DB_PORT=5432 ruby -Ilib -Itest $file; done]
/home/travis/build/travis-ci/marginalia/Rakefile:15:in `block (2 levels) in <top (required)>'
/home/travis/.rvm/gems/ruby-3.2.2/gems/rake-13.1.0/exe/rake:27:in `<top (required)>'
/home/travis/.rvm/gems/ruby-3.2.2/bin/ruby_executable_hooks:22:in `eval'
/home/travis/.rvm/gems/ruby-3.2.2/bin/ruby_executable_hooks:22:in `<main>'
Tasks: TOP => test:postgresql
(See full trace by running task with --trace)
travis_time:end:08074400:start=1709053110077584234,finish=1709053110748556961,duration=670972727,event=script
The command "bundle exec rake db:reset test:postgresql" exited with 1.


Done. Your build exited with 1.