Avocado API Skipping all the tests #4835

marioaag · 2021-08-09T17:22:43Z

marioaag
Aug 9, 2021

Hi,

Is there a way to find the reason why avocado is skipping all the tests that I'm trying to run?

I'm using the nrunner and the API.

JOB ID     : 7064f5b08eb5bc1896b2b78608cd529835ca92ed
JOB LOG    : /tmp/avocado_logs/job-2021-08-09T12.18-7064f5b/job.log
2021-08-09 12:18:02 -D- BlueGen: Using selector: KqueueSelector
RESULTS    : PASS 0 | ERROR 0 | FAIL 0 | SKIP 1 | WARN 0 | INTERRUPT 0 | CANCEL 0
JOB HTML   : /tmp/avocado_logs/job-2021-08-09T12.18-7064f5b/results.html
JOB TIME   : 2.50 s

Avocado version installed -> avocado-framework-90.0

In the previous versions I didn't get this behavior

Answered by clebergnu

Aug 18, 2021

@marioaag, would you be able to run the same job/tests, but using avocado from #4864 ?

That version includes some extra checks and debugging information on the avocado.core.DEBUG file that is created on the job results directory. If you could please include that output here, it would be very useful.

Thanks!

View full answer

beraldoleal · 2021-08-09T17:33:19Z

beraldoleal
Aug 9, 2021
Maintainer

@marioaag can you please paste your /tmp/avocado_logs/job-2021-08-09T12.18-7064f5b/job.log and the specific test job file (if any)?

0 replies

marioaag · 2021-08-09T18:50:37Z

marioaag
Aug 9, 2021
Author

Note:
Checked the installation and python version and ensured that everything were in python3.9

Executing only one suit:

(base) marioalvarado@MacBook-Pro iLabConfig % cat /tmp/avocado_logs/job-2021-08-09T13.42-00075e7/job.log
2021-08-09 13:42:27,394 job              L0287 I    | Command line: /usr/local/lib/python3.9/site-packages/bluegen/common/main.py test -t CheckHostStatus.py:CheckHostStatus.test_os_info
2021-08-09 13:42:27,394 job              L0288 I    | 
2021-08-09 13:42:27,394 job              L0281 I    | Avocado version: 90.0
2021-08-09 13:42:27,394 job              L0282 I    | 
2021-08-09 13:42:27,394 job              L0260 I    | Avocado config:
2021-08-09 13:42:27,394 job              L0261 I    | 
2021-08-09 13:42:27,395 job              L0263 I    | {'core.input_encoding': 'utf-8',
2021-08-09 13:42:27,396 job              L0263 I    |  'core.show': ['app'],
2021-08-09 13:42:27,396 job              L0263 I    |  'datadir.paths.base_dir': '/Users/marioalvarado/avocado',
2021-08-09 13:42:27,396 job              L0263 I    |  'datadir.paths.cache_dirs': ['/Users/marioalvarado/avocado/data/cache'],
2021-08-09 13:42:27,396 job              L0263 I    |  'datadir.paths.data_dir': '/Users/marioalvarado/avocado/data',
2021-08-09 13:42:27,396 job              L0263 I    |  'datadir.paths.logs_dir': '/tmp/avocado_logs',
2021-08-09 13:42:27,396 job              L0263 I    |  'datadir.paths.test_dir': '/Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus',
2021-08-09 13:42:27,396 job              L0263 I    |  'job.output.loglevel': 'DEBUG',
2021-08-09 13:42:27,396 job              L0263 I    |  'job.output.testlogs.logfiles': ['debug.log'],
2021-08-09 13:42:27,396 job              L0263 I    |  'job.output.testlogs.statuses': [],
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.html.enabled': True,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.html.open_browser': False,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.html.output': None,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.json.enabled': True,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.json.output': None,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.tap.enabled': True,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.tap.include_logs': False,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.tap.output': None,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.xunit.enabled': True,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.xunit.job_name': None,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.xunit.max_test_log_chars': 100000,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.result.xunit.output': None,
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.store_logging_stream': [],
2021-08-09 13:42:27,396 job              L0263 I    |  'job.run.timeout': 0,
2021-08-09 13:42:27,397 job              L0263 I    |  'json.variants.load': None,
2021-08-09 13:42:27,397 job              L0263 I    |  'nrunner.max_parallel_tasks': 12,
2021-08-09 13:42:27,397 job              L0263 I    |  'nrunner.shuffle': False,
2021-08-09 13:42:27,397 job              L0263 I    |  'nrunner.spawner': 'process',
2021-08-09 13:42:27,397 job              L0263 I    |  'nrunner.status_server_buffer_size': 33554432,
2021-08-09 13:42:27,397 job              L0263 I    |  'nrunner.status_server_listen': '127.0.0.1:8888',
2021-08-09 13:42:27,397 job              L0263 I    |  'nrunner.status_server_uri': '127.0.0.1:8888',
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.cli.cmd.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.cli.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.disable': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.init.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.job.prepost.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.jobscripts.post': '/etc/avocado/scripts/job/post.d/',
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.jobscripts.pre': '/etc/avocado/scripts/job/pre.d/',
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.jobscripts.warn_non_existing_dir': False,
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.jobscripts.warn_non_zero_status': True,
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.resolver.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.result.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.result_events.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.runnable.runner.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.runner.order': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.skip_broken_plugin_notification': [],
2021-08-09 13:42:27,397 job              L0263 I    |  'plugins.spawner.order': [],
2021-08-09 13:42:27,398 job              L0263 I    |  'plugins.varianter.order': [],
2021-08-09 13:42:27,398 job              L0263 I    |  'run.dict_variants': [],
2021-08-09 13:42:27,398 job              L0263 I    |  'run.execution_order': 'variants-per-test',
2021-08-09 13:42:27,398 job              L0263 I    |  'run.references': [],
2021-08-09 13:42:27,398 job              L0263 I    |  'run.test_runner': 'nrunner',
2021-08-09 13:42:27,398 job              L0263 I    |  'runner.exectest.exitcodes.skip': [],
2021-08-09 13:42:27,398 job              L0263 I    |  'runner.output.color': 'auto',
2021-08-09 13:42:27,398 job              L0263 I    |  'runner.output.colored': True,
2021-08-09 13:42:27,398 job              L0263 I    |  'runner.output.utf8': True,
2021-08-09 13:42:27,398 job              L0263 I    |  'runner.timeout.after_interrupted': 60,
2021-08-09 13:42:27,398 job              L0263 I    |  'runner.timeout.process_alive': 60,
2021-08-09 13:42:27,398 job              L0263 I    |  'runner.timeout.process_died': 10,
2021-08-09 13:42:27,398 job              L0263 I    |  'simpletests.status.failure_fields': ['status', 'stdout', 'stderr'],
2021-08-09 13:42:27,398 job              L0263 I    |  'simpletests.status.skip_location': 'all',
2021-08-09 13:42:27,398 job              L0263 I    |  'simpletests.status.skip_regex': '^SKIP$',
2021-08-09 13:42:27,398 job              L0263 I    |  'simpletests.status.warn_location': 'all',
2021-08-09 13:42:27,398 job              L0263 I    |  'simpletests.status.warn_regex': '^WARN$',
2021-08-09 13:42:27,398 job              L0263 I    |  'spawner.podman.bin': '/usr/bin/podman',
2021-08-09 13:42:27,398 job              L0263 I    |  'spawner.podman.image': 'fedora:31',
2021-08-09 13:42:27,398 job              L0263 I    |  'sysinfo.collect.commands_timeout': -1,
2021-08-09 13:42:27,398 job              L0263 I    |  'sysinfo.collect.enabled': True,
2021-08-09 13:42:27,398 job              L0263 I    |  'sysinfo.collect.installed_packages': False,
2021-08-09 13:42:27,398 job              L0263 I    |  'sysinfo.collect.locale': 'C',
2021-08-09 13:42:27,398 job              L0263 I    |  'sysinfo.collect.optimize': False,
2021-08-09 13:42:27,398 job              L0263 I    |  'sysinfo.collect.per_test': False,
2021-08-09 13:42:27,399 job              L0263 I    |  'sysinfo.collect.profiler': False,
2021-08-09 13:42:27,399 job              L0263 I    |  'sysinfo.collectibles.commands': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/commands',
2021-08-09 13:42:27,399 job              L0263 I    |  'sysinfo.collectibles.fail_commands': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/fail_commands',
2021-08-09 13:42:27,399 job              L0263 I    |  'sysinfo.collectibles.fail_files': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/fail_files',
2021-08-09 13:42:27,399 job              L0263 I    |  'sysinfo.collectibles.files': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/files',
2021-08-09 13:42:27,399 job              L0263 I    |  'sysinfo.collectibles.profilers': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/profilers',
2021-08-09 13:42:27,399 job              L0263 I    |  'task.timeout.running': None}
2021-08-09 13:42:27,399 job              L0264 I    | 
2021-08-09 13:42:27,399 job              L0267 I    | Avocado Data Directories:
2021-08-09 13:42:27,399 job              L0268 I    | 
2021-08-09 13:42:27,399 job              L0269 I    | base     /Users/marioalvarado/avocado
2021-08-09 13:42:27,399 job              L0270 I    | tests    /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus
2021-08-09 13:42:27,399 job              L0271 I    | data     /Users/marioalvarado/avocado/data
2021-08-09 13:42:27,399 job              L0272 I    | logs     /tmp/avocado_logs/job-2021-08-09T13.42-00075e7
2021-08-09 13:42:27,399 job              L0273 I    | 
2021-08-09 13:42:27,400 job              L0310 I    | Temporary dir: /var/folders/pn/xdk10w_12dx8_bvjjrfh35hh0000gn/T/avocado_2h851z59/avocado_job_d8m12k_1
2021-08-09 13:42:27,400 job              L0311 I    | 
2021-08-09 13:42:27,400 job              L0304 I    | Job ID: 00075e72c47164a457db7be1cb1500331397a5f3
2021-08-09 13:42:27,400 job              L0307 I    | 
2021-08-09 13:42:27,736 selector_events  L0059 D    | Using selector: KqueueSelector
2021-08-09 13:42:29,212 job              L0640 I    | Test results available in /tmp/avocado_logs/job-2021-08-09T13.42-00075e7

Executing multipel suits:

(base) marioalvarado@MacBook-Pro iLAB_LOGS % cat /tmp/avocado_logs/job-2021-08-09T13.42-c6419d9/job.log
2021-08-09 13:42:05,571 job              L0287 I    | Command line: /usr/local/lib/python3.9/site-packages/bluegen/common/main.py test -s /Users/marioalvarado/git/iLabConfig/test/avocado/sequences/CheckHostStatus.yaml
2021-08-09 13:42:05,571 job              L0288 I    | 
2021-08-09 13:42:05,571 job              L0281 I    | Avocado version: 90.0
2021-08-09 13:42:05,571 job              L0282 I    | 
2021-08-09 13:42:05,571 job              L0260 I    | Avocado config:
2021-08-09 13:42:05,571 job              L0261 I    | 
2021-08-09 13:42:05,572 job              L0263 I    | {'core.input_encoding': 'utf-8',
2021-08-09 13:42:05,572 job              L0263 I    |  'core.show': ['app'],
2021-08-09 13:42:05,572 job              L0263 I    |  'datadir.paths.base_dir': '/Users/marioalvarado/avocado',
2021-08-09 13:42:05,573 job              L0263 I    |  'datadir.paths.cache_dirs': ['/Users/marioalvarado/avocado/data/cache'],
2021-08-09 13:42:05,573 job              L0263 I    |  'datadir.paths.data_dir': '/Users/marioalvarado/avocado/data',
2021-08-09 13:42:05,573 job              L0263 I    |  'datadir.paths.logs_dir': '/tmp/avocado_logs',
2021-08-09 13:42:05,573 job              L0263 I    |  'datadir.paths.test_dir': '/Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus',
2021-08-09 13:42:05,573 job              L0263 I    |  'job.output.loglevel': 'DEBUG',
2021-08-09 13:42:05,573 job              L0263 I    |  'job.output.testlogs.logfiles': ['debug.log'],
2021-08-09 13:42:05,573 job              L0263 I    |  'job.output.testlogs.statuses': [],
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.html.enabled': True,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.html.open_browser': False,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.html.output': None,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.json.enabled': True,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.json.output': None,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.tap.enabled': True,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.tap.include_logs': False,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.tap.output': None,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.xunit.enabled': True,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.xunit.job_name': None,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.xunit.max_test_log_chars': 100000,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.result.xunit.output': None,
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.store_logging_stream': [],
2021-08-09 13:42:05,573 job              L0263 I    |  'job.run.timeout': 0,
2021-08-09 13:42:05,573 job              L0263 I    |  'json.variants.load': None,
2021-08-09 13:42:05,574 job              L0263 I    |  'nrunner.max_parallel_tasks': 12,
2021-08-09 13:42:05,574 job              L0263 I    |  'nrunner.shuffle': False,
2021-08-09 13:42:05,574 job              L0263 I    |  'nrunner.spawner': 'process',
2021-08-09 13:42:05,574 job              L0263 I    |  'nrunner.status_server_buffer_size': 33554432,
2021-08-09 13:42:05,574 job              L0263 I    |  'nrunner.status_server_listen': '127.0.0.1:8888',
2021-08-09 13:42:05,574 job              L0263 I    |  'nrunner.status_server_uri': '127.0.0.1:8888',
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.cli.cmd.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.cli.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.disable': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.init.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.job.prepost.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.jobscripts.post': '/etc/avocado/scripts/job/post.d/',
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.jobscripts.pre': '/etc/avocado/scripts/job/pre.d/',
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.jobscripts.warn_non_existing_dir': False,
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.jobscripts.warn_non_zero_status': True,
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.resolver.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.result.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.result_events.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.runnable.runner.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.runner.order': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.skip_broken_plugin_notification': [],
2021-08-09 13:42:05,574 job              L0263 I    |  'plugins.spawner.order': [],
2021-08-09 13:42:05,575 job              L0263 I    |  'plugins.varianter.order': [],
2021-08-09 13:42:05,575 job              L0263 I    |  'run.dict_variants': [],
2021-08-09 13:42:05,575 job              L0263 I    |  'run.execution_order': 'variants-per-test',
2021-08-09 13:42:05,575 job              L0263 I    |  'run.references': [],
2021-08-09 13:42:05,575 job              L0263 I    |  'run.test_runner': 'nrunner',
2021-08-09 13:42:05,575 job              L0263 I    |  'runner.exectest.exitcodes.skip': [],
2021-08-09 13:42:05,575 job              L0263 I    |  'runner.output.color': 'auto',
2021-08-09 13:42:05,575 job              L0263 I    |  'runner.output.colored': True,
2021-08-09 13:42:05,575 job              L0263 I    |  'runner.output.utf8': True,
2021-08-09 13:42:05,575 job              L0263 I    |  'runner.timeout.after_interrupted': 60,
2021-08-09 13:42:05,575 job              L0263 I    |  'runner.timeout.process_alive': 60,
2021-08-09 13:42:05,575 job              L0263 I    |  'runner.timeout.process_died': 10,
2021-08-09 13:42:05,575 job              L0263 I    |  'simpletests.status.failure_fields': ['status', 'stdout', 'stderr'],
2021-08-09 13:42:05,575 job              L0263 I    |  'simpletests.status.skip_location': 'all',
2021-08-09 13:42:05,575 job              L0263 I    |  'simpletests.status.skip_regex': '^SKIP$',
2021-08-09 13:42:05,575 job              L0263 I    |  'simpletests.status.warn_location': 'all',
2021-08-09 13:42:05,575 job              L0263 I    |  'simpletests.status.warn_regex': '^WARN$',
2021-08-09 13:42:05,575 job              L0263 I    |  'spawner.podman.bin': '/usr/bin/podman',
2021-08-09 13:42:05,575 job              L0263 I    |  'spawner.podman.image': 'fedora:31',
2021-08-09 13:42:05,575 job              L0263 I    |  'sysinfo.collect.commands_timeout': -1,
2021-08-09 13:42:05,575 job              L0263 I    |  'sysinfo.collect.enabled': True,
2021-08-09 13:42:05,575 job              L0263 I    |  'sysinfo.collect.installed_packages': False,
2021-08-09 13:42:05,575 job              L0263 I    |  'sysinfo.collect.locale': 'C',
2021-08-09 13:42:05,575 job              L0263 I    |  'sysinfo.collect.optimize': False,
2021-08-09 13:42:05,576 job              L0263 I    |  'sysinfo.collect.per_test': False,
2021-08-09 13:42:05,576 job              L0263 I    |  'sysinfo.collect.profiler': False,
2021-08-09 13:42:05,576 job              L0263 I    |  'sysinfo.collectibles.commands': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/commands',
2021-08-09 13:42:05,576 job              L0263 I    |  'sysinfo.collectibles.fail_commands': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/fail_commands',
2021-08-09 13:42:05,576 job              L0263 I    |  'sysinfo.collectibles.fail_files': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/fail_files',
2021-08-09 13:42:05,576 job              L0263 I    |  'sysinfo.collectibles.files': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/files',
2021-08-09 13:42:05,576 job              L0263 I    |  'sysinfo.collectibles.profilers': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/profilers',
2021-08-09 13:42:05,576 job              L0263 I    |  'task.timeout.running': None}
2021-08-09 13:42:05,576 job              L0264 I    | 
2021-08-09 13:42:05,576 job              L0267 I    | Avocado Data Directories:
2021-08-09 13:42:05,576 job              L0268 I    | 
2021-08-09 13:42:05,576 job              L0269 I    | base     /Users/marioalvarado/avocado
2021-08-09 13:42:05,576 job              L0270 I    | tests    /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus
2021-08-09 13:42:05,576 job              L0271 I    | data     /Users/marioalvarado/avocado/data
2021-08-09 13:42:05,576 job              L0272 I    | logs     /tmp/avocado_logs/job-2021-08-09T13.42-c6419d9
2021-08-09 13:42:05,577 job              L0273 I    | 
2021-08-09 13:42:05,578 job              L0310 I    | Temporary dir: /var/folders/pn/xdk10w_12dx8_bvjjrfh35hh0000gn/T/avocado_2yrbia0r/avocado_job_zts3szo0
2021-08-09 13:42:05,578 job              L0311 I    | 
2021-08-09 13:42:05,579 job              L0304 I    | Job ID: c6419d9a073d3ac27b6f623ea6927b4fa90cf517
2021-08-09 13:42:05,579 job              L0307 I    | 
2021-08-09 13:42:05,916 selector_events  L0059 D    | Using selector: KqueueSelector
2021-08-09 13:42:18,385 job              L0640 I    | Test results available in /tmp/avocado_logs/job-2021-08-09T13.42-c6419d9

Executing using avocado command line:

(base) marioalvarado@MacBook-Pro iLAB_LOGS % cat /tmp/avocado_logs/job-2021-08-09T13.42-0ce7f8d/job.log
2021-08-09 13:42:34,223 job              L0287 INFO | Command line: /Library/Frameworks/Python.framework/Versions/3.9/bin/avocado run --test-runner=nrunner /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info
2021-08-09 13:42:34,224 job              L0288 INFO | 
2021-08-09 13:42:34,224 job              L0281 INFO | Avocado version: 90.0
2021-08-09 13:42:34,224 job              L0282 INFO | 
2021-08-09 13:42:34,224 job              L0260 INFO | Avocado config:
2021-08-09 13:42:34,224 job              L0261 INFO | 
2021-08-09 13:42:34,226 job              L0263 INFO | {'assets.fetch.ignore_errors': False,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.fetch.references': [],
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.list.days': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.list.overall_limit': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.list.size_filter': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.purge.days': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.purge.overall_limit': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.purge.size_filter': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.register.name': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.register.sha1_hash': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'assets.register.url': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'config': None,
2021-08-09 13:42:34,226 job              L0263 INFO |  'config.datadir': False,
2021-08-09 13:42:34,226 job              L0263 INFO |  'core.input_encoding': 'utf-8',
2021-08-09 13:42:34,226 job              L0263 INFO |  'core.paginator': False,
2021-08-09 13:42:34,226 job              L0263 INFO |  'core.show': {'app'},
2021-08-09 13:42:34,226 job              L0263 INFO |  'core.verbose': False,
2021-08-09 13:42:34,226 job              L0263 INFO |  'datadir.paths.base_dir': '/Users/marioalvarado/avocado',
2021-08-09 13:42:34,226 job              L0263 INFO |  'datadir.paths.cache_dirs': ['/Users/marioalvarado/avocado/data/cache'],
2021-08-09 13:42:34,227 job              L0263 INFO |  'datadir.paths.data_dir': '/Users/marioalvarado/avocado/data',
2021-08-09 13:42:34,227 job              L0263 INFO |  'datadir.paths.logs_dir': '/tmp/avocado_logs',
2021-08-09 13:42:34,227 job              L0263 INFO |  'datadir.paths.test_dir': '/Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus',
2021-08-09 13:42:34,227 job              L0263 INFO |  'diff.create_reports': False,
2021-08-09 13:42:34,227 job              L0263 INFO |  'diff.filter': ['cmdline', 'time', 'variants', 'results', 'config', 'sysinfo'],
2021-08-09 13:42:34,227 job              L0263 INFO |  'diff.html': None,
2021-08-09 13:42:34,227 job              L0263 INFO |  'diff.jobids': [],
2021-08-09 13:42:34,227 job              L0263 INFO |  'diff.open_browser': False,
2021-08-09 13:42:34,227 job              L0263 INFO |  'diff.strip_id': False,
2021-08-09 13:42:34,227 job              L0263 INFO |  'distro.distro_def_arch': '',
2021-08-09 13:42:34,227 job              L0263 INFO |  'distro.distro_def_create': False,
2021-08-09 13:42:34,227 job              L0263 INFO |  'distro.distro_def_name': '',
2021-08-09 13:42:34,227 job              L0263 INFO |  'distro.distro_def_path': '',
2021-08-09 13:42:34,227 job              L0263 INFO |  'distro.distro_def_release': '',
2021-08-09 13:42:34,227 job              L0263 INFO |  'distro.distro_def_type': '',
2021-08-09 13:42:34,227 job              L0263 INFO |  'distro.distro_def_version': '',
2021-08-09 13:42:34,227 job              L0263 INFO |  'filter.by_tags.include_empty': False,
2021-08-09 13:42:34,227 job              L0263 INFO |  'filter.by_tags.include_empty_key': False,
2021-08-09 13:42:34,227 job              L0263 INFO |  'filter.by_tags.tags': [],
2021-08-09 13:42:34,227 job              L0263 INFO |  'job.output.loglevel': 'DEBUG',
2021-08-09 13:42:34,227 job              L0263 INFO |  'job.output.testlogs.logfiles': ['debug.log'],
2021-08-09 13:42:34,227 job              L0263 INFO |  'job.output.testlogs.statuses': [],
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.replay.source_job_id': 'latest',
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.html.enabled': True,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.html.open_browser': False,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.html.output': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.json.enabled': True,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.json.output': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.tap.enabled': True,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.tap.include_logs': False,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.tap.output': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.xunit.enabled': True,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.xunit.job_name': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.xunit.max_test_log_chars': 100000,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.result.xunit.output': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.store_logging_stream': [],
2021-08-09 13:42:34,228 job              L0263 INFO |  'job.run.timeout': 0,
2021-08-09 13:42:34,228 job              L0263 INFO |  'jobs.get.output_files.destination': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'jobs.get.output_files.job_id': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'jobs.show.job_id': 'latest',
2021-08-09 13:42:34,228 job              L0263 INFO |  'json.variants.load': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'list.external_runner': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'list.external_runner_chdir': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'list.external_runner_testdir': None,
2021-08-09 13:42:34,228 job              L0263 INFO |  'list.loaders': ['file', '@DEFAULT'],
2021-08-09 13:42:34,228 job              L0263 INFO |  'list.recipes.write_to_directory': None,
2021-08-09 13:42:34,229 job              L0263 INFO |  'list.references': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'list.resolver': False,
2021-08-09 13:42:34,229 job              L0263 INFO |  'list.write_to_json_file': None,
2021-08-09 13:42:34,229 job              L0263 INFO |  'nrunner.max_parallel_tasks': 12,
2021-08-09 13:42:34,229 job              L0263 INFO |  'nrunner.shuffle': False,
2021-08-09 13:42:34,229 job              L0263 INFO |  'nrunner.spawner': 'process',
2021-08-09 13:42:34,229 job              L0263 INFO |  'nrunner.status_server_buffer_size': 33554432,
2021-08-09 13:42:34,229 job              L0263 INFO |  'nrunner.status_server_listen': '127.0.0.1:8888',
2021-08-09 13:42:34,229 job              L0263 INFO |  'nrunner.status_server_uri': '127.0.0.1:8888',
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.cli.cmd.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.cli.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.disable': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.init.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.job.prepost.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.jobscripts.post': '/etc/avocado/scripts/job/post.d/',
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.jobscripts.pre': '/etc/avocado/scripts/job/pre.d/',
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.jobscripts.warn_non_existing_dir': False,
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.jobscripts.warn_non_zero_status': True,
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.resolver.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.result.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.result_events.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.runnable.runner.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.runner.order': [],
2021-08-09 13:42:34,229 job              L0263 INFO |  'plugins.skip_broken_plugin_notification': [],
2021-08-09 13:42:34,230 job              L0263 INFO |  'plugins.spawner.order': [],
2021-08-09 13:42:34,230 job              L0263 INFO |  'plugins.varianter.order': [],
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.dict_variants': [],
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.dry_run.enabled': False,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.dry_run.no_cleanup': False,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.execution_order': 'variants-per-test',
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.external_runner': None,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.external_runner_chdir': None,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.external_runner_testdir': None,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.failfast': False,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.ignore_missing_references': False,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.job_category': None,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.journal.enabled': False,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.keep_tmp': False,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.loaders': ['file', '@DEFAULT'],
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.log_test_data_directories': False,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.output_check': True,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.output_check_record': None,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.references': ['/Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info'],
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.replay.ignore': [],
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.replay.job_id': None,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.replay.resume': False,
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.replay.test_status': [],
2021-08-09 13:42:34,230 job              L0263 INFO |  'run.results.archive': False,
2021-08-09 13:42:34,231 job              L0263 INFO |  'run.results_dir': None,
2021-08-09 13:42:34,231 job              L0263 INFO |  'run.test_parameters': [],
2021-08-09 13:42:34,231 job              L0263 INFO |  'run.test_runner': 'nrunner',
2021-08-09 13:42:34,231 job              L0263 INFO |  'run.unique_job_id': None,
2021-08-09 13:42:34,231 job              L0263 INFO |  'run.wrapper.wrappers': [],
2021-08-09 13:42:34,231 job              L0263 INFO |  'runner.exectest.exitcodes.skip': [],
2021-08-09 13:42:34,231 job              L0263 INFO |  'runner.output.color': 'auto',
2021-08-09 13:42:34,231 job              L0263 INFO |  'runner.output.colored': True,
2021-08-09 13:42:34,231 job              L0263 INFO |  'runner.output.utf8': True,
2021-08-09 13:42:34,231 job              L0263 INFO |  'runner.timeout.after_interrupted': 60,
2021-08-09 13:42:34,231 job              L0263 INFO |  'runner.timeout.process_alive': 60,
2021-08-09 13:42:34,231 job              L0263 INFO |  'runner.timeout.process_died': 10,
2021-08-09 13:42:34,231 job              L0263 INFO |  'simpletests.status.failure_fields': ['status', 'stdout', 'stderr'],
2021-08-09 13:42:34,231 job              L0263 INFO |  'simpletests.status.skip_location': 'all',
2021-08-09 13:42:34,231 job              L0263 INFO |  'simpletests.status.skip_regex': '^SKIP$',
2021-08-09 13:42:34,231 job              L0263 INFO |  'simpletests.status.warn_location': 'all',
2021-08-09 13:42:34,231 job              L0263 INFO |  'simpletests.status.warn_regex': '^WARN$',
2021-08-09 13:42:34,231 job              L0263 INFO |  'spawner.podman.bin': '/usr/bin/podman',
2021-08-09 13:42:34,231 job              L0263 INFO |  'spawner.podman.image': 'fedora:31',
2021-08-09 13:42:34,231 job              L0263 INFO |  'subcommand': 'run',
2021-08-09 13:42:34,231 job              L0263 INFO |  'sysinfo.collect.commands_timeout': -1,
2021-08-09 13:42:34,231 job              L0263 INFO |  'sysinfo.collect.enabled': True,
2021-08-09 13:42:34,231 job              L0263 INFO |  'sysinfo.collect.installed_packages': False,
2021-08-09 13:42:34,231 job              L0263 INFO |  'sysinfo.collect.locale': 'C',
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collect.optimize': False,
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collect.per_test': False,
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collect.profiler': False,
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collect.sysinfodir': None,
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collectibles.commands': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/commands',
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collectibles.fail_commands': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/fail_commands',
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collectibles.fail_files': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/fail_files',
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collectibles.files': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/files',
2021-08-09 13:42:34,232 job              L0263 INFO |  'sysinfo.collectibles.profilers': '/Library/Frameworks/Python.framework/Versions/3.9/lib/python3.9/site-packages/avocado/etc/avocado/sysinfo/profilers',
2021-08-09 13:42:34,232 job              L0263 INFO |  'task.timeout.running': None,
2021-08-09 13:42:34,232 job              L0263 INFO |  'variants.contents': False,
2021-08-09 13:42:34,232 job              L0263 INFO |  'variants.debug': False,
2021-08-09 13:42:34,232 job              L0263 INFO |  'variants.inherit': False,
2021-08-09 13:42:34,232 job              L0263 INFO |  'variants.json_variants_dump': None,
2021-08-09 13:42:34,232 job              L0263 INFO |  'variants.summary': 0,
2021-08-09 13:42:34,232 job              L0263 INFO |  'variants.tree': False,
2021-08-09 13:42:34,232 job              L0263 INFO |  'variants.variants': 1,
2021-08-09 13:42:34,232 job              L0263 INFO |  'vmimage.get.arch': None,
2021-08-09 13:42:34,232 job              L0263 INFO |  'vmimage.get.distro': None,
2021-08-09 13:42:34,232 job              L0263 INFO |  'vmimage.get.version': None}
2021-08-09 13:42:34,232 job              L0264 INFO | 
2021-08-09 13:42:34,232 job              L0267 INFO | Avocado Data Directories:
2021-08-09 13:42:34,232 job              L0268 INFO | 
2021-08-09 13:42:34,232 job              L0269 INFO | base     /Users/marioalvarado/avocado
2021-08-09 13:42:34,233 job              L0270 INFO | tests    /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus
2021-08-09 13:42:34,233 job              L0271 INFO | data     /Users/marioalvarado/avocado/data
2021-08-09 13:42:34,233 job              L0272 INFO | logs     /tmp/avocado_logs/job-2021-08-09T13.42-0ce7f8d
2021-08-09 13:42:34,233 job              L0273 INFO | 
2021-08-09 13:42:34,234 job              L0310 INFO | Temporary dir: /var/folders/pn/xdk10w_12dx8_bvjjrfh35hh0000gn/T/avocado_zsu1jq7c/avocado_job_rrr6h1a7
2021-08-09 13:42:34,234 job              L0311 INFO | 
2021-08-09 13:42:34,234 job              L0304 INFO | Job ID: 0ce7f8de8811805e62a5da3e1be2c4cf25bad639
2021-08-09 13:42:34,234 job              L0307 INFO | 
2021-08-09 13:42:34,574 selector_events  L0059 DEBUG| Using selector: KqueueSelector
2021-08-09 13:42:34,950 testlogs         L0094 INFO | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 13:42:36,007 testlogs         L0101 INFO | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: ERROR
2021-08-09 13:42:36,007 testlogs         L0103 INFO | More information in /tmp/avocado_logs/job-2021-08-09T13.42-0ce7f8d/test-results/1-_Users_marioalvarado_git_iLabConfig_test_avocado_testcases_CheckHostStatus_CheckHostStatus.py_CheckHostStatus.test_os_info
2021-08-09 13:42:36,083 job              L0640 INFO | Test results available in /tmp/avocado_logs/job-2021-08-09T13.42-0ce7f8d

Test Code:

# Standard library
from time import sleep

# Avocado library
from avocado import Test

# BlueGen library
from bluegen.utils import utils
from bluegen.modules.ssh_module import SshModule
from bluegen.common.error import BLUEGENError

#==============================================================================
# Functions
#==============================================================================

class CheckHostStatus(Test):
    """ """

    def test_os_info(self):
        """Retrieve OS information via ssh connection"""
        # Retrieving parameters
        host_ip = self.params.get('HOST_IP')
        host_name = self.params.get('HOST_NAME')

        # Retrieving OS info from the Host
        ssh_connection = SshModule(host_ip, 'root', 'B!gm0ney')
        retval, stdout, stderr = ssh_connection.execute_quick('uname -a')
        self.log.info(stdout)

5 replies

beraldoleal Aug 9, 2021
Maintainer

Thanks @marioaag , I could not find bluegen library, but have you checked this dir?

2021-08-09 13:42:36,007 testlogs         L0103 INFO | More information in /tmp/avocado_logs/job-2021-08-09T13.42-0ce7f8d/test-results/1-_Users_marioalvarado_git_iLabConfig_test_avocado_testcases_CheckHostStatus_CheckHostStatus.py_CheckHostStatus.test_os_info

Usually test-results folder will give you some hints about the problem.

marioaag Aug 9, 2021
Author

yes for that one the folder exist due that one was executed and fail as expected.
The problem is with the other 2 executions where the tests are skipped, the test-results dir doesn't exist so I don't have any clue why them are getting skipped.

beraldoleal Aug 9, 2021
Maintainer

can you paste your job api script? The one that is calling your tests.

marioaag Aug 9, 2021
Author

if you wan to give it a try just run something like this and you will se that it skip the test:

# Standard library
from time import sleep

# Avocado library
from avocado import Test


#==============================================================================
# Functions
#==============================================================================

class CheckHostStatus(Test):
    """ """

    def test_os_info(self):
        """Retrieve OS information via ssh connection"""
        # Retrieving parameters
        host_ip = self.params.get('HOST_IP')
        host_name = self.params.get('HOST_NAME')

        self.log.info('TEST')

I'm using the Avocado API that use to work, if you need more details let me know and I can try to create simplified piece of code to call job.run

beraldoleal Aug 9, 2021
Maintainer

By using the avocado command line, this is working:

avocado run --test-runner='nrunner' /tmp/test.py
JOB ID     : 62da3ce1ed99588c6d81fdb08a2123e481b16e66
JOB LOG    : /home/local/avocado/job-results/job-2021-08-09T17.16-62da3ce/job.log
 (1/1) /tmp/test.py:CheckHostStatus.test_os_info: STARTED
 (1/1) /tmp/test.py:CheckHostStatus.test_os_info: PASS (0.01 s)
RESULTS    : PASS 1 | ERROR 0 | FAIL 0 | SKIP 0 | WARN 0 | INTERRUPT 0 | CANCEL 0
JOB HTML   : /home/local/avocado/job-results/job-2021-08-09T17.16-62da3ce/results.html
JOB TIME   : 1.07 s

So, how are you calling this? I mean, the actual JobAPI Script used to execute this test?

marioaag · 2021-08-09T19:58:25Z

marioaag
Aug 9, 2021
Author

Doing some debug found that removing the commit 3a2fef0 - Merge remote-tracking branch richtja/task_status_uri

And running in the commit fa4aabe - Merge remote-tracking branch 'clebergnu/setup_develop_exclude_optional_plugins' the behavior start changing and now when a test suit with multiple test is executed randomly some of them get executed and some other skipped

(base) marioalvarado@MacBook-Pro iLAB_LOGS % cat /tmp/avocado_logs/job-2021-08-09T14.56-8e4df25/job.log
2021-08-09 14:56:32,404 sysinfo          L0440 I    | Commands configured by file: /Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/commands
2021-08-09 14:56:32,404 sysinfo          L0440 I    | Files configured by file: /Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/files
2021-08-09 14:56:32,404 sysinfo          L0445 D    | File /Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/fail_commands does not exist.
2021-08-09 14:56:32,404 sysinfo          L0445 D    | File /Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/fail_files does not exist.
2021-08-09 14:56:32,404 sysinfo          L0464 I    | Profilers configured by file: /Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/profilers
2021-08-09 14:56:32,404 sysinfo          L0473 I    | Profiler disabled
2021-08-09 14:56:32,408 sysinfo          L0282 D    | Journalctl collection failed: [Errno 2] No such file or directory (journalctl --quiet --lines 1 --output json): 'journalctl'
2021-08-09 14:56:32,408 sysinfo          L0113 D    | Not logging /sys/kernel/debug/sched_features (file does not exist)
2021-08-09 14:56:32,444 sysinfo          L0113 D    | Not logging /proc/slabinfo (file does not exist)
2021-08-09 14:56:32,454 sysinfo          L0113 D    | Not logging /proc/cpuinfo (file does not exist)
2021-08-09 14:56:32,462 sysinfo          L0113 D    | Not logging /proc/mounts (file does not exist)
2021-08-09 14:56:32,462 sysinfo          L0113 D    | Not logging /proc/version (file does not exist)
2021-08-09 14:56:32,517 sysinfo          L0113 D    | Not logging /sys/devices/system/clocksource/clocksource0/current_clocksource (file does not exist)
2021-08-09 14:56:32,517 sysinfo          L0113 D    | Not logging /proc/meminfo (file does not exist)
2021-08-09 14:56:32,536 sysinfo          L0113 D    | Not logging /proc/pci (file does not exist)
2021-08-09 14:56:32,573 sysinfo          L0113 D    | Not logging /proc/partitions (file does not exist)
2021-08-09 14:56:32,573 sysinfo          L0113 D    | Not logging /sys/devices/system/cpu/cpu0/cpufreq/scaling_governor (file does not exist)
2021-08-09 14:56:32,573 sysinfo          L0113 D    | Not logging /proc/modules (file does not exist)
2021-08-09 14:56:32,573 sysinfo          L0113 D    | Not logging /proc/interrupts (file does not exist)
2021-08-09 14:56:32,581 sysinfo          L0113 D    | Not logging /proc/cmdline (file does not exist)
2021-08-09 14:56:32,603 job              L0284 I    | Command line: /usr/local/lib/python3.9/site-packages/bluegen/common/main.py test -s /Users/marioalvarado/git/iLabConfig/test/avocado/sequences/CheckHostStatus.yaml
2021-08-09 14:56:32,603 job              L0285 I    | 
2021-08-09 14:56:32,603 job              L0278 I    | Avocado version: 89.0
2021-08-09 14:56:32,604 job              L0279 I    | 
2021-08-09 14:56:32,604 job              L0257 I    | Avocado config:
2021-08-09 14:56:32,604 job              L0258 I    | 
2021-08-09 14:56:32,605 job              L0260 I    | {'core.input_encoding': 'utf-8',
2021-08-09 14:56:32,605 job              L0260 I    |  'core.show': ['app'],
2021-08-09 14:56:32,605 job              L0260 I    |  'datadir.paths.base_dir': '/var/lib/avocado',
2021-08-09 14:56:32,605 job              L0260 I    |  'datadir.paths.cache_dirs': ['/Users/marioalvarado/avocado/data/cache'],
2021-08-09 14:56:32,605 job              L0260 I    |  'datadir.paths.data_dir': '/Users/marioalvarado/avocado/data',
2021-08-09 14:56:32,605 job              L0260 I    |  'datadir.paths.logs_dir': '/tmp/avocado_logs',
2021-08-09 14:56:32,605 job              L0260 I    |  'datadir.paths.test_dir': '/Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus',
2021-08-09 14:56:32,605 job              L0260 I    |  'job.output.loglevel': 'DEBUG',
2021-08-09 14:56:32,605 job              L0260 I    |  'job.output.testlogs.logfiles': ['debug.log'],
2021-08-09 14:56:32,605 job              L0260 I    |  'job.output.testlogs.statuses': [],
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.json.enabled': True,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.json.output': None,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.tap.enabled': True,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.tap.include_logs': False,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.tap.output': None,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.xunit.enabled': True,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.xunit.job_name': None,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.xunit.max_test_log_chars': 100000,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.result.xunit.output': None,
2021-08-09 14:56:32,605 job              L0260 I    |  'job.run.store_logging_stream': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'job.run.timeout': 0,
2021-08-09 14:56:32,606 job              L0260 I    |  'json.variants.load': None,
2021-08-09 14:56:32,606 job              L0260 I    |  'nrunner.max_parallel_tasks': 12,
2021-08-09 14:56:32,606 job              L0260 I    |  'nrunner.shuffle': False,
2021-08-09 14:56:32,606 job              L0260 I    |  'nrunner.spawner': 'process',
2021-08-09 14:56:32,606 job              L0260 I    |  'nrunner.status_server_buffer_size': 33554432,
2021-08-09 14:56:32,606 job              L0260 I    |  'nrunner.status_server_listen': '127.0.0.1:8888',
2021-08-09 14:56:32,606 job              L0260 I    |  'nrunner.status_server_uri': '127.0.0.1:8888',
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.cli.cmd.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.cli.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.disable': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.init.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.job.prepost.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.jobscripts.post': '/etc/avocado/scripts/job/post.d/',
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.jobscripts.pre': '/etc/avocado/scripts/job/pre.d/',
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.jobscripts.warn_non_existing_dir': False,
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.jobscripts.warn_non_zero_status': True,
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.resolver.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.result.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.result_events.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.runnable.runner.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.runner.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.skip_broken_plugin_notification': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.spawner.order': [],
2021-08-09 14:56:32,606 job              L0260 I    |  'plugins.varianter.order': [],
2021-08-09 14:56:32,607 job              L0260 I    |  'run.dict_variants': [],
2021-08-09 14:56:32,607 job              L0260 I    |  'run.execution_order': 'variants-per-test',
2021-08-09 14:56:32,607 job              L0260 I    |  'run.references': [],
2021-08-09 14:56:32,607 job              L0260 I    |  'run.test_runner': 'nrunner',
2021-08-09 14:56:32,607 job              L0260 I    |  'runner.exectest.exitcodes.skip': [],
2021-08-09 14:56:32,607 job              L0260 I    |  'runner.output.color': 'auto',
2021-08-09 14:56:32,607 job              L0260 I    |  'runner.output.colored': True,
2021-08-09 14:56:32,607 job              L0260 I    |  'runner.output.utf8': True,
2021-08-09 14:56:32,607 job              L0260 I    |  'runner.timeout.after_interrupted': 60,
2021-08-09 14:56:32,607 job              L0260 I    |  'runner.timeout.process_alive': 60,
2021-08-09 14:56:32,607 job              L0260 I    |  'runner.timeout.process_died': 10,
2021-08-09 14:56:32,607 job              L0260 I    |  'simpletests.status.failure_fields': ['status', 'stdout', 'stderr'],
2021-08-09 14:56:32,607 job              L0260 I    |  'simpletests.status.skip_location': 'all',
2021-08-09 14:56:32,607 job              L0260 I    |  'simpletests.status.skip_regex': '^SKIP$',
2021-08-09 14:56:32,607 job              L0260 I    |  'simpletests.status.warn_location': 'all',
2021-08-09 14:56:32,607 job              L0260 I    |  'simpletests.status.warn_regex': '^WARN$',
2021-08-09 14:56:32,607 job              L0260 I    |  'spawner.podman.bin': '/usr/bin/podman',
2021-08-09 14:56:32,607 job              L0260 I    |  'spawner.podman.image': 'fedora:31',
2021-08-09 14:56:32,607 job              L0260 I    |  'sysinfo.collect.commands_timeout': -1,
2021-08-09 14:56:32,607 job              L0260 I    |  'sysinfo.collect.enabled': True,
2021-08-09 14:56:32,607 job              L0260 I    |  'sysinfo.collect.installed_packages': False,
2021-08-09 14:56:32,607 job              L0260 I    |  'sysinfo.collect.locale': 'C',
2021-08-09 14:56:32,607 job              L0260 I    |  'sysinfo.collect.optimize': False,
2021-08-09 14:56:32,607 job              L0260 I    |  'sysinfo.collect.per_test': False,
2021-08-09 14:56:32,607 job              L0260 I    |  'sysinfo.collect.profiler': False,
2021-08-09 14:56:32,608 job              L0260 I    |  'sysinfo.collectibles.commands': '/Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/commands',
2021-08-09 14:56:32,608 job              L0260 I    |  'sysinfo.collectibles.fail_commands': '/Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/fail_commands',
2021-08-09 14:56:32,608 job              L0260 I    |  'sysinfo.collectibles.fail_files': '/Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/fail_files',
2021-08-09 14:56:32,608 job              L0260 I    |  'sysinfo.collectibles.files': '/Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/files',
2021-08-09 14:56:32,608 job              L0260 I    |  'sysinfo.collectibles.profilers': '/Users/marioalvarado/Library/Python/3.9/lib/python/site-packages/avocado_framework-89.0-py3.9.egg/avocado/etc/avocado/sysinfo/profilers',
2021-08-09 14:56:32,608 job              L0260 I    |  'task.timeout.running': None}
2021-08-09 14:56:32,608 job              L0261 I    | 
2021-08-09 14:56:32,608 job              L0264 I    | Avocado Data Directories:
2021-08-09 14:56:32,608 job              L0265 I    | 
2021-08-09 14:56:32,609 job              L0266 I    | base     /Users/marioalvarado/avocado
2021-08-09 14:56:32,609 job              L0267 I    | tests    /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus
2021-08-09 14:56:32,609 job              L0268 I    | data     /Users/marioalvarado/avocado/data
2021-08-09 14:56:32,609 job              L0269 I    | logs     /tmp/avocado_logs/job-2021-08-09T14.56-8e4df25
2021-08-09 14:56:32,609 job              L0270 I    | 
2021-08-09 14:56:32,611 job              L0307 I    | Temporary dir: /var/folders/pn/xdk10w_12dx8_bvjjrfh35hh0000gn/T/avocado_38l9imfy/avocado_job_jdwt_1oi
2021-08-09 14:56:32,611 job              L0308 I    | 
2021-08-09 14:56:32,611 job              L0301 I    | Job ID: 8e4df253ef61cb58d2ef96095f13a9f71dab6e44
2021-08-09 14:56:32,611 job              L0304 I    | 
2021-08-09 14:56:32,955 selector_events  L0059 D    | Using selector: KqueueSelector
2021-08-09 14:56:33,263 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 14:56:36,010 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 14:56:38,707 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_up_time: STARTED
2021-08-09 14:56:41,237 testlogs         L0101 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_up_time: PASS
2021-08-09 14:56:41,237 testlogs         L0103 I    | More information in /tmp/avocado_logs/job-2021-08-09T14.56-8e4df25/test-results/090821-195632396485.BlueGenSequential.-1-_Users_marioalvarado_git_iLabConfig_test_avocado_testcases_CheckHostStatus_CheckHostStatus.py_CheckHostStatus.test_up_time
2021-08-09 14:56:41,695 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 14:56:41,695 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 14:56:41,695 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 14:56:41,746 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 14:56:41,747 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 14:56:41,747 testlogs         L0094 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: STARTED
2021-08-09 14:56:44,290 testlogs         L0101 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: PASS
2021-08-09 14:56:44,290 testlogs         L0103 I    | More information in /tmp/avocado_logs/job-2021-08-09T14.56-8e4df25/test-results/090821-195632397371.BlueGenParallel.-3-_Users_marioalvarado_git_iLabConfig_test_avocado_testcases_CheckHostStatus_CheckHostStatus.py_CheckHostStatus.test_os_info
2021-08-09 14:56:44,290 testlogs         L0101 I    | /Users/marioalvarado/git/iLabConfig/test/avocado/testcases/CheckHostStatus/CheckHostStatus.py:CheckHostStatus.test_os_info: PASS
2021-08-09 14:56:44,290 testlogs         L0103 I    | More information in /tmp/avocado_logs/job-2021-08-09T14.56-8e4df25/test-results/090821-195632397371.BlueGenParallel.-1-_Users_marioalvarado_git_iLabConfig_test_avocado_testcases_CheckHostStatus_CheckHostStatus.py_CheckHostStatus.test_os_info
2021-08-09 14:56:44,707 job              L0637 I    | Test results available in /tmp/avocado_logs/job-2021-08-09T14.56-8e4df25
2021-08-09 14:56:44,708 sysinfo          L0113 D    | Not logging /sys/kernel/debug/sched_features (file does not exist)
2021-08-09 14:56:44,755 sysinfo          L0113 D    | Not logging /proc/slabinfo (file does not exist)
2021-08-09 14:56:44,766 sysinfo          L0113 D    | Not logging /proc/cpuinfo (file does not exist)
2021-08-09 14:56:44,775 sysinfo          L0113 D    | Not logging /proc/mounts (file does not exist)
2021-08-09 14:56:44,775 sysinfo          L0113 D    | Not logging /proc/version (file does not exist)
2021-08-09 14:56:44,835 sysinfo          L0113 D    | Not logging /sys/devices/system/clocksource/clocksource0/current_clocksource (file does not exist)
2021-08-09 14:56:44,835 sysinfo          L0113 D    | Not logging /proc/meminfo (file does not exist)
2021-08-09 14:56:44,854 sysinfo          L0113 D    | Not logging /proc/pci (file does not exist)
2021-08-09 14:56:44,891 sysinfo          L0113 D    | Not logging /proc/partitions (file does not exist)
2021-08-09 14:56:44,892 sysinfo          L0113 D    | Not logging /sys/devices/system/cpu/cpu0/cpufreq/scaling_governor (file does not exist)
2021-08-09 14:56:44,892 sysinfo          L0113 D    | Not logging /proc/modules (file does not exist)
2021-08-09 14:56:44,892 sysinfo          L0113 D    | Not logging /proc/interrupts (file does not exist)
2021-08-09 14:56:44,900 sysinfo          L0113 D    | Not logging /proc/cmdline (file does not exist)

3 replies

beraldoleal Aug 9, 2021
Maintainer

Probably this is due the fact that nrunner multiple jobs has one open issue (#4308)

clebergnu Aug 18, 2021
Maintainer

@marioaag, would you be able to run the same job/tests, but using avocado from #4864 ?

That version includes some extra checks and debugging information on the avocado.core.DEBUG file that is created on the job results directory. If you could please include that output here, it would be very useful.

Thanks!

Answer selected by marioaag

marioaag Aug 18, 2021
Author

Details added in #4308

Avocado API Skipping all the tests #4835

Uh oh!

marioaag Aug 9, 2021

Replies: 3 comments · 8 replies

Uh oh!

beraldoleal Aug 9, 2021 Maintainer

Uh oh!

marioaag Aug 9, 2021 Author

Uh oh!

beraldoleal Aug 9, 2021 Maintainer

Uh oh!

marioaag Aug 9, 2021 Author

Uh oh!

beraldoleal Aug 9, 2021 Maintainer

Uh oh!

marioaag Aug 9, 2021 Author

Uh oh!

beraldoleal Aug 9, 2021 Maintainer

Uh oh!

marioaag Aug 9, 2021 Author

Uh oh!

beraldoleal Aug 9, 2021 Maintainer

Uh oh!

clebergnu Aug 18, 2021 Maintainer

Uh oh!

marioaag Aug 18, 2021 Author

marioaag
Aug 9, 2021

Replies: 3 comments 8 replies

beraldoleal
Aug 9, 2021
Maintainer

marioaag
Aug 9, 2021
Author

beraldoleal Aug 9, 2021
Maintainer

marioaag Aug 9, 2021
Author

beraldoleal Aug 9, 2021
Maintainer

marioaag Aug 9, 2021
Author

beraldoleal Aug 9, 2021
Maintainer

marioaag
Aug 9, 2021
Author

beraldoleal Aug 9, 2021
Maintainer

clebergnu Aug 18, 2021
Maintainer

marioaag Aug 18, 2021
Author