travis_fold:start:worker_info [0K[33;1mWorker information[0m hostname: 282ea58c-a050-49bc-8fc6-0eca7ffa280d@1.worker-n2-com-779d777f7b-rxlft.gce-production-1 version: 6.2.22 https://github.com/travis-ci/worker/tree/858cb91994a513269f2fe9782c15fc113e966231 instance: travis-job-c83c74b7-22da-4c5f-80e5-c98bcb422afb travis-ci-ubuntu-1804-1706776257-3d36ff62 (via amqp) startup: 6.593011753s travis_fold:end:worker_info [0Ktravis_time:start:01019b0b [0Ktravis_time:end:01019b0b:start=1709108771798730635,finish=1709108771995796668,duration=197066033,event=no_world_writable_dirs [0Ktravis_time:start:0e89b30e [0Ktravis_time:end:0e89b30e:start=1709108771998553619,finish=1709108772005609789,duration=7056170,event=agent [0Ktravis_time:start:0a23e7e0 [0Ktravis_time:end:0a23e7e0:start=1709108772008005430,finish=1709108772010134450,duration=2129020,event=check_unsupported [0Ktravis_time:start:0a0e3416 [0Ktravis_fold:start:system_info [0K[33;1mBuild system information[0m Build language: ruby Build dist: bionic Build id: 269162319 Job id: 618435798 Runtime kernel version: 5.4.0-1106-gcp travis-build version: f3de9fb7 [34m[1mBuild image provisioning date and time[0m Thu Feb 1 09:04:40 UTC 2024 [34m[1mOperating System Details[0m Distributor ID: Ubuntu Description: Ubuntu 18.04.6 LTS Release: 18.04 Codename: bionic [34m[1mSystemd Version[0m systemd 237 [34m[1mCookbooks Version[0m 4a0cff6 https://github.com/travis-ci/travis-cookbooks/tree/4a0cff6 [34m[1mgit version[0m git version 2.43.0 [34m[1mbash version[0m GNU bash, version 4.4.20(1)-release (x86_64-pc-linux-gnu) [34m[1mgcc version[0m gcc (Ubuntu 7.5.0-3ubuntu1~18.04) 7.5.0 [34m[1mdocker version[0m Client: Version: 20.10.21 API version: 1.41 Go version: go1.18.1 Git commit: 20.10.21-0ubuntu1~18.04.3 Built: Thu Apr 27 05:50:21 2023 OS/Arch: linux/amd64 Context: default Experimental: true Server: Engine: Version: 20.10.21 API version: 1.41 (minimum version 1.12) Go version: go1.18.1 Git commit: 20.10.21-0ubuntu1~18.04.3 Built: Thu Apr 27 05:36:22 2023 OS/Arch: linux/amd64 Experimental: false containerd: Version: 1.6.12-0ubuntu1~18.04.1 GitCommit: runc: Version: 1.1.4-0ubuntu1~18.04.2 GitCommit: docker-init: Version: 0.19.0 GitCommit: [34m[1mclang version[0m clang version 16.0.0 [34m[1mjq version[0m jq-1.7 [34m[1mbats version[0m Bats 0.4.0 [34m[1mshellcheck version[0m 0.9.0 [34m[1mshfmt version[0m v3.7.0 [34m[1mccache version[0m 3.4.1 [34m[1mcmake version[0m cmake version 3.26.3 [34m[1mheroku version[0m heroku/8.7.1 linux-x64 node-v16.19.0 [34m[1mimagemagick version[0m Version: ImageMagick 6.9.7-4 Q16 x86_64 20170114 http://www.imagemagick.org [34m[1mmd5deep version[0m 4.4 [34m[1mmercurial version[0m version 5.3 [34m[1mmysql version[0m mysql Ver 14.14 Distrib 5.7.42, for Linux (x86_64) using EditLine wrapper [34m[1mopenssl version[0m OpenSSL 1.1.1 11 Sep 2018 [34m[1mpacker version[0m 1.9.4 [34m[1mpostgresql client version[0m psql (PostgreSQL) 11.20 (Ubuntu 11.20-1.pgdg18.04+1) [34m[1mragel version[0m Ragel State Machine Compiler version 6.10 March 2017 [34m[1msudo version[0m 1.8.21p2 [34m[1mgzip version[0m gzip 1.6 [34m[1mzip version[0m Zip 3.0 [34m[1mvim version[0m VIM - Vi IMproved 8.0 (2016 Sep 12, compiled Apr 18 2023 09:20:34) [34m[1miptables version[0m iptables v1.6.1 [34m[1mcurl version[0m curl 7.58.0 (x86_64-pc-linux-gnu) libcurl/7.58.0 OpenSSL/1.1.1 zlib/1.2.11 libidn2/2.0.4 libpsl/0.19.1 (+libidn2/2.0.4) nghttp2/1.30.0 librtmp/2.3 [34m[1mwget version[0m GNU Wget 1.19.4 built on linux-gnu. [34m[1mrsync version[0m rsync version 3.1.2 protocol version 31 [34m[1mgimme version[0m v1.5.4 [34m[1mnvm version[0m 0.39.7 [34m[1mperlbrew version[0m /home/travis/perl5/perlbrew/bin/perlbrew - App::perlbrew/0.95 [34m[1mphpenv version[0m rbenv 1.2.0 [34m[1mrvm version[0m rvm 1.29.12 (latest) by Michal Papis, Piotr Kuczynski, Wayne E. Seguin [https://rvm.io] [34m[1mdefault ruby version[0m ruby 2.7.6p219 (2022-04-12 revision c9c2245c0a) [x86_64-linux] [34m[1mdefault python version[0m Python 3.7.17 [34m[1mElasticSearch version[0m 7.16.3 [34m[1mInstalled Firefox version[0m firefox 63.0.1 [34m[1mMongoDB version[0m MongoDB 4.4.28 [34m[1mPhantomJS version[0m 2.1.1 [34m[1mPre-installed PostgreSQL versions[0m 9.3.25 9.4.26 9.5.25 9.6.24 [34m[1mRedis version[0m redis-server 7.2.4 [34m[1mPre-installed Go versions[0m 1.11.1 [34m[1mmvn version[0m Apache Maven 3.9.4 (dfbb324ad4a7c8fb0bf182e6d91b0ae20e3d2dd9) [34m[1mgradle version[0m Gradle 8.3! [34m[1mlein version[0m Leiningen 2.11.1 on Java 11.0.21 OpenJDK 64-Bit Server VM [34m[1mPre-installed Node.js versions[0m v10.24.1 v12.22.12 v14.21.3 v16.15 v16.15.1 v16.20.2 v18.19.0 v20.11.0 v4.9.1 v6.17.1 v8.17.0 [34m[1mphpenv versions[0m system 7.1 7.1.33 7.2 * 7.2.27 (set by /home/travis/.phpenv/version) 7.3 7.3.14 7.4 7.4.2 hhvm-stable hhvm [34m[1mcomposer --version[0m Composer version 1.9.2 2020-01-14 16:30:31 [34m[1mPre-installed Ruby versions[0m ruby-2.5.9 ruby-2.7.6 ruby-3.1.2 travis_fold:end:system_info [0K travis_time:end:0a0e3416:start=1709108772012676041,finish=1709108772022377276,duration=9701235,event=show_system_info [0Ktravis_time:start:0a0e3ba0 [0Ktravis_time:end:0a0e3ba0:start=1709108772024943354,finish=1709108772041177105,duration=16233751,event=rm_riak_source [0Ktravis_time:start:03125163 [0Ktravis_time:end:03125163:start=1709108772044897976,finish=1709108772050266693,duration=5368717,event=fix_rwky_redis [0Ktravis_time:start:0d47d201 [0Ktravis_time:end:0d47d201:start=1709108772053038181,finish=1709108772804967106,duration=751928925,event=wait_for_network [0Ktravis_time:start:0063f788 [0Ktravis_time:end:0063f788:start=1709108772808081860,finish=1709108773138465035,duration=330383175,event=update_apt_keys [0Ktravis_time:start:17939808 [0Ktravis_time:end:17939808:start=1709108773141374781,finish=1709108773193608486,duration=52233705,event=fix_hhvm_source [0Ktravis_time:start:05c25b64 [0Ktravis_time:end:05c25b64:start=1709108773196580848,finish=1709108773198911042,duration=2330194,event=update_mongo_arch [0Ktravis_time:start:26d62cd3 [0Ktravis_time:end:26d62cd3:start=1709108773201506016,finish=1709108773240966170,duration=39460154,event=fix_sudo_enabled_trusty [0Ktravis_time:start:014de468 [0Ktravis_time:end:014de468:start=1709108773244049177,finish=1709108773246057141,duration=2007964,event=update_glibc [0Ktravis_time:start:0103f01e [0Ktravis_time:end:0103f01e:start=1709108773248725048,finish=1709108773256109369,duration=7384321,event=clean_up_path [0Ktravis_time:start:0081f867 [0Ktravis_time:end:0081f867:start=1709108773258758228,finish=1709108773266857689,duration=8099461,event=fix_resolv_conf [0Ktravis_time:start:08732f99 [0Ktravis_time:end:08732f99:start=1709108773269528519,finish=1709108773292815664,duration=23287145,event=fix_etc_hosts [0Ktravis_time:start:2850b940 [0Ktravis_time:end:2850b940:start=1709108773295605904,finish=1709108773302817802,duration=7211898,event=fix_mvn_settings_xml [0Ktravis_time:start:06b4fef9 [0Ktravis_time:end:06b4fef9:start=1709108773305540955,finish=1709108773314756792,duration=9215837,event=no_ipv6_localhost [0Ktravis_time:start:189bfe08 [0Ktravis_time:end:189bfe08:start=1709108773317613922,finish=1709108773319655487,duration=2041565,event=fix_etc_mavenrc [0Ktravis_time:start:09316c04 [0KOK travis_time:end:09316c04:start=1709108773322219504,finish=1709108773716038713,duration=393819209,event=fix_perforce_key [0Ktravis_time:start:00dffff2 [0Ktravis_time:end:00dffff2:start=1709108773719008614,finish=1709108773721949704,duration=2941090,event=fix_wwdr_certificate [0Ktravis_time:start:315313c0 [0Ktravis_time:end:315313c0:start=1709108773724811260,finish=1709108773786191881,duration=61380621,event=put_localhost_first [0Ktravis_time:start:0fa4b032 [0Ktravis_time:end:0fa4b032:start=1709108773789510833,finish=1709108773792372733,duration=2861900,event=home_paths [0Ktravis_time:start:0f4e2adb [0Ktravis_time:end:0f4e2adb:start=1709108773795311151,finish=1709108773807964588,duration=12653437,event=disable_initramfs [0Ktravis_time:start:0eea68bb [0Ktravis_time:end:0eea68bb:start=1709108773810856947,finish=1709108773967644260,duration=156787313,event=disable_ssh_roaming [0Ktravis_time:start:290c717a [0Ktravis_time:end:290c717a:start=1709108773970750930,finish=1709108773972994334,duration=2243404,event=debug_tools [0Ktravis_time:start:0a6ec6ef [0Ktravis_time:end:0a6ec6ef:start=1709108773975966013,finish=1709108773978850103,duration=2884090,event=uninstall_oclint [0Ktravis_time:start:0de50f46 [0Ktravis_time:end:0de50f46:start=1709108773981747453,finish=1709108773984585607,duration=2838154,event=rvm_use [0Ktravis_time:start:191947da [0Ktravis_time:end:191947da:start=1709108773987425134,finish=1709108773995506858,duration=8081724,event=rm_etc_boto_cfg [0Ktravis_time:start:15cd558c [0Ktravis_time:end:15cd558c:start=1709108773998476620,finish=1709108774001358976,duration=2882356,event=rm_oraclejdk8_symlink [0Ktravis_time:start:070a7e10 [0Ktravis_time:end:070a7e10:start=1709108774004174993,finish=1709108774106243629,duration=102068636,event=enable_i386 [0Ktravis_time:start:158d43c4 [0Ktravis_time:end:158d43c4:start=1709108774109452906,finish=1709108774113728196,duration=4275290,event=update_rubygems [0Ktravis_time:start:14836c66 [0Ktravis_time:end:14836c66:start=1709108774116686626,finish=1709108775068670942,duration=951984316,event=ensure_path_components [0Ktravis_time:start:0419c335 [0Ktravis_time:end:0419c335:start=1709108775072104909,finish=1709108775074370350,duration=2265441,event=redefine_curl [0Ktravis_time:start:16113e60 [0Ktravis_time:end:16113e60:start=1709108775077256847,finish=1709108775079429493,duration=2172646,event=nonblock_pipe [0Ktravis_time:start:271775c4 [0Ktravis_time:end:271775c4:start=1709108775082349809,finish=1709108781119193935,duration=6036844126,event=apt_get_update [0Ktravis_time:start:026188f5 [0Ktravis_time:end:026188f5:start=1709108781122351588,finish=1709108781124307717,duration=1956129,event=deprecate_xcode_64 [0Ktravis_time:start:02189095 [0Ktravis_time:end:02189095:start=1709108781126776292,finish=1709108785005555438,duration=3878779146,event=update_heroku [0Ktravis_time:start:045d650c [0Ktravis_time:end:045d650c:start=1709108785008481988,finish=1709108785010398354,duration=1916366,event=shell_session_update [0Ktravis_time:start:145148c2 [0Ktravis_fold:start:docker_mtu_and_registry_mirrors [0Ktravis_fold:end:docker_mtu_and_registry_mirrors [0Ktravis_time:end:145148c2:start=1709108785012935366,finish=1709108787302516483,duration=2289581117,event=set_docker_mtu_and_registry_mirrors [0Ktravis_time:start:0c5ef374 [0Ktravis_fold:start:resolvconf [0Ktravis_fold:end:resolvconf [0Ktravis_time:end:0c5ef374:start=1709108787305482939,finish=1709108787399201552,duration=93718613,event=resolvconf [0Ktravis_time:start:0e9a9f85 [0Ktravis_time:end:0e9a9f85:start=1709108787402961141,finish=1709108787531749038,duration=128787897,event=maven_central_mirror [0Ktravis_time:start:028f2b33 [0Ktravis_time:end:028f2b33:start=1709108787534569049,finish=1709108787621412005,duration=86842956,event=maven_https [0Ktravis_fold:start:apt [0Ktravis_time:start:01cdcf20 [0K$ travis_apt_get_update travis_time:end:01cdcf20:start=1709108787625137417,finish=1709108800469606769,duration=12844469352,event=prepare [0K[33;1mInstalling APT Packages[0m [33;1mPostgreSQL package is detected. Stopping postgresql service. See https://github.com/travis-ci/travis-ci/issues/5737 for more information.[0m $ sudo systemctl stop postgresql travis_time:start:10ce796e [0K$ 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... postgresql-11 is already the newest version (11.20-1.pgdg18.04+1). postgresql-client-11 is already the newest version (11.20-1.pgdg18.04+1). postgresql-client-11 set to manually installed. The following package was automatically installed and is no longer required: libgeos-3.6.2 Use 'sudo apt autoremove' to remove it. 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. travis_time:end:10ce796e:start=1709108800503607724,finish=1709108801241335782,duration=737728058,event=prepare [0Ktravis_fold:end:apt [0Ktravis_time:start:058d31e9 [0Ktravis_fold:start:services [0Ktravis_fold:end:services [0Ktravis_time:end:058d31e9:start=1709108801244565121,finish=1709108804250040909,duration=3005475788,event=services [0Ktravis_time:start:0119d516 [0Ktravis_time:end:0119d516:start=1709108804253202581,finish=1709108804255316817,duration=2114236,event=fix_ps4 [0Ktravis_fold:start:postgresql [0Ktravis_time:start:060585a5 [0K$ travis_setup_postgresql 11 [33;1mStarting PostgreSQL v11[0K sudo systemctl start postgresql@11-main travis_time:end:060585a5:start=1709108804258362494,finish=1709108807435085695,duration=3176723201,event=prepare [0Ktravis_fold:end:postgresql [0Ktravis_time:start:0bb71c94 [0K travis_fold:start:git.checkout [0Ktravis_time:start:3b572f76 [0Ktravis_time:end:3b572f76:start=1709108807441710726,finish=1709108807446733783,duration=5023057,event=checkout [0Ktravis_time:start:0eb17db6 [0K$ 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.[K remote: Counting objects: 0% (1/606)[K remote: Counting objects: 1% (7/606)[K remote: Counting objects: 2% (13/606)[K remote: Counting objects: 3% (19/606)[K remote: Counting objects: 4% (25/606)[K remote: Counting objects: 5% (31/606)[K remote: Counting objects: 6% (37/606)[K remote: Counting objects: 7% (43/606)[K remote: Counting objects: 8% (49/606)[K remote: Counting objects: 9% (55/606)[K remote: Counting objects: 10% (61/606)[K remote: Counting objects: 11% (67/606)[K remote: Counting objects: 12% (73/606)[K remote: Counting objects: 13% (79/606)[K remote: Counting objects: 14% (85/606)[K remote: Counting objects: 15% (91/606)[K remote: Counting objects: 16% (97/606)[K remote: Counting objects: 17% (104/606)[K remote: Counting objects: 18% (110/606)[K remote: Counting objects: 19% (116/606)[K remote: Counting objects: 20% (122/606)[K remote: Counting objects: 21% (128/606)[K remote: Counting objects: 22% (134/606)[K remote: Counting objects: 23% (140/606)[K remote: Counting objects: 24% (146/606)[K remote: Counting objects: 25% (152/606)[K remote: Counting objects: 26% (158/606)[K remote: Counting objects: 27% (164/606)[K remote: Counting objects: 28% (170/606)[K remote: Counting objects: 29% (176/606)[K remote: Counting objects: 30% (182/606)[K remote: Counting objects: 31% (188/606)[K remote: Counting objects: 32% (194/606)[K remote: Counting objects: 33% (200/606)[K remote: Counting objects: 34% (207/606)[K remote: Counting objects: 35% (213/606)[K remote: Counting objects: 36% (219/606)[K remote: Counting objects: 37% (225/606)[K remote: Counting objects: 38% (231/606)[K remote: Counting objects: 39% (237/606)[K remote: Counting objects: 40% (243/606)[K remote: Counting objects: 41% (249/606)[K remote: Counting objects: 42% (255/606)[K remote: Counting objects: 43% (261/606)[K remote: Counting objects: 44% (267/606)[K remote: Counting objects: 45% (273/606)[K remote: Counting objects: 46% (279/606)[K remote: Counting objects: 47% (285/606)[K remote: Counting objects: 48% (291/606)[K remote: Counting objects: 49% (297/606)[K remote: Counting objects: 50% (303/606)[K remote: Counting objects: 51% (310/606)[K remote: Counting objects: 52% (316/606)[K remote: Counting objects: 53% (322/606)[K remote: Counting objects: 54% (328/606)[K remote: Counting objects: 55% (334/606)[K remote: Counting objects: 56% (340/606)[K remote: Counting objects: 57% (346/606)[K remote: Counting objects: 58% (352/606)[K remote: Counting objects: 59% (358/606)[K remote: Counting objects: 60% (364/606)[K remote: Counting objects: 61% (370/606)[K remote: Counting objects: 62% (376/606)[K remote: Counting objects: 63% (382/606)[K remote: Counting objects: 64% (388/606)[K remote: Counting objects: 65% (394/606)[K remote: Counting objects: 66% (400/606)[K remote: Counting objects: 67% (407/606)[K remote: Counting objects: 68% (413/606)[K remote: Counting objects: 69% (419/606)[K remote: Counting objects: 70% (425/606)[K remote: Counting objects: 71% (431/606)[K remote: Counting objects: 72% (437/606)[K remote: Counting objects: 73% (443/606)[K remote: Counting objects: 74% (449/606)[K remote: Counting objects: 75% (455/606)[K remote: Counting objects: 76% (461/606)[K remote: Counting objects: 77% (467/606)[K remote: Counting objects: 78% (473/606)[K remote: Counting objects: 79% (479/606)[K remote: Counting objects: 80% (485/606)[K remote: Counting objects: 81% (491/606)[K remote: Counting objects: 82% (497/606)[K remote: Counting objects: 83% (503/606)[K remote: Counting objects: 84% (510/606)[K remote: Counting objects: 85% (516/606)[K remote: Counting objects: 86% (522/606)[K remote: Counting objects: 87% (528/606)[K remote: Counting objects: 88% (534/606)[K remote: Counting objects: 89% (540/606)[K remote: Counting objects: 90% (546/606)[K remote: Counting objects: 91% (552/606)[K remote: Counting objects: 92% (558/606)[K remote: Counting objects: 93% (564/606)[K remote: Counting objects: 94% (570/606)[K remote: Counting objects: 95% (576/606)[K remote: Counting objects: 96% (582/606)[K remote: Counting objects: 97% (588/606)[K remote: Counting objects: 98% (594/606)[K remote: Counting objects: 99% (600/606)[K remote: Counting objects: 100% (606/606)[K remote: Counting objects: 100% (606/606), done.[K remote: Compressing objects: 0% (1/322)[K remote: Compressing objects: 1% (4/322)[K remote: Compressing objects: 2% (7/322)[K remote: Compressing objects: 3% (10/322)[K remote: Compressing objects: 4% (13/322)[K remote: Compressing objects: 5% (17/322)[K remote: Compressing objects: 6% (20/322)[K remote: Compressing objects: 7% (23/322)[K remote: Compressing objects: 8% (26/322)[K remote: Compressing objects: 9% (29/322)[K remote: Compressing objects: 10% (33/322)[K remote: Compressing objects: 11% (36/322)[K remote: Compressing objects: 12% (39/322)[K remote: Compressing objects: 13% (42/322)[K remote: Compressing objects: 14% (46/322)[K remote: Compressing objects: 15% (49/322)[K remote: Compressing objects: 16% (52/322)[K remote: Compressing objects: 17% (55/322)[K remote: Compressing objects: 18% (58/322)[K remote: Compressing objects: 19% (62/322)[K remote: Compressing objects: 20% (65/322)[K remote: Compressing objects: 21% (68/322)[K remote: Compressing objects: 22% (71/322)[K remote: Compressing objects: 23% (75/322)[K remote: Compressing objects: 24% (78/322)[K remote: Compressing objects: 25% (81/322)[K remote: Compressing objects: 26% (84/322)[K remote: Compressing objects: 27% (87/322)[K remote: Compressing objects: 28% (91/322)[K remote: Compressing objects: 29% (94/322)[K remote: Compressing objects: 30% (97/322)[K remote: Compressing objects: 31% (100/322)[K remote: Compressing objects: 32% (104/322)[K remote: Compressing objects: 33% (107/322)[K remote: Compressing objects: 34% (110/322)[K remote: Compressing objects: 35% (113/322)[K remote: Compressing objects: 36% (116/322)[K remote: Compressing objects: 37% (120/322)[K remote: Compressing objects: 38% (123/322)[K remote: Compressing objects: 39% (126/322)[K remote: Compressing objects: 40% (129/322)[K remote: Compressing objects: 41% (133/322)[K remote: Compressing objects: 42% (136/322)[K remote: Compressing objects: 43% (139/322)[K remote: Compressing objects: 44% (142/322)[K remote: Compressing objects: 45% (145/322)[K remote: Compressing objects: 46% (149/322)[K remote: Compressing objects: 47% (152/322)[K remote: Compressing objects: 48% (155/322)[K remote: Compressing objects: 49% (158/322)[K remote: Compressing objects: 50% (161/322)[K remote: Compressing objects: 51% (165/322)[K remote: Compressing objects: 52% (168/322)[K remote: Compressing objects: 53% (171/322)[K remote: Compressing objects: 54% (174/322)[K remote: Compressing objects: 55% (178/322)[K remote: Compressing objects: 56% (181/322)[K remote: Compressing objects: 57% (184/322)[K remote: Compressing objects: 58% (187/322)[K remote: Compressing objects: 59% (190/322)[K remote: Compressing objects: 60% (194/322)[K remote: Compressing objects: 61% (197/322)[K remote: Compressing objects: 62% (200/322)[K remote: Compressing objects: 63% (203/322)[K remote: Compressing objects: 64% (207/322)[K remote: Compressing objects: 65% (210/322)[K remote: Compressing objects: 66% (213/322)[K remote: Compressing objects: 67% (216/322)[K remote: Compressing objects: 68% (219/322)[K remote: Compressing objects: 69% (223/322)[K remote: Compressing objects: 70% (226/322)[K remote: Compressing objects: 71% (229/322)[K remote: Compressing objects: 72% (232/322)[K remote: Compressing objects: 73% (236/322)[K remote: Compressing objects: 74% (239/322)[K remote: Compressing objects: 75% (242/322)[K remote: Compressing objects: 76% (245/322)[K remote: Compressing objects: 77% (248/322)[K remote: Compressing objects: 78% (252/322)[K remote: Compressing objects: 79% (255/322)[K remote: Compressing objects: 80% (258/322)[K remote: Compressing objects: 81% (261/322)[K remote: Compressing objects: 82% (265/322)[K remote: Compressing objects: 83% (268/322)[K remote: Compressing objects: 84% (271/322)[K remote: Compressing objects: 85% (274/322)[K remote: Compressing objects: 86% (277/322)[K remote: Compressing objects: 87% (281/322)[K remote: Compressing objects: 88% (284/322)[K remote: Compressing objects: 89% (287/322)[K remote: Compressing objects: 90% (290/322)[K remote: Compressing objects: 91% (294/322)[K remote: Compressing objects: 92% (297/322)[K remote: Compressing objects: 93% (300/322)[K remote: Compressing objects: 94% (303/322)[K remote: Compressing objects: 95% (306/322)[K remote: Compressing objects: 96% (310/322)[K remote: Compressing objects: 97% (313/322)[K remote: Compressing objects: 98% (316/322)[K remote: Compressing objects: 99% (319/322)[K remote: Compressing objects: 100% (322/322)[K remote: Compressing objects: 100% (322/322), done.[K 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[K 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 | 1.68 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:0eb17db6:start=1709108807449913056,finish=1709108807930713840,duration=480800784,event=checkout [0K$ cd travis-ci/marginalia $ git checkout -qf af24f2a6d2c5da19626bfc469194aecf058e7b3f travis_fold:end:git.checkout [0K travis_time:end:0eb17db6:start=1709108807449913056,finish=1709108807936407442,duration=486494386,event=checkout [0Ktravis_time:start:01a61714 [0K [33;1mSetting environment variables from .travis.yml[0m $ export PGPORT=5432 $ export PGHOST=localhost $ export PGDATABASE=marginalia_test $ export MARGINALIA_DB_PORT=5432 $ export MARGINALIA_LOG_FILE=/var/log/postgresql/postgresql-11-main.log travis_time:end:01a61714:start=1709108807939419888,finish=1709108807946925248,duration=7505360,event=env [0Ktravis_fold:start:rvm [0Ktravis_time:start:0591fd6c [0K$ rvm get head Downloading https://get.rvm.io [32mDownloading https://raw.githubusercontent.com/rvm/rvm/master/binscripts/rvm-installer.asc[0m [0m[32mVerifying /home/travis/.rvm/archives/rvm-installer.asc[0m [0mgpg: 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 [32mGPG verified '/home/travis/.rvm/archives/rvm-installer'[0m [0mDownloading https://github.com/rvm/rvm/archive/master.tar.gz [32mUpgrading the RVM installation in /home/travis/.rvm/[0m [0m RVM PATH line found in /home/travis/.mkshrc /home/travis/.profile /home/travis/.zshrc. [33m RVM PATH line not found for Bash, rerun this command with '--auto-dotfiles' flag to fix it.[0m [0m RVM sourcing line found in /home/travis/.profile /home/travis/.bashrc /home/travis/.zlogin. [32mUpgrade of RVM in /home/travis/.rvm/ is complete.[0m [0m [32m[33mThanks for installing RVM ðŸ™[0m Please consider donating to our open collective to help us maintain RVM. 👉 Donate: [34mhttps://opencollective.com/rvm/donate[0m [0m [0m[32mRVM reloaded![0m [0mtravis_time:end:0591fd6c:start=1709108807954884396,finish=1709108810746908484,duration=2792024088,event= [0Ktravis_time:start:1cde6980 [0K$ rvm use 3.2.2 --install --binary --fuzzy curl: (22) The requested URL returned error: 404 [33mRequired ruby-3.2.2 is not installed - installing.[0m [0mcurl: (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/18.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:00:01 --:--:-- 0 0 0 0 0 0 0 0 0 --:--:-- 0:00:01 --:--:-- 0 72 29.2M 72 21.2M 0 0 12.3M 0 0:00:02 0:00:01 0:00:01 12.3M 100 29.2M 100 29.2M 0 0 14.6M 0 0:00:01 0:00:01 --:--:-- 29.2M [33mNo checksum for downloaded archive, recording checksum in user configuration.[0m [0mruby-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 [32mruby-3.2.2 - #importing gemset /home/travis/.rvm/gemsets/global.gems[0m|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.. [32mruby-3.2.2 - #generating global wrappers[0m|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/. [32mruby-3.2.2 - #uninstalling gem rubygems-bundler-1.4.5[0m|/. ruby-3.2.2 - #gemset created /home/travis/.rvm/gems/ruby-3.2.2 [32mruby-3.2.2 - #importing gemset /home/travis/.rvm/gemsets/default.gems[0m|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\. [32mruby-3.2.2 - #generating default wrappers[0m|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-\|/-.|/-\|/-\|.-\|/-. Using /home/travis/.rvm/gems/ruby-3.2.2 travis_time:end:1cde6980:start=1709108810751530662,finish=1709108832794789491,duration=22043258829,event= [0Ktravis_fold:end:rvm [0K $ export BUNDLE_GEMFILE=$PWD/Gemfile travis_fold:start:ruby.versions [0K$ 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 [0K travis_fold:start:before_install [0Ktravis_time:start:0fbb7b62 [0K$ bash -c "yes | gem uninstall -i /home/travis/.rvm/gems/ruby-3.2.0@global rake" Gem 'rake' is not installed travis_time:end:0fbb7b62:start=1709108833245411826,finish=1709108833411954112,duration=166542286,event=before_install [0Ktravis_fold:end:before_install [0Ktravis_fold:start:install.bundler [0Ktravis_time:start:0949b89f [0K$ bundle install --jobs=3 --retry=3 Fetching gem metadata from https://rubygems.org/....... Resolving dependencies... [32mFetching rake 13.1.0[0m [32mInstalling rake 13.1.0[0m [32mFetching base64 0.2.0[0m [32mFetching bigdecimal 3.1.6[0m [32mFetching concurrent-ruby 1.2.3[0m [32mInstalling base64 0.2.0[0m [32mFetching connection_pool 2.4.1[0m [32mInstalling bigdecimal 3.1.6 with native extensions[0m [32mInstalling connection_pool 2.4.1[0m [32mInstalling concurrent-ruby 1.2.3[0m Using ruby2_keywords 0.0.5 [32mFetching minitest 5.22.2[0m [32mFetching mutex_m 0.2.0[0m [32mInstalling minitest 5.22.2[0m [32mFetching timeout 0.4.1[0m [32mInstalling mutex_m 0.2.0[0m Using bundler 2.4.10 [32mFetching pg 1.5.5[0m [32mInstalling timeout 0.4.1[0m Using power_assert 2.0.3 [32mFetching drb 2.2.0[0m [32mInstalling pg 1.5.5 with native extensions[0m [32mInstalling drb 2.2.0[0m [32mFetching mocha 2.1.0[0m [32mInstalling mocha 2.1.0[0m [32mFetching i18n 1.14.1[0m [32mInstalling i18n 1.14.1[0m [32mFetching tzinfo 2.0.6[0m [32mInstalling tzinfo 2.0.6[0m [32mFetching test-unit 3.6.2[0m [32mInstalling test-unit 3.6.2[0m [32mFetching activesupport 7.1.3.2[0m [32mFetching sequel 5.77.0[0m [32mInstalling activesupport 7.1.3.2[0m [32mInstalling sequel 5.77.0[0m [32mFetching activemodel 7.1.3.2[0m [32mInstalling activemodel 7.1.3.2[0m [32mFetching activerecord 7.1.3.2[0m [32mInstalling activerecord 7.1.3.2[0m Using marginalia 1.5.0 from source at `.` [32mBundle complete! 7 Gemfile dependencies, 22 gems now installed.[0m [32mUse `bundle info [gemname]` to see where a bundled gem is installed.[0m travis_time:end:0949b89f:start=1709108833416774867,finish=1709108849713280450,duration=16296505583,event=install [0Ktravis_fold:end:install.bundler [0K travis_fold:start:before_script [0Ktravis_time:start:11321968 [0K$ sudo chmod 0644 $MARGINALIA_LOG_FILE travis_time:end:11321968:start=1709108849718705690,finish=1709108849727650218,duration=8944528,event=before_script [0Ktravis_fold:end:before_script [0Ktravis_time:start:00cdeb00 [0K$ bundle exec rake db:reset test:postgresql 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)>': [1mTest::Unit must be loaded *before* `require 'mocha/test_unit'`. ([1;4mRuntimeError[m[1m)[m 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/pg/pg_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:00cdeb00:start=1709108849732311981,finish=1709108850590458724,duration=858146743,event=script [0K[31;1mThe command "bundle exec rake db:reset test:postgresql" exited with 1.[0m Done. Your build exited with 1.