Skip to content

Instantly share code, notes, and snippets.

@pvalena

pvalena/tmt-run.log

Created August 11, 2020 02:44
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save pvalena/f2e1fb3d4badae291ed18e5471b0d91c to your computer and use it in GitHub Desktop.
Save pvalena/f2e1fb3d4badae291ed18e5471b0d91c to your computer and use it in GitHub Desktop.
Download ZIP
Raw
tmt-run.log
$ tmt run -avv execute -h beakerlib
/var/tmp/tmt/run-150
Found 1 plan.
/plans/default
discover
how: fmf
directory: /home/lpcs/lpcsf-new/test/fedora/packages/ruby/tests
summary: 2 tests selected
/run-basic-rails-application
/systemtap-static-probes-in-ruby
provision
how: virtual
image: fedora
user: root
memory: 2048 MB
disk: 10 GB
qcow: Fedora-Cloud-Base-32-1.6.x86_64.qcow2
name: MlmwZWOvenxSVMyk
progress: booting...
distro: Fedora release 32 (Thirty Two)
kernel: 5.6.6-300.fc32.x86_64
summary: 1 guest provisioned
prepare
how: install
summary: Install required packages
name: requires
package: beakerlib
how: install
summary: Install recommended packages
name: recommends
package: rubygems, gcc-c++, systemtap and 20 more
summary: 2 preparations applied
execute
how: beakerlib
stdout.log: ..D
stderr.log:
/var/tmp/tmt/run-150/plans/default $ main beakerlib < discover/run.yaml
> /run-basic-rails-application:
> test: ./runtest.sh
> path: /default/tests/run-basic-rails-application
> duration: 60m
> > path: /var/tmp/tmt/run-150/plans/default/discover/default/tests/run-basic-rails-application
> > environment:
> > duration: timeout '60m'
> > command: ./runtest.sh
> > type: beakerlib
> > out.log:
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Setup
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
ruby-2.7.1-132.fc32.x86_64
:: [ 02:33:05 ] :: [ PASS ] :: Checking for the presence of ruby rpm
:: [ 02:33:05 ] :: [ LOG ] :: Package versions:
:: [ 02:33:05 ] :: [ LOG ] :: ruby-2.7.1-132.fc32.x86_64
rubygems-3.1.2-132.fc32.noarch
:: [ 02:33:05 ] :: [ PASS ] :: Checking for the presence of rubygems rpm
:: [ 02:33:05 ] :: [ LOG ] :: Package versions:
:: [ 02:33:05 ] :: [ LOG ] :: rubygems-3.1.2-132.fc32.noarch
rubypick-1.1.1-12.fc32.noarch
:: [ 02:33:05 ] :: [ PASS ] :: Checking for the presence of rubypick rpm
:: [ 02:33:05 ] :: [ LOG ] :: Package versions:
:: [ 02:33:05 ] :: [ LOG ] :: rubypick-1.1.1-12.fc32.noarch
rubypick
:: [ 02:33:05 ] :: [ PASS ] :: Binary 'ruby' should belong to: ruby rubygems rubypick
rubygems
:: [ 02:33:05 ] :: [ PASS ] :: Binary 'gem' should belong to: ruby rubygems rubypick
:: [ 02:33:05 ] :: [ BEGIN ] :: Creating tmp directory :: actually running 'TmpDir=$(mktemp -d)'
:: [ 02:33:05 ] :: [ PASS ] :: Creating tmp directory (Expected 0, got 0)
:: [ 02:33:05 ] :: [ BEGIN ] :: Running 'pushd /tmp/tmp.7l41bbG2MZ'
/tmp/tmp.7l41bbG2MZ /var/tmp/tmt/run-150/plans/default/discover/default/tests/run-basic-rails-application
:: [ 02:33:05 ] :: [ PASS ] :: Command 'pushd /tmp/tmp.7l41bbG2MZ' (Expected 0, got 0)
true
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 0s
:: Assertions: 7 good, 0 bad
:: RESULT: PASS (Setup)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Test
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:06 ] :: [ BEGIN ] :: Creating new rails application :: actually running 'rails new app --skip-bundle --skip-spring --skip-test --skip-bootsnap --skip-webpacker --skip-javascript -f | tee bundle.log'
create
create README.md
create Rakefile
create .ruby-version
create config.ru
create .gitignore
create Gemfile
run git init from "."
create package.json
create app
create app/assets/config/manifest.js
create app/assets/javascripts/application.js
create app/assets/javascripts/cable.js
create app/assets/stylesheets/application.css
create app/channels/application_cable/channel.rb
create app/channels/application_cable/connection.rb
create app/controllers/application_controller.rb
create app/helpers/application_helper.rb
create app/jobs/application_job.rb
create app/mailers/application_mailer.rb
create app/models/application_record.rb
create app/views/layouts/application.html.erb
create app/views/layouts/mailer.html.erb
create app/views/layouts/mailer.text.erb
create app/assets/images/.keep
create app/assets/javascripts/channels
create app/assets/javascripts/channels/.keep
create app/controllers/concerns/.keep
create app/models/concerns/.keep
create bin
create bin/bundle
create bin/rails
create bin/rake
create bin/setup
create bin/update
create bin/yarn
create config
create config/routes.rb
create config/application.rb
create config/environment.rb
create config/cable.yml
create config/puma.rb
create config/storage.yml
create config/environments
create config/environments/development.rb
create config/environments/production.rb
create config/environments/test.rb
create config/initializers
create config/initializers/application_controller_renderer.rb
create config/initializers/assets.rb
create config/initializers/backtrace_silencers.rb
create config/initializers/content_security_policy.rb
create config/initializers/cookies_serializer.rb
create config/initializers/cors.rb
create config/initializers/filter_parameter_logging.rb
create config/initializers/inflections.rb
create config/initializers/mime_types.rb
create config/initializers/new_framework_defaults_5_2.rb
create config/initializers/wrap_parameters.rb
create config/locales
create config/locales/en.yml
create config/master.key
append .gitignore
/usr/share/gems/gems/activesupport-5.2.3/lib/active_support/message_encryptor.rb:175: warning: Using the last argument as keyword parameters is deprecated; maybe ** should be added to the call
/usr/share/gems/gems/activesupport-5.2.3/lib/active_support/messages/metadata.rb:17: warning: The called method `wrap' is defined here
create config/boot.rb
create config/database.yml
create db
create db/seeds.rb
create lib
create lib/tasks
create lib/tasks/.keep
create lib/assets
create lib/assets/.keep
create log
create log/.keep
create public
create public/404.html
create public/422.html
create public/500.html
create public/apple-touch-icon-precomposed.png
create public/apple-touch-icon.png
create public/favicon.ico
create public/robots.txt
create tmp
create tmp/.keep
create tmp/cache
create tmp/cache/assets
create vendor
create vendor/.keep
create storage
create storage/.keep
create tmp/storage
create tmp/storage/.keep
remove app/assets/javascripts
remove config/initializers/cors.rb
remove config/initializers/new_framework_defaults_5_2.rb
:: [ 02:33:06 ] :: [ PASS ] :: Creating new rails application (Expected 0, got 0)
:: [ 02:33:06 ] :: [ PASS ] :: File 'bundle.log' should not contain 'bundle install --local'
:: [ 02:33:06 ] :: [ BEGIN ] :: Running 'pushd app/'
/tmp/tmp.7l41bbG2MZ/app /tmp/tmp.7l41bbG2MZ /var/tmp/tmt/run-150/plans/default/discover/default/tests/run-basic-rails-application
:: [ 02:33:06 ] :: [ PASS ] :: Command 'pushd app/' (Expected 0, got 0)
:: [ 02:33:06 ] :: [ PASS ] :: Directory app should exist
:: [ 02:33:06 ] :: [ BEGIN ] :: Relaxing puma dependency :: actually running 'sed -i "s/\(gem..puma.\).*/\1/" Gemfile'
:: [ 02:33:06 ] :: [ PASS ] :: Relaxing puma dependency (Expected 0, got 0)
:: [ 02:33:06 ] :: [ BEGIN ] :: Relaxing listen dependency :: actually running 'sed -i "s/\(gem..listen.\).*/\1/" Gemfile'
:: [ 02:33:06 ] :: [ PASS ] :: Relaxing listen dependency (Expected 0, got 0)
:: [ 02:33:06 ] :: [ BEGIN ] :: Running 'bundle install --local --without development test'
[DEPRECATED] The `--without` flag is deprecated because it relies on being remembered across bundler invocations, which bundler will no longer do in future versions. Instead please use `bundle config set without 'development test'`, and stop using this flag
Don't run Bundler as root. Bundler can ask for sudo if it is needed, and
installing your bundle as root will break this application for all non-root
users on this machine.
Resolving dependencies...
The dependency tzinfo-data (>= 0) will be unused by any of the platforms Bundler is installing for. Bundler is installing for ruby but the dependency is only for x86-mingw32, x86-mswin32, x64-mingw32, java. To add those platforms to the bundle, run `bundle lock --add-platform x86-mingw32 x86-mswin32 x64-mingw32 java`.
Using rake 13.0.1
Using concurrent-ruby 1.1.5
Using i18n 1.8.2
Using minitest 5.14.1
Using thread_safe 0.3.6
Using tzinfo 1.2.5
Using activesupport 5.2.3
Using builder 3.2.4
Using erubi 1.7.0
Using nokogiri 1.10.9
Using rails-dom-testing 2.0.2
Using crass 1.0.4
Using loofah 2.3.1
Using rails-html-sanitizer 1.3.0
Using actionview 5.2.3
Using rack 2.2.2
Using rack-test 1.1.0
Using actionpack 5.2.3
Using nio4r 2.5.2
Using websocket-extensions 0.1.2
Using websocket-driver 0.6.5
Using actioncable 5.2.3
Using globalid 0.4.2
Using activejob 5.2.3
Using mime-types-data 3.2019.1009
Using mime-types 3.3.1
Using mail 2.6.4
Using actionmailer 5.2.3
Using activemodel 5.2.3
Using arel 9.0.0
Using activerecord 5.2.3
Using mimemagic 0.3.2
Using marcel 0.3.2
Using activestorage 5.2.3
Using bundler 2.1.4
Using ffi 1.12.1
Using multi_json 1.14.1
Using jbuilder 2.7.0
Using rb-inotify 0.10.0
Using listen 3.2.0
Using method_source 0.8.2
Using puma 4.3.3
Using thor 0.20.3
Using railties 5.2.3
Using sprockets 3.7.2
Using sprockets-rails 3.2.1
Using rails 5.2.3
Using sass 3.7.3
Using tilt 2.0.8
Using sass-rails 5.0.7
Using sqlite3 1.4.2
Bundle complete! 9 Gemfile dependencies, 51 gems now installed.
Gems in the groups development and test were not installed.
Use `bundle info [gemname]` to see where a bundled gem is installed.
:: [ 02:33:06 ] :: [ PASS ] :: Command 'bundle install --local --without development test' (Expected 0, got 0)
:: [ 02:33:06 ] :: [ BEGIN ] :: Check if port 3000 isn't engaged :: actually running 'netstat -tulpn | grep 3000'
:: [ 02:33:06 ] :: [ PASS ] :: Check if port 3000 isn't engaged (Expected 1, got 1)
:: [ 02:33:06 ] :: [ BEGIN ] :: Running rails server :: actually running 'rails server &'
:: [ 02:33:06 ] :: [ PASS ] :: Running rails server (Expected 0, got 0)
/usr/share/gems/gems/tzinfo-1.2.5/lib/tzinfo/ruby_core_support.rb:142: warning: Using the last argument as keyword parameters is deprecated
/usr/share/gems/gems/tzinfo-1.2.5/lib/tzinfo/ruby_core_support.rb:142: warning: Using the last argument as keyword parameters is deprecated
/usr/share/gems/gems/actionpack-5.2.3/lib/action_dispatch/middleware/stack.rb:37: warning: Using the last argument as keyword parameters is deprecated; maybe ** should be added to the call
/usr/share/gems/gems/actionpack-5.2.3/lib/action_dispatch/middleware/static.rb:111: warning: The called method `initialize' is defined here
:: [ 02:33:16 ] :: [ BEGIN ] :: Wgetting running rails application :: actually running 'wget http://0.0.0.0:3000'
--2020-08-11 02:33:16-- http://0.0.0.0:3000/
Connecting to 0.0.0.0:3000... connected.
HTTP request sent, awaiting response... => Booting Puma
=> Rails 5.2.3 application starting in development
=> Run `rails server -h` for more startup options
Puma starting in single mode...
* Version 4.3.3 (ruby 2.7.1-p83), codename: Mysterious Traveller
* Min threads: 5, max threads: 5
* Environment: development
* Listening on tcp://127.0.0.1:3000
* Listening on tcp://[::1]:3000
Use Ctrl-C to stop
Started GET "/" for 127.0.0.1 at 2020-08-11 02:33:17 +0000
Processing by Rails::WelcomeController#index as */*
/usr/share/gems/gems/actionview-5.2.3/lib/action_view/view_paths.rb:11: warning: Using the last argument as keyword parameters is deprecated; maybe ** should be added to the call
/usr/share/gems/gems/actionview-5.2.3/lib/action_view/lookup_context.rb:128: warning: The called method `template_exists?' is defined here
Rendering /usr/share/gems/gems/railties-5.2.3/lib/rails/templates/rails/welcome/index.html.erb
Rendered /usr/share/gems/gems/railties-5.2.3/lib/rails/templates/rails/welcome/index.html.erb (2.0ms)
Completed 200 OK in 7ms (Views: 5.2ms | ActiveRecord: 0.0ms)
200 OK
Length: unspecified [text/html]
Saving to: ‘index.html’
0K .......... .......... .......... .......... .......... 97.6M
50K .......... .......... .......... .......... .......... 216M
100K .......... .......... 221M=0.001s
2020-08-11 02:33:17 (144 MB/s) - ‘index.html’ saved [123063]
:: [ 02:33:17 ] :: [ PASS ] :: Wgetting running rails application (Expected 0, got 0)
:: [ 02:33:17 ] :: [ BEGIN ] :: Killing rails server :: actually running 'pkill ruby'
- Gracefully stopping, waiting for requests to finish
=== puma shutdown: 2020-08-11 02:33:17 +0000 ===
- Goodbye!
Exiting
:: [ 02:33:17 ] :: [ PASS ] :: Killing rails server (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 11s
:: Assertions: 11 good, 0 bad
:: RESULT: PASS (Test)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Cleanup
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:17 ] :: [ BEGIN ] :: Running 'popd; popd'
/tmp/tmp.7l41bbG2MZ /var/tmp/tmt/run-150/plans/default/discover/default/tests/run-basic-rails-application
/var/tmp/tmt/run-150/plans/default/discover/default/tests/run-basic-rails-application
:: [ 02:33:17 ] :: [ PASS ] :: Command 'popd; popd' (Expected 0, got 0)
:: [ 02:33:17 ] :: [ BEGIN ] :: Removing tmp directory :: actually running 'rm -r /tmp/tmp.7l41bbG2MZ'
:: [ 02:33:17 ] :: [ PASS ] :: Removing tmp directory (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 0s
:: Assertions: 2 good, 0 bad
:: RESULT: PASS (Cleanup)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: TEST PROTOCOL
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
Package : ruby rubygems rubypick
Installed : ruby-2.7.1-132.fc32.x86_64
beakerlib RPM : beakerlib-1.18-12.fc32.noarch
Test name : unknown
Test started : 2020-08-11 02:33:05 UTC
Test finished : 2020-08-11 02:33:17 UTC (still running)
Test duration : 12 seconds
Distro : Fedora release 32 (Thirty Two)
Hostname : testcloud
Architecture : x86_64
CPUs : 1 x Intel(R) Core(TM) i7-8650U CPU @ 1.90GHz
RAM size : 1979 MB
HDD size : 9.77 GB
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Test description
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
PURPOSE of /CoreOS/rubygem-rails/run-basic-rails-application
Description: Test creates new rails application and runs it
Author: Iveta Senfeldova <isenfeld@redhat.com>
Test ensure it is possible to create new rails application and run it.
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Setup
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:05 ] :: [ PASS ] :: Checking for the presence of ruby rpm
:: [ 02:33:05 ] :: [ LOG ] :: Package versions:
:: [ 02:33:05 ] :: [ LOG ] :: ruby-2.7.1-132.fc32.x86_64
:: [ 02:33:05 ] :: [ PASS ] :: Checking for the presence of rubygems rpm
:: [ 02:33:05 ] :: [ LOG ] :: Package versions:
:: [ 02:33:05 ] :: [ LOG ] :: rubygems-3.1.2-132.fc32.noarch
:: [ 02:33:05 ] :: [ PASS ] :: Checking for the presence of rubypick rpm
:: [ 02:33:05 ] :: [ LOG ] :: Package versions:
:: [ 02:33:05 ] :: [ LOG ] :: rubypick-1.1.1-12.fc32.noarch
:: [ 02:33:05 ] :: [ PASS ] :: Binary 'ruby' should belong to: ruby rubygems rubypick
:: [ 02:33:05 ] :: [ PASS ] :: Binary 'gem' should belong to: ruby rubygems rubypick
:: [ 02:33:05 ] :: [ PASS ] :: Creating tmp directory (Expected 0, got 0)
:: [ 02:33:05 ] :: [ PASS ] :: Command 'pushd /tmp/tmp.7l41bbG2MZ' (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 0s
:: Assertions: 7 good, 0 bad
:: RESULT: PASS (Setup)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Test
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:06 ] :: [ PASS ] :: Creating new rails application (Expected 0, got 0)
:: [ 02:33:06 ] :: [ PASS ] :: File 'bundle.log' should not contain 'bundle install --local'
:: [ 02:33:06 ] :: [ PASS ] :: Command 'pushd app/' (Expected 0, got 0)
:: [ 02:33:06 ] :: [ PASS ] :: Directory app should exist
:: [ 02:33:06 ] :: [ PASS ] :: Relaxing puma dependency (Expected 0, got 0)
:: [ 02:33:06 ] :: [ PASS ] :: Relaxing listen dependency (Expected 0, got 0)
:: [ 02:33:06 ] :: [ PASS ] :: Command 'bundle install --local --without development test' (Expected 0, got 0)
:: [ 02:33:06 ] :: [ PASS ] :: Check if port 3000 isn't engaged (Expected 1, got 1)
:: [ 02:33:06 ] :: [ PASS ] :: Running rails server (Expected 0, got 0)
:: [ 02:33:17 ] :: [ PASS ] :: Wgetting running rails application (Expected 0, got 0)
:: [ 02:33:17 ] :: [ PASS ] :: Killing rails server (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 11s
:: Assertions: 11 good, 0 bad
:: RESULT: PASS (Test)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Cleanup
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:17 ] :: [ PASS ] :: Command 'popd; popd' (Expected 0, got 0)
:: [ 02:33:17 ] :: [ PASS ] :: Removing tmp directory (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 0s
:: Assertions: 2 good, 0 bad
:: RESULT: PASS (Cleanup)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: unknown
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:17 ] :: [ LOG ] :: JOURNAL XML: /var/tmp/tmt/run-150/plans/default/execute/logs/run-basic-rails-application/journal.xml
:: [ 02:33:17 ] :: [ LOG ] :: JOURNAL TXT: /var/tmp/tmt/run-150/plans/default/execute/logs/run-basic-rails-application/journal.txt
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 12s
:: Phases: 3 good, 0 bad
:: OVERALL RESULT: PASS (unknown)
> > exitcode.log:
0
> /systemtap-static-probes-in-ruby:
> test: ./runtest.sh
> path: /default/tests/systemtap-static-probes-in-ruby
> duration: 5m
> > path: /var/tmp/tmt/run-150/plans/default/discover/default/tests/systemtap-static-probes-in-ruby
> > environment:
> > duration: timeout '5m'
> > command: ./runtest.sh
> > type: beakerlib
> > out.log:
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Setup
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
ruby-2.7.1-132.fc32.x86_64
:: [ 02:33:17 ] :: [ PASS ] :: Checking for the presence of ruby rpm
:: [ 02:33:17 ] :: [ LOG ] :: Package versions:
:: [ 02:33:17 ] :: [ LOG ] :: ruby-2.7.1-132.fc32.x86_64
ruby-libs-2.7.1-132.fc32.x86_64
:: [ 02:33:17 ] :: [ PASS ] :: Checking for the presence of ruby-libs rpm
:: [ 02:33:17 ] :: [ LOG ] :: Package versions:
:: [ 02:33:17 ] :: [ LOG ] :: ruby-libs-2.7.1-132.fc32.x86_64
rubypick-1.1.1-12.fc32.noarch
:: [ 02:33:17 ] :: [ PASS ] :: Checking for the presence of rubypick rpm
:: [ 02:33:17 ] :: [ LOG ] :: Package versions:
:: [ 02:33:17 ] :: [ LOG ] :: rubypick-1.1.1-12.fc32.noarch
ruby-doc-2.7.1-132.fc32.noarch
:: [ 02:33:17 ] :: [ PASS ] :: Checking for the presence of ruby-doc rpm
:: [ 02:33:17 ] :: [ LOG ] :: Package versions:
:: [ 02:33:17 ] :: [ LOG ] :: ruby-doc-2.7.1-132.fc32.noarch
rubypick
:: [ 02:33:17 ] :: [ PASS ] :: Binary 'ruby' should belong to: ruby ruby-libs rubypick ruby-doc
:: [ 02:33:17 ] :: [ PASS ] :: File /usr/share/doc/ruby-doc/ruby-exercise.stp should exist
:: [ 02:33:17 ] :: [ BEGIN ] :: Creating tmp directory :: actually running 'TmpDir=`mktemp -d`'
:: [ 02:33:17 ] :: [ PASS ] :: Creating tmp directory (Expected 0, got 0)
:: [ 02:33:17 ] :: [ BEGIN ] :: Running 'pushd /tmp/tmp.L0tYR8gIcO'
/tmp/tmp.L0tYR8gIcO /var/tmp/tmt/run-150/plans/default/discover/default/tests/systemtap-static-probes-in-ruby
:: [ 02:33:17 ] :: [ PASS ] :: Command 'pushd /tmp/tmp.L0tYR8gIcO' (Expected 0, got 0)
:: [ 02:33:18 ] :: [ BEGIN ] :: Installing corresponding kernel-devel: $_KERNEL_DEVEL :: actually running 'rpm -q 'kernel-devel-5.6.6-300.fc32.x86_64' || dnf install -y 'kernel-devel-5.6.6-300.fc32.x86_64''
package kernel-devel-5.6.6-300.fc32.x86_64 is not installed
Last metadata expiration check: 0:01:45 ago on Tue 11 Aug 2020 02:31:34 AM UTC.
Dependencies resolved.
================================================================================
Package Architecture Version Repository Size
================================================================================
Installing:
kernel-devel x86_64 5.6.6-300.fc32 fedora 13 M
Transaction Summary
================================================================================
Install 1 Package
Total download size: 13 M
Installed size: 50 M
Downloading Packages:
kernel-devel-5.6.6-300.fc32.x86_64.rpm 8.0 MB/s | 13 MB 00:01
--------------------------------------------------------------------------------
Total 5.8 MB/s | 13 MB 00:02
Running transaction check
Transaction check succeeded.
Running transaction test
Transaction test succeeded.
Running transaction
Preparing : 1/1
Installing : kernel-devel-5.6.6-300.fc32.x86_64 1/1
Running scriptlet: kernel-devel-5.6.6-300.fc32.x86_64 1/1
Verifying : kernel-devel-5.6.6-300.fc32.x86_64 1/1
Installed:
kernel-devel-5.6.6-300.fc32.x86_64
Complete!
:: [ 02:33:33 ] :: [ PASS ] :: Installing corresponding kernel-devel: $_KERNEL_DEVEL (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 16s
:: Assertions: 9 good, 0 bad
:: RESULT: PASS (Setup)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Test
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:33 ] :: [ BEGIN ] :: Checking tapset file :: actually running 'rpm -ql ruby ruby-libs rubypick ruby-doc | grep 'stp' | grep 'tapset''
/usr/share/systemtap/tapset/libruby.so.2.7.stp
:: [ 02:33:33 ] :: [ PASS ] :: Checking tapset file (Expected 0, got 0)
:: [ 02:33:33 ] :: [ BEGIN ] :: Checking doc file :: actually running 'rpm -ql ruby ruby-libs rubypick ruby-doc | grep 'stp' | grep 'doc''
/usr/share/doc/ruby-doc/ruby-exercise.stp
:: [ 02:33:33 ] :: [ PASS ] :: Checking doc file (Expected 0, got 0)
:: [ 02:33:33 ] :: [ BEGIN ] :: Running systemtap in background :: actually running '( timeout 60 stap -v /usr/share/doc/ruby-doc/ruby-exercise.stp -o /tmp/tmp.L0tYR8gIcO/ruby.log 1>&2 2>stap-out.log & )'
:: [ 02:33:33 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:34 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
Pass 5: starting run.
:: [ 02:33:48 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:48 ] :: [ BEGIN ] :: Checking stap output. :: actually running 'grep -q 'starting run' stap-out.log'
:: [ 02:33:48 ] :: [ PASS ] :: Checking stap output. (Expected 0, got 0)
---/tmp/tmp.L0tYR8gIcO/ruby.log--
:: [ 02:33:49 ] :: [ BEGIN ] :: Running systemtap in background :: actually running '( timeout 2 stap -v /usr/share/doc/ruby-doc/ruby-exercise.stp -o /tmp/tmp.L0tYR8gIcO/ruby.log 1>&2 2>stap-out.log & )'
:: [ 02:33:49 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:50 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
Pass 5: starting run.
:: [ 02:33:50 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:50 ] :: [ BEGIN ] :: Running ruby interpreter: Array test :: actually running 'ruby -e '[1, 2, 3].push(4)''
:: [ 02:33:50 ] :: [ PASS ] :: Running ruby interpreter: Array test (Expected 0, got 0)
---/tmp/tmp.L0tYR8gIcO/ruby.log--
18105 -> Array::push -e:1
18105 <- Array::push -e:1
:: [ 02:33:51 ] :: [ BEGIN ] :: Checking systemtap log: Array test :: actually running 'grep -q 'Array::push -e:1$' /tmp/tmp.L0tYR8gIcO/ruby.log'
:: [ 02:33:51 ] :: [ PASS ] :: Checking systemtap log: Array test (Expected 0, got 0)
:: [ 02:33:51 ] :: [ BEGIN ] :: Running systemtap in background :: actually running '( timeout 2 stap -v /usr/share/doc/ruby-doc/ruby-exercise.stp -o /tmp/tmp.L0tYR8gIcO/ruby.log 1>&2 2>stap-out.log & )'
:: [ 02:33:51 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:52 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
Pass 5: starting run.
:: [ 02:33:52 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:52 ] :: [ BEGIN ] :: Running ruby interpreter: Range test :: actually running 'ruby -e '(1..10).step(1)''
:: [ 02:33:52 ] :: [ PASS ] :: Running ruby interpreter: Range test (Expected 0, got 0)
---/tmp/tmp.L0tYR8gIcO/ruby.log--
18194 -> Range::step -e:1
18194 <- Range::step -e:1
18194 -> Range::step -e:1
18194 <- Range::step -e:1
:: [ 02:33:53 ] :: [ BEGIN ] :: Checking systemtap log: Range test :: actually running 'grep -q 'Range::step -e:1$' /tmp/tmp.L0tYR8gIcO/ruby.log'
:: [ 02:33:53 ] :: [ PASS ] :: Checking systemtap log: Range test (Expected 0, got 0)
:: [ 02:33:53 ] :: [ BEGIN ] :: Running systemtap in background :: actually running '( timeout 2 stap -v /usr/share/doc/ruby-doc/ruby-exercise.stp -o /tmp/tmp.L0tYR8gIcO/ruby.log 1>&2 2>stap-out.log & )'
:: [ 02:33:53 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:54 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
Pass 5: starting run.
:: [ 02:33:55 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:55 ] :: [ BEGIN ] :: Running ruby interpreter: Hash test :: actually running 'ruby -e 'Hash.new()''
:: [ 02:33:55 ] :: [ PASS ] :: Running ruby interpreter: Hash test (Expected 0, got 0)
---/tmp/tmp.L0tYR8gIcO/ruby.log--
Binary file /tmp/tmp.L0tYR8gIcO/ruby.log matches
:: [ 02:33:56 ] :: [ BEGIN ] :: Checking systemtap log: Hash test :: actually running 'grep -q 'Hash::initialize -e:1$' /tmp/tmp.L0tYR8gIcO/ruby.log'
:: [ 02:33:56 ] :: [ PASS ] :: Checking systemtap log: Hash test (Expected 0, got 0)
:: [ 02:33:56 ] :: [ BEGIN ] :: Running systemtap in background :: actually running '( timeout 2 stap -v /usr/share/doc/ruby-doc/ruby-exercise.stp -o /tmp/tmp.L0tYR8gIcO/ruby.log 1>&2 2>stap-out.log & )'
:: [ 02:33:56 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:57 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
Pass 5: starting run.
:: [ 02:33:57 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:57 ] :: [ BEGIN ] :: Running ruby interpreter: String test :: actually running 'ruby -e '"abc".slice!("c")''
:: [ 02:33:57 ] :: [ PASS ] :: Running ruby interpreter: String test (Expected 0, got 0)
---/tmp/tmp.L0tYR8gIcO/ruby.log--
Binary file /tmp/tmp.L0tYR8gIcO/ruby.log matches
:: [ 02:33:58 ] :: [ BEGIN ] :: Checking systemtap log: String test :: actually running 'grep -q 'String::slice! -e:1$' /tmp/tmp.L0tYR8gIcO/ruby.log'
:: [ 02:33:58 ] :: [ PASS ] :: Checking systemtap log: String test (Expected 0, got 0)
:: [ 02:33:58 ] :: [ BEGIN ] :: Running systemtap in background :: actually running '( timeout 2 stap -v /usr/share/doc/ruby-doc/ruby-exercise.stp -o /tmp/tmp.L0tYR8gIcO/ruby.log 1>&2 2>stap-out.log & )'
:: [ 02:33:58 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:59 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
Pass 5: starting run.
:: [ 02:33:59 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:59 ] :: [ BEGIN ] :: Running ruby interpreter: Cycle and print test :: actually running 'ruby -e '5.times { printf "Ruby!
" }''
Ruby!
Ruby!
Ruby!
Ruby!
Ruby!
:: [ 02:33:59 ] :: [ PASS ] :: Running ruby interpreter: Cycle and print test (Expected 0, got 0)
---/tmp/tmp.L0tYR8gIcO/ruby.log--
Binary file /tmp/tmp.L0tYR8gIcO/ruby.log matches
:: [ 02:34:01 ] :: [ BEGIN ] :: Checking systemtap log: Cycle and print test - iteration number :: actually running 'grep -q 'Integer::times -e:1$' /tmp/tmp.L0tYR8gIcO/ruby.log'
:: [ 02:34:01 ] :: [ PASS ] :: Checking systemtap log: Cycle and print test - iteration number (Expected 0, got 0)
:: [ 02:34:01 ] :: [ BEGIN ] :: Checking systemtap log: Cycle and print test - printf function :: actually running 'grep -q 'Kernel::printf -e:1$' /tmp/tmp.L0tYR8gIcO/ruby.log'
:: [ 02:34:01 ] :: [ PASS ] :: Checking systemtap log: Cycle and print test - printf function (Expected 0, got 0)
:: [ 02:34:01 ] :: [ BEGIN ] :: Checking systemtap log: Cycle and print test - IO write :: actually running 'grep -q 'IO::write -e:1$' /tmp/tmp.L0tYR8gIcO/ruby.log'
:: [ 02:34:01 ] :: [ PASS ] :: Checking systemtap log: Cycle and print test - IO write (Expected 0, got 0)
:: [ 02:34:01 ] :: [ BEGIN ] :: Attempt killing all leftover stapios :: actually running 'pkill stapio'
:: [ 02:34:01 ] :: [ PASS ] :: Attempt killing all leftover stapios (Expected 0,1, got 0)
---ps-out--
17180 ? 00:00:00 stap
17952 ? 00:00:00 stapio
18416 ? 00:00:00 stap
18429 ? 00:00:00 stapio
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 28s
:: Assertions: 22 good, 0 bad
:: RESULT: PASS (Test)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Cleanup
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:34:01 ] :: [ BEGIN ] :: Running 'popd'
/var/tmp/tmt/run-150/plans/default/discover/default/tests/systemtap-static-probes-in-ruby
:: [ 02:34:01 ] :: [ PASS ] :: Command 'popd' (Expected 0, got 0)
:: [ 02:34:01 ] :: [ BEGIN ] :: Removing tmp directory :: actually running 'rm -r /tmp/tmp.L0tYR8gIcO'
:: [ 02:34:01 ] :: [ PASS ] :: Removing tmp directory (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 0s
:: Assertions: 2 good, 0 bad
:: RESULT: PASS (Cleanup)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: TEST PROTOCOL
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
Package : ruby ruby-libs rubypick ruby-doc
Installed : ruby-2.7.1-132.fc32.x86_64
beakerlib RPM : beakerlib-1.18-12.fc32.noarch
Test name : unknown
Test started : 2020-08-11 02:33:17 UTC
Test finished : 2020-08-11 02:34:01 UTC (still running)
Test duration : 44 seconds
Distro : Fedora release 32 (Thirty Two)
Hostname : testcloud
Architecture : x86_64
CPUs : 1 x Intel(R) Core(TM) i7-8650U CPU @ 1.90GHz
RAM size : 1979 MB
HDD size : 9.77 GB
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Test description
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
Simple test to exercise ruby's systemtap API.
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Setup
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:17 ] :: [ PASS ] :: Checking for the presence of ruby rpm
:: [ 02:33:17 ] :: [ LOG ] :: Package versions:
:: [ 02:33:17 ] :: [ LOG ] :: ruby-2.7.1-132.fc32.x86_64
:: [ 02:33:17 ] :: [ PASS ] :: Checking for the presence of ruby-libs rpm
:: [ 02:33:17 ] :: [ LOG ] :: Package versions:
:: [ 02:33:17 ] :: [ LOG ] :: ruby-libs-2.7.1-132.fc32.x86_64
:: [ 02:33:17 ] :: [ PASS ] :: Checking for the presence of rubypick rpm
:: [ 02:33:17 ] :: [ LOG ] :: Package versions:
:: [ 02:33:17 ] :: [ LOG ] :: rubypick-1.1.1-12.fc32.noarch
:: [ 02:33:17 ] :: [ PASS ] :: Checking for the presence of ruby-doc rpm
:: [ 02:33:17 ] :: [ LOG ] :: Package versions:
:: [ 02:33:17 ] :: [ LOG ] :: ruby-doc-2.7.1-132.fc32.noarch
:: [ 02:33:17 ] :: [ PASS ] :: Binary 'ruby' should belong to: ruby ruby-libs rubypick ruby-doc
:: [ 02:33:17 ] :: [ PASS ] :: File /usr/share/doc/ruby-doc/ruby-exercise.stp should exist
:: [ 02:33:17 ] :: [ PASS ] :: Creating tmp directory (Expected 0, got 0)
:: [ 02:33:17 ] :: [ PASS ] :: Command 'pushd /tmp/tmp.L0tYR8gIcO' (Expected 0, got 0)
:: [ 02:33:33 ] :: [ PASS ] :: Installing corresponding kernel-devel: $_KERNEL_DEVEL (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 16s
:: Assertions: 9 good, 0 bad
:: RESULT: PASS (Setup)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Test
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:33:33 ] :: [ PASS ] :: Checking tapset file (Expected 0, got 0)
:: [ 02:33:33 ] :: [ PASS ] :: Checking doc file (Expected 0, got 0)
:: [ 02:33:33 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:34 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
:: [ 02:33:48 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:48 ] :: [ PASS ] :: Checking stap output. (Expected 0, got 0)
:: [ 02:33:49 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:50 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
:: [ 02:33:50 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:50 ] :: [ PASS ] :: Running ruby interpreter: Array test (Expected 0, got 0)
:: [ 02:33:51 ] :: [ PASS ] :: Checking systemtap log: Array test (Expected 0, got 0)
:: [ 02:33:51 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:52 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
:: [ 02:33:52 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:52 ] :: [ PASS ] :: Running ruby interpreter: Range test (Expected 0, got 0)
:: [ 02:33:53 ] :: [ PASS ] :: Checking systemtap log: Range test (Expected 0, got 0)
:: [ 02:33:53 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:54 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
:: [ 02:33:55 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:55 ] :: [ PASS ] :: Running ruby interpreter: Hash test (Expected 0, got 0)
:: [ 02:33:56 ] :: [ PASS ] :: Checking systemtap log: Hash test (Expected 0, got 0)
:: [ 02:33:56 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:57 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
:: [ 02:33:57 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:57 ] :: [ PASS ] :: Running ruby interpreter: String test (Expected 0, got 0)
:: [ 02:33:58 ] :: [ PASS ] :: Checking systemtap log: String test (Expected 0, got 0)
:: [ 02:33:58 ] :: [ PASS ] :: Running systemtap in background (Expected 0, got 0)
:: [ 02:33:59 ] :: [ INFO ] :: rlWaitForCmd: waiting for `grep 'starting run' stap-out.log' to return 0 in 120 seconds
:: [ 02:33:59 ] :: [ INFO ] :: rlWaitForCmd: Wait successful!
:: [ 02:33:59 ] :: [ PASS ] :: Running ruby interpreter: Cycle and print test (Expected 0, got 0)
:: [ 02:34:01 ] :: [ PASS ] :: Checking systemtap log: Cycle and print test - iteration number (Expected 0, got 0)
:: [ 02:34:01 ] :: [ PASS ] :: Checking systemtap log: Cycle and print test - printf function (Expected 0, got 0)
:: [ 02:34:01 ] :: [ PASS ] :: Checking systemtap log: Cycle and print test - IO write (Expected 0, got 0)
:: [ 02:34:01 ] :: [ PASS ] :: Attempt killing all leftover stapios (Expected 0,1, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 28s
:: Assertions: 22 good, 0 bad
:: RESULT: PASS (Test)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Cleanup
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:34:01 ] :: [ PASS ] :: Command 'popd' (Expected 0, got 0)
:: [ 02:34:01 ] :: [ PASS ] :: Removing tmp directory (Expected 0, got 0)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 0s
:: Assertions: 2 good, 0 bad
:: RESULT: PASS (Cleanup)
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: unknown
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: [ 02:34:01 ] :: [ LOG ] :: JOURNAL XML: /var/tmp/tmt/run-150/plans/default/execute/logs/systemtap-static-probes-in-ruby/journal.xml
:: [ 02:34:01 ] :: [ LOG ] :: JOURNAL TXT: /var/tmp/tmt/run-150/plans/default/execute/logs/systemtap-static-probes-in-ruby/journal.txt
::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::
:: Duration: 44s
:: Phases: 3 good, 0 bad
:: OVERALL RESULT: PASS (unknown)
> > exitcode.log:
0
Test execution finished.
summary: 2 tests executed
report
how: display
pass /run-basic-rails-application
out.log: /var/tmp/tmt/run-150/plans/default/execute/logs/run-basic-rails-application/out.log
journal.txt: /var/tmp/tmt/run-150/plans/default/execute/logs/run-basic-rails-application/journal.txt
pass /systemtap-static-probes-in-ruby
out.log: /var/tmp/tmt/run-150/plans/default/execute/logs/systemtap-static-probes-in-ruby/out.log
journal.txt: /var/tmp/tmt/run-150/plans/default/execute/logs/systemtap-static-probes-in-ruby/journal.txt
summary: 2 tests passed
finish
guest: stopped
guest: removed
summary: 0 tasks completed
total: 2 tests passed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment