Bug 12665

Summary: [2.5 M3 rc1] OpenSUSE42.3 runtime test fail at runtime_test.TextExport, runtime_test.TestImage, runtime_test.TestImage and oe_runmake.
Product: [QA/Testing] Runtime Testing Reporter: Mohamad Noor Alim <mohamad.noor.alim.hussin>
Component: generalAssignee: Paul Eggleton <bluelightning>
Status: RESOLVED FIXED QA Contact: Mohamad Noor Alim <mohamad.noor.alim.hussin>
Severity: normal    
Priority: High CC: aaron.chun.yew.chan, ee.peng.yeoh, richard.purdie, ross.burton
Version: 2.5   
Target Milestone: 2.5 M4   
Hardware: x86   
OS: Multiple   
URL: http://centaurus01.png.intel.com:8010/builders/nightly-oe-selftest-opensuse423/builds/24/steps/Running%20oe-selftest/logs/stdio
Whiteboard:
OS type for building Yocto: --- Type of Regression: ---
Verified: Documentation change: Don't know
Attachments:
Description Flags
contains log files for runtime_test
none
bitbake install tar manual
none
Texi file define atchar
none
bitbake install tar logfile none

Description Mohamad Noor Alim 2018-04-08 18:44:52 UTC
Created attachment 4260 [details]
contains log files for runtime_test

Summary
=======
oe-selftest fail at
1) oe_runmake. See log file log.do_install.17313.
2) test_testexport_sdk (runtime_test.TestExport). See log file log.do_install.1865.
3) test_testimage_install (runtime_test.TestImage). See log file do_install.10275.
4) test_testimage_dnf (runtime_test.TestImage). 

Details
=======
1) ERROR: nativesdk-tar-1.30-r0 do_install: oe_runmake failed. See log file log.do_install.17313.

2)
2018-04-06 00:29:11,568 - oe-selftest - INFO - FAIL [19.440s]: test_testexport_sdk (runtime_test.TestExport)
2018-04-06 00:29:11,569 - oe-selftest - INFO - ----------------------------------------------------------------------
2018-04-06 00:29:11,569 - oe-selftest - INFO - Traceback (most recent call last):
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/core/decorator/__init__.py", line 32, in wrapped_f
    return func(*args, **kwargs)
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/selftest/cases/runtime_test.py", line 81, in test_testexport_sdk
    bitbake('-c testexport core-image-minimal')
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/utils/commands.py", line 213, in bitbake
    return runCmd(cmd, ignore_status, timeout, output_log=output_log, **options)
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/utils/commands.py", line 191, in runCmd
    raise AssertionError("Command '%s' returned non-zero exit status %d:\n%s" % (command, result.status, exc_output))
AssertionError: Command 'bitbake  -c testexport core-image-minimal' returned non-zero exit status 1: See log file log.do_install.1865.

3)
2018-04-06 00:29:11,573 - oe-selftest - INFO - FAIL [22.960s]: test_testimage_install (runtime_test.TestImage)
2018-04-06 00:29:11,573 - oe-selftest - INFO - ----------------------------------------------------------------------
2018-04-06 00:29:11,573 - oe-selftest - INFO - Traceback (most recent call last):
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/core/decorator/__init__.py", line 32, in wrapped_f
    return func(*args, **kwargs)
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/selftest/cases/runtime_test.py", line 129, in test_testimage_install
    bitbake('core-image-full-cmdline socat')
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/utils/commands.py", line 213, in bitbake
    return runCmd(cmd, ignore_status, timeout, output_log=output_log, **options)
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/utils/commands.py", line 191, in runCmd
    raise AssertionError("Command '%s' returned non-zero exit status %d:\n%s" % (command, result.status, exc_output))
AssertionError: Command 'bitbake  core-image-full-cmdline socat' returned non-zero exit status 1: See log file do_install.10275.

4)
2018-04-06 00:29:11,569 - oe-selftest - INFO - FAIL [168.860s]: test_testimage_dnf (runtime_test.TestImage)
2018-04-06 00:29:11,569 - oe-selftest - INFO - ----------------------------------------------------------------------
2018-04-06 00:29:11,570 - oe-selftest - INFO - Traceback (most recent call last):
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/core/decorator/__init__.py", line 32, in wrapped_f
    return func(*args, **kwargs)
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/selftest/cases/runtime_test.py", line 162, in test_testimage_dnf
    bitbake('core-image-full-cmdline socat')
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/utils/commands.py", line 213, in bitbake
    return runCmd(cmd, ignore_status, timeout, output_log=output_log, **options)
  File "/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/meta/lib/oeqa/utils/commands.py", line 191, in runCmd
    raise AssertionError("Command '%s' returned non-zero exit status %d:\n%s" % (command, result.status, exc_output))
AssertionError: Command 'bitbake  core-image-full-cmdline socat' returned non-zero exit status 1:
Comment 1 Paul Eggleton 2018-04-09 22:43:57 UTC
So far I'm not able to reproduce this in an OpenSUSE 42.3 container.
Comment 2 Paul Eggleton 2018-04-11 13:40:26 UTC
With Aaron's help I got further with this. makeinfo (part of texinfo) is being used from the host, and at version 4.13 it is definitely too old to compile the documentation due to the @atchar command which was only introduced with texinfo 5.0. The thing is though the .texi source file is older than the .info output file on the machine where it fails, so it should not be trying to rebuild it - in my OpenSUSE 42.3 container it doesn't try, but in the VM it does.
Comment 3 Ross Burton 2018-04-12 06:49:23 UTC
I can't replicate this on the public 42.3 builder.
Comment 4 Aaron Chan 2018-04-12 19:56:35 UTC
====================================================================================
TAR version on KVM-OpenSuse 42.3
====================================================================================
$ tar -v
tar (GNU tar) 1.27.1
Copyright (C) 2013 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>.
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.

Written by John Gilmore and Jay Fenlason.
====================================================================================
MAKEINFO version on KVM-OpenSuse 42.3
====================================================================================
$ makeinfo --version
makeinfo (GNU texinfo) 4.13

Copyright (C) 2008 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.
====================================================================================


STEPS to reproduce error:

1. Run bitbake -c clean tar
2. Run bitbake -c compile tar
3. Tarball tar_source_directory(tmp/work/core2-64-poky-linux/tar/1.30-r0/1.30-r0)
4. Run bitbake -c install tar
5. Tarball tar_work_directory(tmp/work/core2-64-poky-linux/tar/1.30-r0)

=================================================================================
 ERROR message
=================================================================================
...
... <truncate to save space>
...
| rm -rf $backupdir; exit $rc
| ../../tar-1.30/doc/tar.texi:5480: Unknown command `atchar'.
| ../../tar-1.30/doc/tar.texi:5480: Misplaced {.
| ../../tar-1.30/doc/tar.texi:5480: Misplaced }.
| makeinfo: Removing output file `../../tar-1.30/doc/tar.info' due to errors; use --force to preserve.
| Makefile:1345: recipe for target '../../tar-1.30/doc/tar.info' failed
| make[1]: *** [../../tar-1.30/doc/tar.info] Error 1
| make[1]: Leaving directory '/home/ab/autobuilder/yocto-worker/nightly-oe-selftest-opensuse423/build/build/tmp/work/core2-64-poky-linux/tar/1.30-r0/build/doc'
| Makefile:1394: recipe for target 'install-recursive' failed
| make: *** [install-recursive] Error 1
| ERROR: oe_runmake failed

==================================================================================
On line 5480 the tar.texi file shown in ../../tar-1.30/doc/tar.texi:5480
==================================================================================
@smallexample
$ @kbd{tar -c -f archive.tar --clamp-mtime --mtime=@atchar{}$SOURCE_DATE_EPOCH .}
@end smallexample
Comment 5 Aaron Chan 2018-04-12 19:59:05 UTC
Created attachment 4263 [details]
bitbake install tar manual
Comment 6 Aaron Chan 2018-04-12 20:00:06 UTC
Created attachment 4264 [details]
Texi file define atchar
Comment 7 Aaron Chan 2018-04-12 20:01:04 UTC
Created attachment 4265 [details]
bitbake install tar logfile
Comment 8 Richard Purdie 2018-04-13 09:52:50 UTC
I've sent a patch to the mailing list which sets TZ to UTC which should fix this.