travis_fold:start:worker_info [0K[33;1mWorker information[0m hostname: d3b35154-82bc-41c4-9e99-c9cd43bc9273@1.worker-n2-com-779d777f7b-rxlft.gce-production-1 version: 6.2.22 https://github.com/travis-ci/worker/tree/858cb91994a513269f2fe9782c15fc113e966231 instance: travis-job-bb48ad47-3513-4d97-9f53-c76166efa0f9 travis-ci-ubuntu-1804-1706776257-3d36ff62 (via amqp) startup: 6.389103981s travis_fold:end:worker_info [0Ktravis_time:start:0d3d5164 [0Ktravis_time:end:0d3d5164:start=1709110989884798975,finish=1709110990081701133,duration=196902158,event=no_world_writable_dirs [0Ktravis_time:start:00a656a8 [0Ktravis_time:end:00a656a8:start=1709110990084518200,finish=1709110990092115966,duration=7597766,event=agent [0Ktravis_time:start:1fe7a366 [0Ktravis_time:end:1fe7a366:start=1709110990094963452,finish=1709110990097420056,duration=2456604,event=check_unsupported [0Ktravis_time:start:02d702c8 [0Ktravis_fold:start:system_info [0K[33;1mBuild system information[0m Build language: ruby Build dist: bionic Build id: 269163095 Job id: 618437552 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:02d702c8:start=1709110990100526648,finish=1709110990109164101,duration=8637453,event=show_system_info [0Ktravis_time:start:1188bcce [0Ktravis_time:end:1188bcce:start=1709110990112765113,finish=1709110990132392546,duration=19627433,event=rm_riak_source [0Ktravis_time:start:01e071b6 [0Ktravis_time:end:01e071b6:start=1709110990135559811,finish=1709110990140850106,duration=5290295,event=fix_rwky_redis [0Ktravis_time:start:21e862a0 [0Ktravis_time:end:21e862a0:start=1709110990146483477,finish=1709110990899094806,duration=752611329,event=wait_for_network [0Ktravis_time:start:0ea6d42a [0Ktravis_time:end:0ea6d42a:start=1709110990902248749,finish=1709110991232875355,duration=330626606,event=update_apt_keys [0Ktravis_time:start:00adcc58 [0Ktravis_time:end:00adcc58:start=1709110991235662223,finish=1709110991288815105,duration=53152882,event=fix_hhvm_source [0Ktravis_time:start:052f6c24 [0Ktravis_time:end:052f6c24:start=1709110991291777888,finish=1709110991294218016,duration=2440128,event=update_mongo_arch [0Ktravis_time:start:0142efae [0Ktravis_time:end:0142efae:start=1709110991296999215,finish=1709110991338577642,duration=41578427,event=fix_sudo_enabled_trusty [0Ktravis_time:start:06bd2739 [0Ktravis_time:end:06bd2739:start=1709110991341536125,finish=1709110991343594901,duration=2058776,event=update_glibc [0Ktravis_time:start:22217f97 [0Ktravis_time:end:22217f97:start=1709110991346349237,finish=1709110991354149214,duration=7799977,event=clean_up_path [0Ktravis_time:start:18b86668 [0Ktravis_time:end:18b86668:start=1709110991356897830,finish=1709110991365446980,duration=8549150,event=fix_resolv_conf [0Ktravis_time:start:01e333fa [0Ktravis_time:end:01e333fa:start=1709110991368270610,finish=1709110991385621656,duration=17351046,event=fix_etc_hosts [0Ktravis_time:start:084137a0 [0Ktravis_time:end:084137a0:start=1709110991388531587,finish=1709110991395966205,duration=7434618,event=fix_mvn_settings_xml [0Ktravis_time:start:21d74406 [0Ktravis_time:end:21d74406:start=1709110991398700854,finish=1709110991407813239,duration=9112385,event=no_ipv6_localhost [0Ktravis_time:start:112dd7d8 [0Ktravis_time:end:112dd7d8:start=1709110991410567218,finish=1709110991412598305,duration=2031087,event=fix_etc_mavenrc [0Ktravis_time:start:27fa756c [0KOK travis_time:end:27fa756c:start=1709110991415251821,finish=1709110991804509259,duration=389257438,event=fix_perforce_key [0Ktravis_time:start:022c6855 [0Ktravis_time:end:022c6855:start=1709110991807437034,finish=1709110991810128260,duration=2691226,event=fix_wwdr_certificate [0Ktravis_time:start:00483ff0 [0Ktravis_time:end:00483ff0:start=1709110991812877264,finish=1709110991855551773,duration=42674509,event=put_localhost_first [0Ktravis_time:start:012f7adc [0Ktravis_time:end:012f7adc:start=1709110991858386482,finish=1709110991860921577,duration=2535095,event=home_paths [0Ktravis_time:start:089c3ac0 [0Ktravis_time:end:089c3ac0:start=1709110991863842840,finish=1709110991875384253,duration=11541413,event=disable_initramfs [0Ktravis_time:start:0da21126 [0Ktravis_time:end:0da21126:start=1709110991878165880,finish=1709110992033294865,duration=155128985,event=disable_ssh_roaming [0Ktravis_time:start:091fc314 [0Ktravis_time:end:091fc314:start=1709110992036359297,finish=1709110992038535221,duration=2175924,event=debug_tools [0Ktravis_time:start:03926f83 [0Ktravis_time:end:03926f83:start=1709110992041471608,finish=1709110992044407042,duration=2935434,event=uninstall_oclint [0Ktravis_time:start:0dc0be6a [0Ktravis_time:end:0dc0be6a:start=1709110992047295490,finish=1709110992050037490,duration=2742000,event=rvm_use [0Ktravis_time:start:011e4986 [0Ktravis_time:end:011e4986:start=1709110992052803516,finish=1709110992060611021,duration=7807505,event=rm_etc_boto_cfg [0Ktravis_time:start:0b11d050 [0Ktravis_time:end:0b11d050:start=1709110992063454379,finish=1709110992066189151,duration=2734772,event=rm_oraclejdk8_symlink [0Ktravis_time:start:079f5a28 [0Ktravis_time:end:079f5a28:start=1709110992069084750,finish=1709110992173177304,duration=104092554,event=enable_i386 [0Ktravis_time:start:0cdfc61e [0Ktravis_time:end:0cdfc61e:start=1709110992176741171,finish=1709110992180853412,duration=4112241,event=update_rubygems [0Ktravis_time:start:0feacc7b [0Ktravis_time:end:0feacc7b:start=1709110992183852550,finish=1709110993119972040,duration=936119490,event=ensure_path_components [0Ktravis_time:start:03244974 [0Ktravis_time:end:03244974:start=1709110993123241816,finish=1709110993125519815,duration=2277999,event=redefine_curl [0Ktravis_time:start:02ce9e50 [0Ktravis_time:end:02ce9e50:start=1709110993128563125,finish=1709110993130811717,duration=2248592,event=nonblock_pipe [0Ktravis_time:start:2534834e [0Ktravis_time:end:2534834e:start=1709110993133714354,finish=1709110999172803390,duration=6039089036,event=apt_get_update [0Ktravis_time:start:073529ba [0Ktravis_time:end:073529ba:start=1709110999175944008,finish=1709110999177959168,duration=2015160,event=deprecate_xcode_64 [0Ktravis_time:start:10a0a9d5 [0Ktravis_time:end:10a0a9d5:start=1709110999180612594,finish=1709111003043500120,duration=3862887526,event=update_heroku [0Ktravis_time:start:18aaa590 [0Ktravis_time:end:18aaa590:start=1709111003046498952,finish=1709111003048527787,duration=2028835,event=shell_session_update [0Ktravis_time:start:246fa800 [0Ktravis_fold:start:docker_mtu_and_registry_mirrors [0Ktravis_fold:end:docker_mtu_and_registry_mirrors [0Ktravis_time:end:246fa800:start=1709111003051184069,finish=1709111005265312710,duration=2214128641,event=set_docker_mtu_and_registry_mirrors [0Ktravis_time:start:0bc37c3d [0Ktravis_fold:start:resolvconf [0Ktravis_fold:end:resolvconf [0Ktravis_time:end:0bc37c3d:start=1709111005268330526,finish=1709111005350359272,duration=82028746,event=resolvconf [0Ktravis_time:start:13c55007 [0Ktravis_time:end:13c55007:start=1709111005354563224,finish=1709111005487972768,duration=133409544,event=maven_central_mirror [0Ktravis_time:start:0042ec80 [0Ktravis_time:end:0042ec80:start=1709111005490935743,finish=1709111005577127524,duration=86191781,event=maven_https [0Ktravis_fold:start:apt [0Ktravis_time:start:1e41eed4 [0K$ travis_apt_get_update travis_time:end:1e41eed4:start=1709111005580627699,finish=1709111018086077326,duration=12505449627,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:0bdab73c [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:0bdab73c:start=1709111018129151299,finish=1709111018975854124,duration=846702825,event=prepare [0Ktravis_fold:end:apt [0Ktravis_time:start:00a739e2 [0Ktravis_fold:start:services [0Ktravis_fold:end:services [0Ktravis_time:end:00a739e2:start=1709111018979094662,finish=1709111021984580003,duration=3005485341,event=services [0Ktravis_time:start:097cda33 [0Ktravis_time:end:097cda33:start=1709111021988124914,finish=1709111021990364558,duration=2239644,event=fix_ps4 [0Ktravis_fold:start:postgresql [0Ktravis_time:start:15de6c5a [0K$ travis_setup_postgresql 11 [33;1mStarting PostgreSQL v11[0K sudo systemctl start postgresql@11-main travis_time:end:15de6c5a:start=1709111021993831822,finish=1709111025115980607,duration=3122148785,event=prepare [0Ktravis_fold:end:postgresql [0Ktravis_time:start:12d71a08 [0K travis_fold:start:git.checkout [0Ktravis_time:start:0266e9c0 [0Ktravis_time:end:0266e9c0:start=1709111025122303422,finish=1709111025127166050,duration=4862628,event=checkout [0Ktravis_time:start:01de7523 [0K$ git clone --depth=50 --branch=ga-test280224 https://github.com/travis-ci/marginalia.git travis-ci/marginalia Cloning into 'travis-ci/marginalia'... remote: Enumerating objects: 581, done.[K remote: Counting objects: 0% (1/581)[K remote: Counting objects: 1% (6/581)[K remote: Counting objects: 2% (12/581)[K remote: Counting objects: 3% (18/581)[K remote: Counting objects: 4% (24/581)[K remote: Counting objects: 5% (30/581)[K remote: Counting objects: 6% (35/581)[K remote: Counting objects: 7% (41/581)[K remote: Counting objects: 8% (47/581)[K remote: Counting objects: 9% (53/581)[K remote: Counting objects: 10% (59/581)[K remote: Counting objects: 11% (64/581)[K remote: Counting objects: 12% (70/581)[K remote: Counting objects: 13% (76/581)[K remote: Counting objects: 14% (82/581)[K remote: Counting objects: 15% (88/581)[K remote: Counting objects: 16% (93/581)[K remote: Counting objects: 17% (99/581)[K remote: Counting objects: 18% (105/581)[K remote: Counting objects: 19% (111/581)[K remote: Counting objects: 20% (117/581)[K remote: Counting objects: 21% (123/581)[K remote: Counting objects: 22% (128/581)[K remote: Counting objects: 23% (134/581)[K remote: Counting objects: 24% (140/581)[K remote: Counting objects: 25% (146/581)[K remote: Counting objects: 26% (152/581)[K remote: Counting objects: 27% (157/581)[K remote: Counting objects: 28% (163/581)[K remote: Counting objects: 29% (169/581)[K remote: Counting objects: 30% (175/581)[K remote: Counting objects: 31% (181/581)[K remote: Counting objects: 32% (186/581)[K remote: Counting objects: 33% (192/581)[K remote: Counting objects: 34% (198/581)[K remote: Counting objects: 35% (204/581)[K remote: Counting objects: 36% (210/581)[K remote: Counting objects: 37% (215/581)[K remote: Counting objects: 38% (221/581)[K remote: Counting objects: 39% (227/581)[K remote: Counting objects: 40% (233/581)[K remote: Counting objects: 41% (239/581)[K remote: Counting objects: 42% (245/581)[K remote: Counting objects: 43% (250/581)[K remote: Counting objects: 44% (256/581)[K remote: Counting objects: 45% (262/581)[K remote: Counting objects: 46% (268/581)[K remote: Counting objects: 47% (274/581)[K remote: Counting objects: 48% (279/581)[K remote: Counting objects: 49% (285/581)[K remote: Counting objects: 50% (291/581)[K remote: Counting objects: 51% (297/581)[K remote: Counting objects: 52% (303/581)[K remote: Counting objects: 53% (308/581)[K remote: Counting objects: 54% (314/581)[K remote: Counting objects: 55% (320/581)[K remote: Counting objects: 56% (326/581)[K remote: Counting objects: 57% (332/581)[K remote: Counting objects: 58% (337/581)[K remote: Counting objects: 59% (343/581)[K remote: Counting objects: 60% (349/581)[K remote: Counting objects: 61% (355/581)[K remote: Counting objects: 62% (361/581)[K remote: Counting objects: 63% (367/581)[K remote: Counting objects: 64% (372/581)[K remote: Counting objects: 65% (378/581)[K remote: Counting objects: 66% (384/581)[K remote: Counting objects: 67% (390/581)[K remote: Counting objects: 68% (396/581)[K remote: Counting objects: 69% (401/581)[K remote: Counting objects: 70% (407/581)[K remote: Counting objects: 71% (413/581)[K remote: Counting objects: 72% (419/581)[K remote: Counting objects: 73% (425/581)[K remote: Counting objects: 74% (430/581)[K remote: Counting objects: 75% (436/581)[K remote: Counting objects: 76% (442/581)[K remote: Counting objects: 77% (448/581)[K remote: Counting objects: 78% (454/581)[K remote: Counting objects: 79% (459/581)[K remote: Counting objects: 80% (465/581)[K remote: Counting objects: 81% (471/581)[K remote: Counting objects: 82% (477/581)[K remote: Counting objects: 83% (483/581)[K remote: Counting objects: 84% (489/581)[K remote: Counting objects: 85% (494/581)[K remote: Counting objects: 86% (500/581)[K remote: Counting objects: 87% (506/581)[K remote: Counting objects: 88% (512/581)[K remote: Counting objects: 89% (518/581)[K remote: Counting objects: 90% (523/581)[K remote: Counting objects: 91% (529/581)[K remote: Counting objects: 92% (535/581)[K remote: Counting objects: 93% (541/581)[K remote: Counting objects: 94% (547/581)[K remote: Counting objects: 95% (552/581)[K remote: Counting objects: 96% (558/581)[K remote: Counting objects: 97% (564/581)[K remote: Counting objects: 98% (570/581)[K remote: Counting objects: 99% (576/581)[K remote: Counting objects: 100% (581/581)[K remote: Counting objects: 100% (581/581), done.[K remote: Compressing objects: 0% (1/309)[K remote: Compressing objects: 1% (4/309)[K remote: Compressing objects: 2% (7/309)[K remote: Compressing objects: 3% (10/309)[K remote: Compressing objects: 4% (13/309)[K remote: Compressing objects: 5% (16/309)[K remote: Compressing objects: 6% (19/309)[K remote: Compressing objects: 7% (22/309)[K remote: Compressing objects: 8% (25/309)[K remote: Compressing objects: 9% (28/309)[K remote: Compressing objects: 10% (31/309)[K remote: Compressing objects: 11% (34/309)[K remote: Compressing objects: 12% (38/309)[K remote: Compressing objects: 13% (41/309)[K remote: Compressing objects: 14% (44/309)[K remote: Compressing objects: 15% (47/309)[K remote: Compressing objects: 16% (50/309)[K remote: Compressing objects: 17% (53/309)[K remote: Compressing objects: 18% (56/309)[K remote: Compressing objects: 19% (59/309)[K remote: Compressing objects: 20% (62/309)[K remote: Compressing objects: 21% (65/309)[K remote: Compressing objects: 22% (68/309)[K remote: Compressing objects: 23% (72/309)[K remote: Compressing objects: 24% (75/309)[K remote: Compressing objects: 25% (78/309)[K remote: Compressing objects: 26% (81/309)[K remote: Compressing objects: 27% (84/309)[K remote: Compressing objects: 28% (87/309)[K remote: Compressing objects: 29% (90/309)[K remote: Compressing objects: 30% (93/309)[K remote: Compressing objects: 31% (96/309)[K remote: Compressing objects: 32% (99/309)[K remote: Compressing objects: 33% (102/309)[K remote: Compressing objects: 34% (106/309)[K remote: Compressing objects: 35% (109/309)[K remote: Compressing objects: 36% (112/309)[K remote: Compressing objects: 37% (115/309)[K remote: Compressing objects: 38% (118/309)[K remote: Compressing objects: 39% (121/309)[K remote: Compressing objects: 40% (124/309)[K remote: Compressing objects: 41% (127/309)[K remote: Compressing objects: 42% (130/309)[K remote: Compressing objects: 43% (133/309)[K remote: Compressing objects: 44% (136/309)[K remote: Compressing objects: 45% (140/309)[K remote: Compressing objects: 46% (143/309)[K remote: Compressing objects: 47% (146/309)[K remote: Compressing objects: 48% (149/309)[K remote: Compressing objects: 49% (152/309)[K remote: Compressing objects: 50% (155/309)[K remote: Compressing objects: 51% (158/309)[K remote: Compressing objects: 52% (161/309)[K remote: Compressing objects: 53% (164/309)[K remote: Compressing objects: 54% (167/309)[K remote: Compressing objects: 55% (170/309)[K remote: Compressing objects: 56% (174/309)[K remote: Compressing objects: 57% (177/309)[K remote: Compressing objects: 58% (180/309)[K remote: Compressing objects: 59% (183/309)[K remote: Compressing objects: 60% (186/309)[K remote: Compressing objects: 61% (189/309)[K remote: Compressing objects: 62% (192/309)[K remote: Compressing objects: 63% (195/309)[K remote: Compressing objects: 64% (198/309)[K remote: Compressing objects: 65% (201/309)[K remote: Compressing objects: 66% (204/309)[K remote: Compressing objects: 67% (208/309)[K remote: Compressing objects: 68% (211/309)[K remote: Compressing objects: 69% (214/309)[K remote: Compressing objects: 70% (217/309)[K remote: Compressing objects: 71% (220/309)[K remote: Compressing objects: 72% (223/309)[K remote: Compressing objects: 73% (226/309)[K remote: Compressing objects: 74% (229/309)[K remote: Compressing objects: 75% (232/309)[K remote: Compressing objects: 76% (235/309)[K remote: Compressing objects: 77% (238/309)[K remote: Compressing objects: 78% (242/309)[K remote: Compressing objects: 79% (245/309)[K remote: Compressing objects: 80% (248/309)[K remote: Compressing objects: 81% (251/309)[K remote: Compressing objects: 82% (254/309)[K remote: Compressing objects: 83% (257/309)[K remote: Compressing objects: 84% (260/309)[K remote: Compressing objects: 85% (263/309)[K remote: Compressing objects: 86% (266/309)[K remote: Compressing objects: 87% (269/309)[K remote: Compressing objects: 88% (272/309)[K remote: Compressing objects: 89% (276/309)[K remote: Compressing objects: 90% (279/309)[K remote: Compressing objects: 91% (282/309)[K remote: Compressing objects: 92% (285/309)[K remote: Compressing objects: 93% (288/309)[K remote: Compressing objects: 94% (291/309)[K remote: Compressing objects: 95% (294/309)[K remote: Compressing objects: 96% (297/309)[K remote: Compressing objects: 97% (300/309)[K remote: Compressing objects: 98% (303/309)[K remote: Compressing objects: 99% (306/309)[K remote: Compressing objects: 100% (309/309)[K remote: Compressing objects: 100% (309/309), done.[K Receiving objects: 0% (1/581) Receiving objects: 1% (6/581) Receiving objects: 2% (12/581) Receiving objects: 3% (18/581) Receiving objects: 4% (24/581) Receiving objects: 5% (30/581) Receiving objects: 6% (35/581) Receiving objects: 7% (41/581) Receiving objects: 8% (47/581) Receiving objects: 9% (53/581) Receiving objects: 10% (59/581) Receiving objects: 11% (64/581) Receiving objects: 12% (70/581) Receiving objects: 13% (76/581) Receiving objects: 14% (82/581) Receiving objects: 15% (88/581) Receiving objects: 16% (93/581) Receiving objects: 17% (99/581) Receiving objects: 18% (105/581) Receiving objects: 19% (111/581) Receiving objects: 20% (117/581) Receiving objects: 21% (123/581) Receiving objects: 22% (128/581) Receiving objects: 23% (134/581) Receiving objects: 24% (140/581) Receiving objects: 25% (146/581) Receiving objects: 26% (152/581) Receiving objects: 27% (157/581) Receiving objects: 28% (163/581) Receiving objects: 29% (169/581) Receiving objects: 30% (175/581) Receiving objects: 31% (181/581) Receiving objects: 32% (186/581) Receiving objects: 33% (192/581) Receiving objects: 34% (198/581) Receiving objects: 35% (204/581) Receiving objects: 36% (210/581) Receiving objects: 37% (215/581) Receiving objects: 38% (221/581) Receiving objects: 39% (227/581) Receiving objects: 40% (233/581) Receiving objects: 41% (239/581) Receiving objects: 42% (245/581) Receiving objects: 43% (250/581) Receiving objects: 44% (256/581) Receiving objects: 45% (262/581) Receiving objects: 46% (268/581) Receiving objects: 47% (274/581) Receiving objects: 48% (279/581) Receiving objects: 49% (285/581) Receiving objects: 50% (291/581) Receiving objects: 51% (297/581) Receiving objects: 52% (303/581) Receiving objects: 53% (308/581) Receiving objects: 54% (314/581) Receiving objects: 55% (320/581) Receiving objects: 56% (326/581) Receiving objects: 57% (332/581) Receiving objects: 58% (337/581) Receiving objects: 59% (343/581) Receiving objects: 60% (349/581) Receiving objects: 61% (355/581) Receiving objects: 62% (361/581) Receiving objects: 63% (367/581) Receiving objects: 64% (372/581) Receiving objects: 65% (378/581) remote: Total 581 (delta 286), reused 531 (delta 243), pack-reused 0[K Receiving objects: 66% (384/581) Receiving objects: 67% (390/581) Receiving objects: 68% (396/581) Receiving objects: 69% (401/581) Receiving objects: 70% (407/581) Receiving objects: 71% (413/581) Receiving objects: 72% (419/581) Receiving objects: 73% (425/581) Receiving objects: 74% (430/581) Receiving objects: 75% (436/581) Receiving objects: 76% (442/581) Receiving objects: 77% (448/581) Receiving objects: 78% (454/581) Receiving objects: 79% (459/581) Receiving objects: 80% (465/581) Receiving objects: 81% (471/581) Receiving objects: 82% (477/581) Receiving objects: 83% (483/581) Receiving objects: 84% (489/581) Receiving objects: 85% (494/581) Receiving objects: 86% (500/581) Receiving objects: 87% (506/581) Receiving objects: 88% (512/581) Receiving objects: 89% (518/581) Receiving objects: 90% (523/581) Receiving objects: 91% (529/581) Receiving objects: 92% (535/581) Receiving objects: 93% (541/581) Receiving objects: 94% (547/581) Receiving objects: 95% (552/581) Receiving objects: 96% (558/581) Receiving objects: 97% (564/581) Receiving objects: 98% (570/581) Receiving objects: 99% (576/581) Receiving objects: 100% (581/581) Receiving objects: 100% (581/581), 83.18 KiB | 2.19 MiB/s, done. Resolving deltas: 0% (0/286) Resolving deltas: 1% (3/286) Resolving deltas: 2% (6/286) Resolving deltas: 3% (9/286) Resolving deltas: 4% (12/286) Resolving deltas: 5% (15/286) Resolving deltas: 6% (18/286) Resolving deltas: 7% (21/286) Resolving deltas: 8% (23/286) Resolving deltas: 9% (26/286) Resolving deltas: 10% (29/286) Resolving deltas: 11% (32/286) Resolving deltas: 12% (35/286) Resolving deltas: 13% (38/286) Resolving deltas: 14% (41/286) Resolving deltas: 15% (43/286) Resolving deltas: 16% (46/286) Resolving deltas: 17% (49/286) Resolving deltas: 18% (52/286) Resolving deltas: 19% (55/286) Resolving deltas: 20% (58/286) Resolving deltas: 21% (61/286) Resolving deltas: 22% (63/286) Resolving deltas: 23% (66/286) Resolving deltas: 24% (69/286) Resolving deltas: 25% (72/286) Resolving deltas: 26% (75/286) Resolving deltas: 27% (78/286) Resolving deltas: 28% (81/286) Resolving deltas: 29% (83/286) Resolving deltas: 30% (86/286) Resolving deltas: 31% (89/286) Resolving deltas: 32% (92/286) Resolving deltas: 33% (95/286) Resolving deltas: 34% (98/286) Resolving deltas: 35% (101/286) Resolving deltas: 36% (103/286) Resolving deltas: 37% (106/286) Resolving deltas: 38% (109/286) Resolving deltas: 39% (112/286) Resolving deltas: 40% (115/286) Resolving deltas: 41% (118/286) Resolving deltas: 42% (121/286) Resolving deltas: 43% (123/286) Resolving deltas: 44% (126/286) Resolving deltas: 45% (129/286) Resolving deltas: 46% (132/286) Resolving deltas: 47% (135/286) Resolving deltas: 48% (138/286) Resolving deltas: 49% (141/286) Resolving deltas: 50% (143/286) Resolving deltas: 51% (146/286) Resolving deltas: 52% (149/286) Resolving deltas: 53% (152/286) Resolving deltas: 54% (155/286) Resolving deltas: 55% (158/286) Resolving deltas: 56% (161/286) Resolving deltas: 57% (164/286) Resolving deltas: 58% (166/286) Resolving deltas: 59% (169/286) Resolving deltas: 60% (172/286) Resolving deltas: 61% (175/286) Resolving deltas: 62% (178/286) Resolving deltas: 63% (181/286) Resolving deltas: 64% (184/286) Resolving deltas: 65% (186/286) Resolving deltas: 66% (189/286) Resolving deltas: 67% (192/286) Resolving deltas: 68% (195/286) Resolving deltas: 69% (198/286) Resolving deltas: 70% (201/286) Resolving deltas: 71% (204/286) Resolving deltas: 72% (206/286) Resolving deltas: 73% (209/286) Resolving deltas: 74% (212/286) Resolving deltas: 75% (215/286) Resolving deltas: 76% (218/286) Resolving deltas: 77% (221/286) Resolving deltas: 78% (224/286) Resolving deltas: 79% (226/286) Resolving deltas: 80% (229/286) Resolving deltas: 81% (232/286) Resolving deltas: 82% (235/286) Resolving deltas: 83% (238/286) Resolving deltas: 84% (241/286) Resolving deltas: 85% (244/286) Resolving deltas: 86% (246/286) Resolving deltas: 87% (249/286) Resolving deltas: 88% (252/286) Resolving deltas: 89% (255/286) Resolving deltas: 90% (258/286) Resolving deltas: 91% (261/286) Resolving deltas: 92% (264/286) Resolving deltas: 93% (266/286) Resolving deltas: 94% (269/286) Resolving deltas: 95% (272/286) Resolving deltas: 96% (275/286) Resolving deltas: 97% (278/286) Resolving deltas: 98% (281/286) Resolving deltas: 99% (284/286) Resolving deltas: 100% (286/286) Resolving deltas: 100% (286/286), done. travis_time:end:01de7523:start=1709111025130281313,finish=1709111025615274796,duration=484993483,event=checkout [0K$ cd travis-ci/marginalia $ git checkout -qf 616bdf4852f70383439905ccb67f6d55abdb9760 travis_fold:end:git.checkout [0K travis_time:end:01de7523:start=1709111025130281313,finish=1709111025620445934,duration=490164621,event=checkout [0Ktravis_time:start:02ee2bf7 [0K [33;1mSetting environment variables from .travis.yml[0m $ export MARGINALIA_DB_PORT=5432 $ export MARGINALIA_LOG_FILE=/var/log/postgresql/postgresql-11-main.log travis_time:end:02ee2bf7:start=1709111025623352262,finish=1709111025629538864,duration=6186602,event=env [0Ktravis_fold:start:rvm [0Ktravis_time:start:07e05c0a [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:07e05c0a:start=1709111025637011719,finish=1709111028416916725,duration=2779905006,event= [0Ktravis_time:start:1075c113 [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 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 56 29.2M 56 16.3M 0 0 11.6M 0 0:00:02 0:00:01 0:00:01 11.6M 100 29.2M 100 29.2M 0 0 18.7M 0 0:00:01 0:00:01 --:--:-- 87.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:1075c113:start=1709111028421382113,finish=1709111050964944272,duration=22543562159,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:22b04c84 [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:22b04c84:start=1709111051434105475,finish=1709111051607511902,duration=173406427,event=before_install [0Ktravis_fold:end:before_install [0Ktravis_fold:start:install.bundler [0Ktravis_time:start:2942dc65 [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 [32mInstalling bigdecimal 3.1.6 with native extensions[0m [32mFetching connection_pool 2.4.1[0m [32mInstalling concurrent-ruby 1.2.3[0m [32mInstalling connection_pool 2.4.1[0m Using ruby2_keywords 0.0.5 [32mFetching minitest 5.18.1[0m [32mFetching mutex_m 0.2.0[0m [32mInstalling mutex_m 0.2.0[0m [32mFetching timeout 0.4.1[0m [32mInstalling minitest 5.18.1[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:2942dc65:start=1709111051613365575,finish=1709111068028735747,duration=16415370172,event=install [0Ktravis_fold:end:install.bundler [0K travis_fold:start:before_script [0Ktravis_time:start:1281a168 [0K$ sudo chmod 0644 $MARGINALIA_LOG_FILE travis_time:end:1281a168:start=1709111068034075429,finish=1709111068043409079,duration=9333650,event=before_script [0Ktravis_fold:end:before_script [0Ktravis_time:start:054fb532 [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 Run options: --seed 52033 # Running: ...... Finished in 0.004530s, 1324.6229 runs/s, 1766.1639 assertions/s. 6 runs, 8 assertions, 0 failures, 0 errors, 0 skips /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/escape_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:054fb532:start=1709111068048344599,finish=1709111069236240898,duration=1187896299,event=script [0K[31;1mThe command "bundle exec rake db:reset test:postgresql" exited with 1.[0m Done. Your build exited with 1.