Bug 11275

Output produced by the bitbake server (for example, bb.note() messages) gets printed twice in a tinfoil application, even with current bitbake master-next.

Example application "parse-recipes.py":

#!/usr/bin/python3

import os
import sys
import logging

# Allow importing lib modules
lib_path = os.path.abspath(os.path.dirname(os.path.realpath(__file__)) + '/../lib')
sys.path.insert(0, lib_path)

# look for bitbake/bin dir in PATH
bitbakepath = None
for pth in os.environ['PATH'].split(':'):
    if os.path.exists(os.path.join(pth, '../lib/bb')):
        bitbakepath = os.path.abspath(os.path.join(pth, '..'))
        break
if bitbakepath:
    sys.path.insert(0, bitbakepath + '/lib')
else:
    print("ERROR: bitbake not found in PATH. This script must be called after setting up a bitbake build environment.",
          file=sys.stderr)
    exit(1)

import bb.tinfoil
import bb
with bb.tinfoil.Tinfoil() as tinfoil:
    # Increase logging from tinfoil and use the logger.
    logger = tinfoil.logger
    tinfoil.logger.setLevel(logging.INFO)
    # Both of these messages appear only once.
    logger.info('logger.info: foo')
    bb.note('bb.note: bar')
    # But any bb.note or bb.warn from a recipe appears twice.
    tinfoil.prepare(config_only=False)

Modified OE-core to trigger a message:

diff --git a/meta/recipes-extended/ethtool/ethtool_4.8.bb b/meta/recipes-extended/ethtool/ethtool_4.8.bb
index 4ca88d766c6..ef153164335 100644
--- a/meta/recipes-extended/ethtool/ethtool_4.8.bb
+++ b/meta/recipes-extended/ethtool/ethtool_4.8.bb
@@ -28,3 +28,6 @@ do_install_ptest () {
    install ${B}/ethtool             ${D}${PTEST_PATH}/ethtool
    sed -i 's/^Makefile/_Makefile/'  ${D}${PTEST_PATH}/Makefile
 }
+python () {
+    bb.note('ethtool getting parsed')
+}

Output okay in bitbake, not okay in the test app:

$ rm -rf cache/ tmp-glibc/cache/
$ parse-recipes.py
NOTE: logger.info: foo
NOTE: bb.note: bar
NOTE: ethtool getting parsed###########################################################################                 | ETA:  0:00:03
NOTE: /fast/work/intel-iot-refkit/openembedded-core/meta/recipes-extended/ethtool/ethtool_4.8.bb: ethtool getting parsed
NOTE: /fast/work/intel-iot-refkit/openembedded-core/meta/recipes-extended/ethtool/ethtool_4.8.bb: ethtool getting parsed
Parsing recipes: 100% |#################################################################################################| Time: 0:00:23
Parsing of 2174 .bb files complete (0 cached, 2174 parsed). 3017 targets, 455 skipped, 0 masked, 0 errors.

Summary: There was 1 WARNING message shown.

$ rm -rf cache/ tmp-glibc/cache/
$ bitbake -g ethtool
NOTE: /fast/work/intel-iot-refkit/openembedded-core/meta/recipes-extended/ethtool/ethtool_4.8.bb: ethtool getting parsed| ETA:  0:00:03
Parsing recipes: 100% |#################################################################################################| Time: 0:00:23
Parsing of 2174 .bb files complete (0 cached, 2174 parsed). 3017 targets, 455 skipped, 0 masked, 0 errors.
NOTE: Resolving any missing task queue dependencies
NOTE: PN build list saved to 'pn-buildlist'
NOTE: Task dependencies saved to 'task-depends.dot'
NOTE: Flatened recipe dependencies saved to 'recipe-depends.dot'

Summary: There was 1 WARNING message shown.