Traceback when tracking an element

Summary

With Buildstream 1.2.5, as well as the tip of the bst-1.2 branch, I get a traceback when tracking an element.

Steps to reproduce

  1. Use Buildstream 1.2 (I have not tried reproducing with the master branch)
  2. git clone https://gitlab.com/freedesktop-sdk/freedesktop-sdk
  3. cd freedesktop-sdk
  4. git checkout master
  5. bst track components/libglvnd.bst

What is the current bug behavior?

The tracking seems to have worked fine, however I get a traceback.

It seems to be in the codewhich prints messages to the console.

What is the expected correct behavior?

Tracking works, no traceback.

Relevant logs and/or screenshots

$ bst track components/libglvnd.bst 
[--:--:--][][] START   Track
[--:--:--][][] START   Loading elements
[00:00:00][][] SUCCESS Loading elements
[--:--:--][][] START   Resolving elements
[00:00:00][][] SUCCESS Resolving elements
[--:--:--][][] START   Resolving cached state
[00:00:00][][] SUCCESS Resolving cached state

BuildStream Version 1.2.5+62.g162c4166
  Session Start: Friday, 26-04-2019 at 21:54:57
  Project:       freedesktop-sdk (/home/mathieu/Projects/desktop/freedesktop-sdk)
  Targets:       
  Cache Usage:   11.7G / infinity (6%)

User Configuration
  Configuration File:      Default Configuration
  Log Files:               /home/mathieu/.cache/buildstream/logs
  Source Mirrors:          /home/mathieu/.cache/buildstream/sources
  Build Area:              /home/mathieu/.cache/buildstream/build
  Artifact Cache:          /home/mathieu/.cache/buildstream/artifacts
  Strict Build Plan:       Yes
  Maximum Fetch Tasks:     10
  Maximum Build Tasks:     4
  Maximum Push Tasks:      4
  Maximum Network Retries: 2

Project Options
  bootstrap_build_arch: x86_64
  snap_grade:           devel
  target_arch:          x86_64

Loaded Plugins
  Source Plugins
    - git_tag

Pipeline

===============================================================================
[--:--:--][????????][track:components/libglvnd.bst       ] START   freedesktop-sdk/components-libglvnd/????????-track.15689.log
Exception in callback Job._parent_recv()
handle: <Handle Job._parent_recv()>
Traceback (most recent call last):
  File "/usr/lib64/python3.7/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_scheduler/jobs/job.py", line 642, in _parent_recv
    self._parent_process_queue()
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_scheduler/jobs/job.py", line 634, in _parent_process_queue
    self._parent_process_envelope(envelope)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_scheduler/jobs/job.py", line 606, in _parent_process_envelope
    self._scheduler.context.message(envelope.message)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_context.py", line 376, in message
    self._message_handler(message, context=self)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_frontend/app.py", line 701, in _message_handler
    text = self.logger.render(message)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_frontend/widget.py", line 593, in render
    return self._render(message)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_frontend/widget.py", line 628, in _render
    text += widget.render(message)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_frontend/widget.py", line 227, in render
    plugin = Plugin._lookup(element_id)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/plugin.py", line 659, in _lookup
    assert unique_id in cls.__TABLE, "Could not find plugin with ID {}".format(unique_id)
AssertionError: Could not find plugin with ID 215
Exception in callback Job._parent_recv()
handle: <Handle Job._parent_recv()>
Traceback (most recent call last):
  File "/usr/lib64/python3.7/asyncio/events.py", line 88, in _run
    self._context.run(self._callback, *self._args)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_scheduler/jobs/job.py", line 642, in _parent_recv
    self._parent_process_queue()
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_scheduler/jobs/job.py", line 634, in _parent_process_queue
    self._parent_process_envelope(envelope)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_scheduler/jobs/job.py", line 606, in _parent_process_envelope
    self._scheduler.context.message(envelope.message)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_context.py", line 376, in message
    self._message_handler(message, context=self)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_frontend/app.py", line 701, in _message_handler
    text = self.logger.render(message)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_frontend/widget.py", line 593, in render
    return self._render(message)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_frontend/widget.py", line 628, in _render
    text += widget.render(message)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/_frontend/widget.py", line 227, in render
    plugin = Plugin._lookup(element_id)
  File "/home/mathieu/.local/lib/python3.7/site-packages/buildstream/plugin.py", line 659, in _lookup
    assert unique_id in cls.__TABLE, "Could not find plugin with ID {}".format(unique_id)
AssertionError: Could not find plugin with ID 215
[--:--:--][        ][track:components/libglvnd.bst-0     ] INFO    Found new revision: v1.1.1-0-gbc9990b7bede44899627354d0c356f48950f4ad1
[00:00:00][????????][track:components/libglvnd.bst       ] SUCCESS freedesktop-sdk/components-libglvnd/????????-track.15689.log
[00:00:02][][] SUCCESS Track

Resolved key Summary
     waiting 3d8725eac964652be6b2238c9ab2ad5aed5d1d087d4a3becd62095e35794dcf9 components/libglvnd.bst 

Pipeline Summary
  Total:       0
  Session:     1
  Track Queue: processed 1, skipped 0, failed 0
Edited by Mathieu Bridon