+ set -e + set -o pipefail ++ dirname ./run.sh + cd . + echo 26970 + echo '1.68.1 24dc2a841' 1.68.1 24dc2a841 + date Wed Dec 26 07:20:13 CET 2018 + uptime 07:20:13 up 10 days, 21:50, 0 users, load average: 6.20, 5.93, 5.94 + head=git@github.com:yegor256/futex.git + ff=default + image=yegor256/rultor + rebase=false + squash=false + head_branch=master + author=yegor256 + tag=0.8.3 + vars=('--env=head=git@github.com:yegor256/futex.git' '--env=tag=0.8.3' '--env=scripts=( '\''export '\''\'\'''\''head=git@github.com:yegor256/futex.git'\''\'\'''\'''\'' '\'';'\'' '\''export '\''\'\'''\''tag=0.8.3'\''\'\'''\'''\'' '\'';'\'' '\''export '\''\'\'''\''head_branch=master'\''\'\'''\'''\'' '\'';'\'' '\''export '\''\'\'''\''author=yegor256'\''\'\'''\'''\'' '\'';'\'' '\''export GEM_HOME=~/.ruby'\'' '\'';'\'' '\''export GEM_PATH=$GEM_HOME:$GEM_PATH'\'' '\'';'\'' '\''bundle install'\'' '\'';'\'' '\''rake'\'' '\'';'\'' '\''rm -rf *.gem'\'' '\'';'\'' '\''sed -i "s/0\.0\.0/${tag}/g" futex.gemspec'\'' '\'';'\'' '\''git add futex.gemspec'\'' '\'';'\'' '\''git commit -m "Version set to ${tag}"'\'' '\'';'\'' '\''gem build futex.gemspec'\'' '\'';'\'' '\''chmod 0600 ../rubygems.yml'\'' '\'';'\'' '\''gem push *.gem --config-file ../rubygems.yml'\'' '\'';'\'' )' '--env=head_branch=master' '--env=author=yegor256') + scripts=('export '\''head=git@github.com:yegor256/futex.git'\''' ';' 'export '\''tag=0.8.3'\''' ';' 'export '\''head_branch=master'\''' ';' 'export '\''author=yegor256'\''' ';' 'export GEM_HOME=~/.ruby' ';' 'export GEM_PATH=$GEM_HOME:$GEM_PATH' ';' 'bundle install' ';' 'rake' ';' 'rm -rf *.gem' ';' 'sed -i "s/0\.0\.0/${tag}/g" futex.gemspec' ';' 'git add futex.gemspec' ';' 'git commit -m "Version set to ${tag}"' ';' 'gem build futex.gemspec' ';' 'chmod 0600 ../rubygems.yml' ';' 'gem push *.gem --config-file ../rubygems.yml' ';') + directory= + container=yegor256_futex_24 + as_root=false + mkdir -p /home/rultor/.ssh + echo -e 'Host github.com\n\tStrictHostKeyChecking no\n' + chmod 600 /home/rultor/.ssh/config + git clone git@github.com:yegor256/futex.git repo Cloning into 'repo'... + cd repo + git config user.email me@rultor.com + git config user.name rultor + '[' -z 'export '\''head=git@github.com:yegor256/futex.git'\''' ']' + cd .. + cat + '[' false = true ']' + cat + chmod a+x entry.sh + cat + echo 'export '\''head=git@github.com:yegor256/futex.git'\''' ';' 'export '\''tag=0.8.3'\''' ';' 'export '\''head_branch=master'\''' ';' 'export '\''author=yegor256'\''' ';' 'export GEM_HOME=~/.ruby' ';' 'export GEM_PATH=$GEM_HOME:$GEM_PATH' ';' 'bundle install' ';' rake ';' 'rm -rf *.gem' ';' 'sed -i "s/0\.0\.0/${tag}/g" futex.gemspec' ';' 'git add futex.gemspec' ';' 'git commit -m "Version set to ${tag}"' ';' 'gem build futex.gemspec' ';' 'chmod 0600 ../rubygems.yml' ';' 'gem push *.gem --config-file ../rubygems.yml' ';' + sensitive=() + rm -rf .gpg + '[' -z 0.8.3 ']' + [[ 0.8.3 =~ ^[a-zA-Z0-9\.\-]+$ ]] + echo 'tag name is valid: "0.8.3"' tag name is valid: "0.8.3" + cd repo ++ git tag -l 0.8.3 + '[' ']' + export BRANCH_NAME=__rultor + BRANCH_NAME=__rultor ++ wc -l ++ git show-branch __rultor + '[' 0 -gt 0 ']' + git checkout -b __rultor Switched to a new branch '__rultor' + docker_when_possible + true ++ uptime ++ tail -n 1 ++ sed 's/ /\n/g' + load=5.95 ++ echo 5.95 '>' 30 ++ bc + '[' 0 -eq 1 ']' + echo 'load average is 5.95, low enough to run a new Docker container' load average is 5.95, low enough to run a new Docker container + break + cd .. + '[' -n '' ']' + use_image=yegor256/rultor + docker pull yegor256/rultor Using default tag: latest latest: Pulling from yegor256/rultor Digest: sha256:7981a71e6a4a4d128e3e829e64038f8a24c3dcb45e9093e0cdbe2a6f3268604e Status: Image is up to date for yegor256/rultor:latest + docker ps --filter=status=exited + grep --quiet '\syegor256_futex_24\s*$' + ls -al . total 712 drwx--x--x 3 rultor rultor 4096 Dec 26 07:20 . drwxrwxrwt 14 root root 684032 Dec 26 07:20 .. -rw-rw-r-- 1 rultor rultor 444 Dec 26 07:20 end.sh -rwxrwxr-x 1 rultor rultor 338 Dec 26 07:20 entry.sh -rw-rw-r-- 1 rultor rultor 6 Dec 26 07:20 pid drwxrwxr-x 5 rultor rultor 4096 Dec 26 07:20 repo -rw-rw-r-- 1 rultor rultor 56 Dec 26 07:20 rubygems.yml -rwxrwxr-x 1 rultor rultor 5710 Dec 26 07:20 run.sh -rw-rw-r-- 1 rultor rultor 562 Dec 26 07:20 script.sh -rw-rw-r-- 1 rultor rultor 3843 Dec 26 07:20 stdout ++ pwd ++ pwd + docker run -t --rm -v /tmp/rultor-uDuc:/main --env=head=git@github.com:yegor256/futex.git --env=tag=0.8.3 '--env=scripts=( '\''export '\''\'\'''\''head=git@github.com:yegor256/futex.git'\''\'\'''\'''\'' '\'';'\'' '\''export '\''\'\'''\''tag=0.8.3'\''\'\'''\'''\'' '\'';'\'' '\''export '\''\'\'''\''head_branch=master'\''\'\'''\'''\'' '\'';'\'' '\''export '\''\'\'''\''author=yegor256'\''\'\'''\'''\'' '\'';'\'' '\''export GEM_HOME=~/.ruby'\'' '\'';'\'' '\''export GEM_PATH=$GEM_HOME:$GEM_PATH'\'' '\'';'\'' '\''bundle install'\'' '\'';'\'' '\''rake'\'' '\'';'\'' '\''rm -rf *.gem'\'' '\'';'\'' '\''sed -i "s/0\.0\.0/${tag}/g" futex.gemspec'\'' '\'';'\'' '\''git add futex.gemspec'\'' '\'';'\'' '\''git commit -m "Version set to ${tag}"'\'' '\'';'\'' '\''gem build futex.gemspec'\'' '\'';'\'' '\''chmod 0600 ../rubygems.yml'\'' '\'';'\'' '\''gem push *.gem --config-file ../rubygems.yml'\'' '\'';'\'' )' --env=head_branch=master --env=author=yegor256 --hostname=docker --privileged --memory=6g --memory-swap=16g --oom-kill-disable --cidfile=/tmp/rultor-uDuc/cid -w=/main -v /var/run/docker.sock:/var/run/docker.sock --name=yegor256_futex_24 yegor256/rultor /main/entry.sh WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. + set -e + set -o pipefail + shopt -s dotglob + useradd -m -G sudo r + echo '%sudo ALL=(ALL) NOPASSWD:ALL' + cp -R /root/.bashrc /root/.composer /root/.gem /root/.gnupg /root/.m2 /root/.oracle_jre_usage /root/.profile /root/texmf /root/.wget-hsts /home/r + cp -R ./cid ./end.sh ./entry.sh ./pid ./repo ./rubygems.yml ./run.sh ./script.sh ./stdout /home/r + rm -rf repo + chown -R r:r /home/r + chmod a+x /home/r/script.sh + su --login r --command /home/r/script.sh mesg: change /dev/pts/0 mode failed: Operation not permitted + set -e + set -o pipefail + shopt -s expand_aliases + alias 'sudo=sudo -i' + export HOME=/home/r + HOME=/home/r + cd /home/r/repo + export head=git@github.com:yegor256/futex.git + head=git@github.com:yegor256/futex.git + export tag=0.8.3 + tag=0.8.3 + export head_branch=master + head_branch=master + export author=yegor256 + author=yegor256 + export GEM_HOME=/home/r/.ruby + GEM_HOME=/home/r/.ruby + export GEM_PATH=/home/r/.ruby:/usr/local/rvm/gems/ruby-2.5.1:/usr/local/rvm/gems/ruby-2.5.1@global + GEM_PATH=/home/r/.ruby:/usr/local/rvm/gems/ruby-2.5.1:/usr/local/rvm/gems/ruby-2.5.1@global + bundle install Fetching gem metadata from https://rubygems.org/............. Resolving dependencies... 32mFetching rake 12.3.10m 32mInstalling rake 12.3.10m 32mFetching ast 2.4.00m 32mInstalling ast 2.4.00m 32mFetching backtrace 0.3.00m 32mInstalling backtrace 0.3.00m Using bundler 1.16.6 32mFetching concurrent-ruby 1.1.40m 32mInstalling concurrent-ruby 1.1.40m Using futex 0.0.0 from source at `.` 32mFetching jaro_winkler 1.5.10m 32mInstalling jaro_winkler 1.5.1 with native extensions0m 32mFetching minitest 5.11.30m 32mInstalling minitest 5.11.30m 32mFetching openssl 2.1.20m 32mInstalling openssl 2.1.2 with native extensions0m 32mFetching parallel 1.12.10m 32mInstalling parallel 1.12.10m 32mFetching parser 2.5.3.00m 32mInstalling parser 2.5.3.00m GemWrappers: Can not wrap missing file: ruby-parse GemWrappers: Can not wrap missing file: ruby-rewrite 32mFetching powerpack 0.1.20m 32mInstalling powerpack 0.1.20m 32mFetching rainbow 3.0.00m 32mInstalling rainbow 3.0.00m 32mFetching rdoc 4.3.00m 32mInstalling rdoc 4.3.00m 32mFetching ruby-progressbar 1.10.00m 32mInstalling ruby-progressbar 1.10.00m 32mFetching unicode-display_width 1.4.10m 32mInstalling unicode-display_width 1.4.10m 32mFetching rubocop 0.60.00m 32mInstalling rubocop 0.60.00m GemWrappers: Can not wrap missing file: rubocop 32mFetching rubocop-rspec 1.30.10m 32mInstalling rubocop-rspec 1.30.10m 32mFetching threads 0.3.00m 32mInstalling threads 0.3.00m 32mBundle complete! 8 Gemfile dependencies, 19 gems now installed.0m 32mUse `bundle info [gemname]` to see where a bundled gem is installed.0m + rake /home/r/repo/Rakefile:27: warning: already initialized constant CLEAN /home/r/.ruby/gems/rake-12.3.1/lib/rake/clean.rb:63: warning: previous definition of CLEAN was here Run options: --seed 8156 # Running: .....Locked by #998-ex/assert-thread-0[/home/r/repo/test/test_futex.rb:41:in `block (2 levels) in test_syncs_access_to_file'] in 179μs, exclusive: /tmp/d20181226-998-1wqs8ic/a/b/c/file.txt (attempt no.0) Unlocked by #998-ex/assert-thread-0[/home/r/repo/test/test_futex.rb:41:in `block (2 levels) in test_syncs_access_to_file'] in 75μs, exclusive: /tmp/d20181226-998-1wqs8ic/a/b/c/file.txt Errno::ENOENT: No such file or directory @ rb_sysopen - /tmp/d20181226-998-1wqs8ic/a/b/c/file.txt.lock-refs /home/r/repo/lib/futex.rb:168:in `read' /home/r/repo/lib/futex.rb:168:in `block in open_synchronized' /home/r/repo/lib/futex.rb:189:in `block in synchronized' /home/r/repo/lib/futex.rb:187:in `open' /home/r/repo/lib/futex.rb:187:in `synchronized' /home/r/repo/lib/futex.rb:166:in `open_synchronized' /home/r/repo/lib/futex.rb:100:in `open' /home/r/repo/test/test_futex.rb:41:in `block (2 levels) in test_syncs_access_to_file' /home/r/.ruby/gems/threads-0.3.0/lib/threads.rb:57:in `block (3 levels) in assert' /home/r/.ruby/gems/threads-0.3.0/lib/threads.rb:53:in `loop' /home/r/.ruby/gems/threads-0.3.0/lib/threads.rb:53:in `block (2 levels) in assert' /home/r/.ruby/gems/concurrent-ruby-1.1.4/lib/concurrent/executor/ruby_thread_pool_executor.rb:348:in `run_task' /home/r/.ruby/gems/concurrent-ruby-1.1.4/lib/concurrent/executor/ruby_thread_pool_executor.rb:337:in `block (3 levels) in create_worker' /home/r/.ruby/gems/concurrent-ruby-1.1.4/lib/concurrent/executor/ruby_thread_pool_executor.rb:320:in `loop' /home/r/.ruby/gems/concurrent-ruby-1.1.4/lib/concurrent/executor/ruby_thread_pool_executor.rb:320:in `block (2 levels) in create_worker' /home/r/.ruby/gems/concurrent-ruby-1.1.4/lib/concurrent/executor/ruby_thread_pool_executor.rb:319:in `catch' /home/r/.ruby/gems/concurrent-ruby-1.1.4/lib/concurrent/executor/ruby_thread_pool_executor.rb:319:in `block in create_worker' E..... Finished in 8.077817s, 1.3618 runs/s, 149.9167 assertions/s. 1) Error: FutexTest#test_syncs_access_to_file: RuntimeError: Only 1 out of 2 threads completed successfully /home/r/.ruby/gems/threads-0.3.0/lib/threads.rb:70:in `assert' /home/r/repo/test/test_futex.rb:40:in `block in test_syncs_access_to_file' /usr/local/rvm/rubies/ruby-2.5.1/lib/ruby/2.5.0/tmpdir.rb:89:in `mktmpdir' /home/r/repo/test/test_futex.rb:38:in `test_syncs_access_to_file' 11 runs, 1211 assertions, 0 failures, 1 errors, 0 skips #<Thread:0x0000000000f68b00@/home/r/repo/test/test_futex.rb:80 aborting> terminated with exception (report_on_exception is true): 1mTracebackm (most recent call last): 6: from /home/r/repo/test/test_futex.rb:81:in `block (2 levels) in test_raises_if_cant_lock' 5: from /home/r/repo/lib/futex.rb:100:in `open' 4: from /home/r/repo/lib/futex.rb:173:in `open_synchronized' 3: from /home/r/repo/lib/futex.rb:173:in `ensure in open_synchronized' 2: from /home/r/repo/lib/futex.rb:187:in `synchronized' 1: from /home/r/repo/lib/futex.rb:187:in `open' /home/r/repo/lib/futex.rb:187:in `initialize': 1mNo such file or directory @ rb_sysopen - /tmp/d20181226-998-4t1zc4/the/simple/file.txt.lock-refs (1;4mErrno::ENOENTm1m) mrake aborted! Command failed with status (1) /home/r/.ruby/gems/rake-12.3.1/exe/rake:27:in `<top (required)>' Tasks: TOP => default => test (See full trace by running task with --trace) container dae779142874a4473c8271177b64fcf7bbcc2b1a44beff55d07da387bfea44f6 is dead Wed Dec 26 07:21:32 CET 2018