Bug #1717921 “CI: BlockingIOError about 50% of the time” : Bugs : Snapcraft

Revision history for this message

Kyle Fazzari (kyrofa) wrote on 2017-10-19:

#1

Download full text (20.2 KiB)

I'm seeing this all over the place, in different tests, and unrelated PRs. Here's a full one:

======================================================================

ERROR: test_build (test_rust_plugin.RustPluginConfinementTestCase)

test_rust_plugin.RustPluginConfinementTestCase.test_build (classic)

----------------------------------------------------------------------

testtools.testresult.real._StringException: output: {{{

Starting snapcraft devel from /root/snapcraft/snapcraft/cli.

"grade" property not specified: defaulting to "stable"

Setting up part 'rust-hello' with plugin 'rust' and properties {'plugin': 'rust', 'source': '.', 'stage': [], 'prime': []}.

Marking 'gcc' (and its dependencies) to be fetched

Marking 'git' (and its dependencies) to be fetched

Marking 'file' (and its dependencies) to be fetched

Marking 'curl' (and its dependencies) to be fetched

Getting details for core

Starting new HTTPS connection (1): api.snapcraft.io

"GET /api/v1/snaps/details/core?channel=stable&fields=status%2Canon_download_url%2Cdownload_url%2Cdownload_sha3_384%2Cdownload_sha512%2Csnap_id%2Crevision%2Crelease HTTP/1.1" 200 589

Downloading core

Starting new HTTPS connection (1): api.snapcraft.io

"HEAD /api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_3017.snap HTTP/1.1" 302 0

Starting new HTTPS connection (1): 068ed04f23.site.internapcdn.net

"GET /download-snap/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_3017.snap?t=2017-10-19T21:00:00Z&h=6b7cc022a250434d9f0754c24f915b5970ea5803 HTTP/1.1" 200 87080960

Successfully downloaded core at /tmp/tmpcb4i_dqh/core.snap

b'Parallel unsquashfs: Using 2 processors\n1 inodes (1 blocks) to write\n\n\r[===================================================================|] 1/1 100%\n\ncreated 1 files\ncreated 2 directories\ncreated 0 symlinks\ncreated 0 devices\ncreated 0 fifos\n'

Setting up '/tmp/tmpdl8bye5v/.cache/snapcraft/projects/snapcraft-core/snap_hashes/amd64/09407c3b0197d5a3f9324b83a50b266840550ad9f15ff7fafe3be282d927901def755c6a6f64916acb41ebbcdcd9cdd5' in '/snap/core/current'

Parallel unsquashfs: Using 2 processors

12267 inodes (13603 blocks) to write

[===========================================================-] 13603/13603 100%

created 9635 files

created 1464 directories

created 2543 symlinks

created 79 devices

created 0 fifos

Preparing to pull rust-hello

Pulling rust-hello

Starting new HTTPS connection (1): static.rust-lang.org

"GET /rustup.sh HTTP/1.1" 200 61986

Downloading 'core.snap' 0%

Downloading 'core.snap' 1%

Downloading 'core.snap' 2%

Downloading 'core.snap' 3%

Downloading 'core.snap' 4%

Downloading 'core.snap' 5%

Downloading 'core.snap' 6%

Downloading 'core.snap' 7%

Downloading 'core.snap' 8%
...

I'm seeing this all over the place, in different tests, and unrelated PRs. Here's a full one:

======================================================================

ERROR: test_build (test_rust_plugin.RustPluginConfinementTestCase)

test_rust_plugin.RustPluginConfinementTestCase.test_build (classic)

----------------------------------------------------------------------

testtools.testresult.real._StringException: output: {{{

Starting snapcraft devel from /root/snapcraft/snapcraft/cli.

"grade" property not specified: defaulting to "stable"

Setting up part 'rust-hello' with plugin 'rust' and properties {'plugin': 'rust', 'source': '.', 'stage': [], 'prime': []}.

Marking 'gcc' (and its dependencies) to be fetched

Marking 'git' (and its dependencies) to be fetched

Marking 'file' (and its dependencies) to be fetched

Marking 'curl' (and its dependencies) to be fetched

Getting details for core

Starting new HTTPS connection (1): api.snapcraft.io

"GET /api/v1/snaps/details/core?channel=stable&fields=status%2Canon_download_url%2Cdownload_url%2Cdownload_sha3_384%2Cdownload_sha512%2Csnap_id%2Crevision%2Crelease HTTP/1.1" 200 589

Downloading core

Starting new HTTPS connection (1): api.snapcraft.io

"HEAD /api/v1/snaps/download/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_3017.snap HTTP/1.1" 302 0

Starting new HTTPS connection (1): 068ed04f23.site.internapcdn.net

"GET /download-snap/99T7MUlRhtI3U0QFgl5mXXESAiSwt776_3017.snap?t=2017-10-19T21:00:00Z&h=6b7cc022a250434d9f0754c24f915b5970ea5803 HTTP/1.1" 200 87080960

Successfully downloaded core at /tmp/tmpcb4i_dqh/core.snap

b'Parallel unsquashfs: Using 2 processors\n1 inodes (1 blocks) to write\n\n\r[===================================================================|] 1/1 100%\n\ncreated 1 files\ncreated 2 directories\ncreated 0 symlinks\ncreated 0 devices\ncreated 0 fifos\n'

Setting up '/tmp/tmpdl8bye5v/.cache/snapcraft/projects/snapcraft-core/snap_hashes/amd64/09407c3b0197d5a3f9324b83a50b266840550ad9f15ff7fafe3be282d927901def755c6a6f64916acb41ebbcdcd9cdd5' in '/snap/core/current'

Parallel unsquashfs: Using 2 processors

12267 inodes (13603 blocks) to write

[===========================================================-] 13603/13603 100%

created 9635 files

created 1464 directories

created 2543 symlinks

created 79 devices

created 0 fifos

Preparing to pull rust-hello

Pulling rust-hello

Starting new HTTPS connection (1): static.rust-lang.org

"GET /rustup.sh HTTP/1.1" 200 61986

Downloading 'core.snap'   0%

Downloading 'core.snap'   1%

Downloading 'core.snap'   2%

Downloading 'core.snap'   3%

Downloading 'core.snap'   4%

Downloading 'core.snap'   5%

Downloading 'core.snap'   6%

Downloading 'core.snap'   7%

Downloading 'core.snap'   8%

Downloading 'core.snap'   9%

Downloading 'core.snap'  10%

Downloading 'core.snap'  11%

Downloading 'core.snap'  12%

Downloading 'core.snap'  13%

Downloading 'core.snap'  14%

Downloading 'core.snap'  15%

Downloading 'core.snap'  16%

Downloading 'core.snap'  17%

Downloading 'core.snap'  18%

Downloading 'core.snap'  19%

Downloading 'core.snap'  20%

Downloading 'core.snap'  21%

Downloading 'core.snap'  22%

Downloading 'core.snap'  23%

Downloading 'core.snap'  24%

Downloading 'core.snap'  25%

Downloading 'core.snap'  26%

Downloading 'core.snap'  27%

Downloading 'core.snap'  28%

Downloading 'core.snap'  29%

Downloading 'core.snap'  30%

Downloading 'core.snap'  31%

Downloading 'core.snap'  32%

Downloading 'core.snap'  33%

Downloading 'core.snap'  34%

Downloading 'core.snap'  35%

Downloading 'core.snap'  36%

Downloading 'core.snap'  37%

Downloading 'core.snap'  38%

Downloading 'core.snap'  39%

Downloading 'core.snap'  40%

Downloading 'core.snap'  41%

Downloading 'core.snap'  42%

Downloading 'core.snap'  43%

Downloading 'core.snap'  44%

Downloading 'core.snap'  45%

Downloading 'core.snap'  46%

Downloading 'core.snap'  47%

Downloading 'core.snap'  48%

Downloading 'core.snap'  49%

Downloading 'core.snap'  50%

Downloading 'core.snap'  51%

Downloading 'core.snap'  52%

Downloading 'core.snap'  53%

Downloading 'core.snap'  54%

Downloading 'core.snap'  55%

Downloading 'core.snap'  56%

Downloading 'core.snap'  57%

Downloading 'core.snap'  58%

Downloading 'core.snap'  59%

Downloading 'core.snap'  60%

Downloading 'core.snap'  61%

Downloading 'core.snap'  62%

Downloading 'core.snap'  63%

Downloading 'core.snap'  64%

Downloading 'core.snap'  65%

Downloading 'core.snap'  66%

Downloading 'core.snap'  67%

Downloading 'core.snap'  68%

Downloading 'core.snap'  69%

Downloading 'core.snap'  70%

Downloading 'core.snap'  71%

Downloading 'core.snap'  72%

Downloading 'core.snap'  73%

Downloading 'core.snap'  74%

Downloading 'core.snap'  75%

Downloading 'core.snap'  76%

Downloading 'core.snap'  77%

Downloading 'core.snap'  78%

Downloading 'core.snap'  79%

Downloading 'core.snap'  80%

Downloading 'core.snap'  81%

Downloading 'core.snap'  82%

Downloading 'core.snap'  83%

Downloading 'core.snap'  84%

Downloading 'core.snap'  85%

Downloading 'core.snap'  86%

Downloading 'core.snap'  87%

Downloading 'core.snap'  88%

Downloading 'core.snap'  89%

Downloading 'core.snap'  90%

Downloading 'core.snap'  91%

Downloading 'core.snap'  92%

Downloading 'core.snap'  93%

Downloading 'core.snap'  94%

Downloading 'core.snap'  95%

Downloading 'core.snap'  96%

Downloading 'core.snap'  97%

Downloading 'core.snap'  98%

Downloading 'core.snap'  99%

Downloading 'core.snap' 100%

Downloading 'rustup.sh'   0%

Downloading 'rustup.sh'   1%

rustup: gpg available. signatures will be verified

rustup: downloading manifest for 'stable'

rustup: downloading toolchain for 'stable'

###                                                                        5.3%

##########                                                                15.0%

##################                                                        25.2%

#######################                                                   32.9%

curl: (56) GnuTLS recv error (-54): Error in the pull function.

rustup: command failed: curl -# -C - -f -O https://static.rust-lang.org/dist/2017-10-12/rust-1.21.0-x86_64-unknown-linux-gnu.tar.gz

rustup: couldn't download 'https://static.rust-lang.org/dist/2017-10-12/rust-1.21.0-x86_64-unknown-linux-gnu.tar.gz'

Downloading 'rustup.sh'   3%

Downloading 'rustup.sh'   4%

Downloading 'rustup.sh'   6%

Downloading 'rustup.sh'   8%

Downloading 'rustup.sh'   9%

Downloading 'rustup.sh'  11%

Downloading 'rustup.sh'  13%

Downloading 'rustup.sh'  14%

Downloading 'rustup.sh'  16%

Downloading 'rustup.sh'  18%

Downloading 'rustup.sh'  19%

Downloading 'rustup.sh'  21%

Downloading 'rustup.sh'  23%

Downloading 'rustup.sh'  24%

Downloading 'rustup.sh'  26%

Downloading 'rustup.sh'  28%

Downloading 'rustup.sh'  29%

Downloading 'rustup.sh'  31%

Downloading 'rustup.sh'  33%

Downloading 'rustup.sh'  34%

Downloading 'rustup.sh'  36%

Downloading 'rustup.sh'  37%

Downloading 'rustup.sh'  39%

Downloading 'rustup.sh'  41%

Downloading 'rustup.sh'  42%

Downloading 'rustup.sh'  44%

Downloading 'rustup.sh'  46%

Downloading 'rustup.sh'  47%

Downloading 'rustup.sh'  49%

Downloading 'rustup.sh'  51%

Downloading 'rustup.sh'  52%

Downloading 'rustup.sh'  54%

Downloading 'rustup.sh'  56%

Downloading 'rustup.sh'  57%

Downloading 'rustup.sh'  59%

Downloading 'rustup.sh'  61%

Downloading 'rustup.sh'  62%

Downloading 'rustup.sh'  64%

Downloading 'rustup.sh'  66%

Downloading 'rustup.sh'  67%

Downloading 'rustup.sh'  69%

Downloading 'rustup.sh'  71%

Downloading 'rustup.sh'  72%

Downloading 'rustup.sh'  74%

Downloading 'rustup.sh'  75%

Downloading 'rustup.sh'  77%

Downloading 'rustup.sh'  79%

Downloading 'rustup.sh'  80%

Downloading 'rustup.sh'  82%

Downloading 'rustup.sh'  84%

Downloading 'rustup.sh'  85%

Downloading 'rustup.sh'  87%

Downloading 'rustup.sh'  89%

Downloading 'rustup.sh'  90%

Downloading 'rustup.sh'  92%

Downloading 'rustup.sh'  94%

Downloading 'rustup.sh'  95%

Downloading 'rustup.sh'  97%

Downloading 'rustup.sh'  99%

Downloading 'rustup.sh' 100%

/tmp/tmpdl8bye5v/parts/rust-hello/rust/rustup.sh --prefix=/tmp/tmpdl8bye5v/parts/rust-hello/rust --disable-sudo --save

Traceback (most recent call last):

File "/root/snapcraft/bin/snapcraft", line 36, in <module>

obj=dict(project=snapcraft.ProjectOptions()))

File "/usr/local/lib/python3.5/dist-packages/click/core.py", line 722, in __call__

return self.main(*args, **kwargs)

File "/usr/local/lib/python3.5/dist-packages/click/core.py", line 697, in main

rv = self.invoke(ctx)

File "/usr/local/lib/python3.5/dist-packages/click/core.py", line 1066, in invoke

return _process_result(sub_ctx.command.invoke(sub_ctx))

File "/usr/local/lib/python3.5/dist-packages/click/core.py", line 895, in invoke

return ctx.invoke(self.callback, **ctx.params)

File "/usr/local/lib/python3.5/dist-packages/click/core.py", line 535, in invoke

return callback(*args, **kwargs)

File "/root/snapcraft/snapcraft/cli/lifecycle.py", line 80, in build

_execute('build', parts, **kwargs)

File "/root/snapcraft/snapcraft/cli/lifecycle.py", line 32, in _execute

lifecycle.execute(command, project_options, parts)

File "/root/snapcraft/snapcraft/internal/lifecycle/_runner.py", line 80, in execute

_Executor(config, project_options).run(step, part_names)

File "/root/snapcraft/snapcraft/internal/lifecycle/_runner.py", line 175, in run

self._run_step(step, part, part_names)

File "/root/snapcraft/snapcraft/internal/lifecycle/_runner.py", line 212, in _run_step

getattr(part, step)()

File "/root/snapcraft/snapcraft/internal/pluginhandler/__init__.py", line 252, in pull

self.plugin.pull()

File "/root/snapcraft/snapcraft/plugins/rust.py", line 180, in pull

self._fetch_rust()

File "/root/snapcraft/snapcraft/plugins/rust.py", line 216, in _fetch_rust

self.run(cmd)

File "/root/snapcraft/snapcraft/_baseplugin.py", line 202, in run

return common.run(cmd, cwd=cwd, **kwargs)

File "/root/snapcraft/snapcraft/internal/common.py", line 63, in run

subprocess.check_call(['/bin/sh', f.name] + cmd, **kwargs)

File "/usr/lib/python3.5/subprocess.py", line 581, in check_call

raise CalledProcessError(retcode, cmd)

subprocess.CalledProcessError: Command '['/bin/sh', '/tmp/tmpw6ztisfz', '/tmp/tmpdl8bye5v/parts/rust-hello/rust/rustup.sh', '--prefix=/tmp/tmpdl8bye5v/parts/rust-hello/rust', '--disable-sudo', '--save']' returned non-zero exit status 1

}}}

Traceback (most recent call last):

File "/root/snapcraft/integration_tests/plugins/test_rust_plugin.py", line 131, in test_build

self.run_snapcraft('build')

File "/root/snapcraft/integration_tests/plugins/test_rust_plugin.py", line 36, in run_snapcraft

super().run_snapcraft(command, project_dir, debug)

File "/root/snapcraft/integration_tests/__init__.py", line 132, in run_snapcraft

env=env)

File "/usr/lib/python3.5/subprocess.py", line 626, in check_output

**kwargs).stdout

File "/usr/lib/python3.5/subprocess.py", line 708, in run

output=stdout, stderr=stderr)

subprocess.CalledProcessError: Command '['/root/snapcraft/bin/snapcraft', '-d', 'build']' returned non-zero exit status 1

Traceback (most recent call last):

File "/usr/lib/python3.5/runpy.py", line 184, in _run_module_as_main

"__main__", mod_spec)

File "/usr/lib/python3.5/runpy.py", line 85, in _run_code

exec(code, run_globals)

File "/usr/lib/python3.5/unittest/__main__.py", line 18, in <module>

main(module=None)

File "/usr/lib/python3.5/unittest/main.py", line 94, in __init__

self.runTests()

File "/usr/lib/python3.5/unittest/main.py", line 255, in runTests

self.result = testRunner.run(self.test)

File "/usr/lib/python3.5/unittest/runner.py", line 183, in run

result.printErrors()

File "/usr/lib/python3.5/unittest/runner.py", line 109, in printErrors

self.printErrorList('ERROR', self.errors)

File "/usr/lib/python3.5/unittest/runner.py", line 117, in printErrorList

self.stream.writeln("%s" % err)

File "/usr/lib/python3.5/unittest/runner.py", line 25, in writeln

self.write(arg)

BlockingIOError: [Errno 11] write could not complete without blocking

summary:

- BlockingIOError after "Downloading 'PyYAML-3.11.tar.gz' 23%"
+ CI: BlockingIOError about 50% of the time

Revision history for this message

Kyle Fazzari (kyrofa) wrote on 2017-10-19:

#2

Download full text (31.0 KiB)

And another, different PR, different test:

======================================================================

ERROR: test_build_rewrites_shebangs (test_python_plugin.PythonPluginTestCase)

test_python_plugin.PythonPluginTestCase.test_build_rewrites_shebangs

----------------------------------------------------------------------

testtools.testresult.real._StringException: output: {{{

Starting snapcraft devel from /root/snapcraft/snapcraft/cli.

"grade" property not specified: defaulting to "stable"

DEPRECATED: The 'python3' plugin's functionality has been replaced by the 'python' plugin, and it will soon be removed.

Setting up part 'python3-test' with plugin 'python3' and properties {'source': 'python3/', 'plugin': 'python3', 'prime': [], 'stage': []}.

Setting up part 'python-test' with plugin 'python' and properties {'stage': ['-lib/python3.5/site-packages/pip*', '-lib/python3.5/site-packages/setuptools*', '-lib/python3.5/site-packages/wheel*', '-lib/python3.5/site-packages/test-0.1.dist-info/RECORD'], 'prime': [], 'source': 'python3/', 'plugin': 'python', 'organize': {'bin/python3_test': 'bin/python_test'}}.

DEPRECATED: The 'python2' plugin's functionality has been replaced by the 'python' plugin, and it will soon be removed.

Setting up part 'python2-test' with plugin 'python2' and properties {'source': 'python2/', 'plugin': 'python2', 'prime': [], 'stage': []}.

Marking 'python3-pip' (and its dependencies) to be fetched

Marking 'python-setuptools' (and its dependencies) to be fetched

Marking 'python-pip' (and its dependencies) to be fetched

Marking 'python-dev' (and its dependencies) to be fetched

Marking 'python3-pkg-resources' (and its dependencies) to be fetched

Marking 'python3-dev' (and its dependencies) to be fetched

Marking 'python-pkg-resources' (and its dependencies) to be fetched

Marking 'python3-setuptools' (and its dependencies) to be fetched

Installing build dependencies: libpython-all-dev libpython-dev libpython2.7 libpython2.7-dev python-all python-all-dev python-dev python-pip python-wheel python2.7-dev

Reading package lists...

Building dependency tree...

Reading state information...

The following NEW packages will be installed:

libpython-all-dev libpython-dev libpython2.7 libpython2.7-dev python-all

python-all-dev python-dev python-pip python-wheel python2.7-dev

debconf: unable to initialize frontend: Dialog

debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)

debconf: falling back to frontend: Readline

0 upgraded, 10 newly installed, 0 to remove and 0 not upgraded.

Need to get 0 B/29.4 MB of archives.

After this operation, 45.7 MB of additional disk space will be used.

Selecting previously unselected package libpython2.7:amd64.

(Reading database ...

(Reading database ... 5%

(Reading database ... 10%

(Reading database ... 15%

(Reading database ... 20%

(Reading database ... 25%

(Reading database ... 30%

(Reading database ... 35%

(Reading database ... 40%

(Reading database ... 45%

(Reading database ... 50%

(Reading database ... 55%

(Reading database ... 60%

(Reading database ... 65%

(Reading database ... 70%...

And another, different PR, different test:

======================================================================

ERROR: test_build_rewrites_shebangs (test_python_plugin.PythonPluginTestCase)

test_python_plugin.PythonPluginTestCase.test_build_rewrites_shebangs

----------------------------------------------------------------------

testtools.testresult.real._StringException: output: {{{

Starting snapcraft devel from /root/snapcraft/snapcraft/cli.

"grade" property not specified: defaulting to "stable"

DEPRECATED: The 'python3' plugin's functionality has been replaced by the 'python' plugin, and it will soon be removed.

Setting up part 'python3-test' with plugin 'python3' and properties {'source': 'python3/', 'plugin': 'python3', 'prime': [], 'stage': []}.

Setting up part 'python-test' with plugin 'python' and properties {'stage': ['-lib/python3.5/site-packages/pip*', '-lib/python3.5/site-packages/setuptools*', '-lib/python3.5/site-packages/wheel*', '-lib/python3.5/site-packages/test-0.1.dist-info/RECORD'], 'prime': [], 'source': 'python3/', 'plugin': 'python', 'organize': {'bin/python3_test': 'bin/python_test'}}.

DEPRECATED: The 'python2' plugin's functionality has been replaced by the 'python' plugin, and it will soon be removed.

Setting up part 'python2-test' with plugin 'python2' and properties {'source': 'python2/', 'plugin': 'python2', 'prime': [], 'stage': []}.

Marking 'python3-pip' (and its dependencies) to be fetched

Marking 'python-setuptools' (and its dependencies) to be fetched

Marking 'python-pip' (and its dependencies) to be fetched

Marking 'python-dev' (and its dependencies) to be fetched

Marking 'python3-pkg-resources' (and its dependencies) to be fetched

Marking 'python3-dev' (and its dependencies) to be fetched

Marking 'python-pkg-resources' (and its dependencies) to be fetched

Marking 'python3-setuptools' (and its dependencies) to be fetched

Installing build dependencies: libpython-all-dev libpython-dev libpython2.7 libpython2.7-dev python-all python-all-dev python-dev python-pip python-wheel python2.7-dev

Reading package lists...

Building dependency tree...

Reading state information...

The following NEW packages will be installed:

libpython-all-dev libpython-dev libpython2.7 libpython2.7-dev python-all

python-all-dev python-dev python-pip python-wheel python2.7-dev

debconf: unable to initialize frontend: Dialog

debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)

debconf: falling back to frontend: Readline

0 upgraded, 10 newly installed, 0 to remove and 0 not upgraded.

Need to get 0 B/29.4 MB of archives.

After this operation, 45.7 MB of additional disk space will be used.

Selecting previously unselected package libpython2.7:amd64.

(Reading database ...

(Reading database ... 5%

(Reading database ... 10%

(Reading database ... 15%

(Reading database ... 20%

(Reading database ... 25%

(Reading database ... 30%

(Reading database ... 35%

(Reading database ... 40%

(Reading database ... 45%

(Reading database ... 50%

(Reading database ... 55%

(Reading database ... 60%

(Reading database ... 65%

(Reading database ... 70%

(Reading database ... 75%

(Reading database ... 80%

(Reading database ... 85%

(Reading database ... 90%

(Reading database ... 95%

(Reading database ... 100%

(Reading database ... 34855 files and directories currently installed.)

Preparing to unpack .../libpython2.7_2.7.12-1ubuntu0~16.04.1_amd64.deb ...

Unpacking libpython2.7:amd64 (2.7.12-1ubuntu0~16.04.1) ...

Selecting previously unselected package libpython2.7-dev:amd64.

Preparing to unpack .../libpython2.7-dev_2.7.12-1ubuntu0~16.04.1_amd64.deb ...

Unpacking libpython2.7-dev:amd64 (2.7.12-1ubuntu0~16.04.1) ...

Selecting previously unselected package libpython-dev:amd64.

Preparing to unpack .../libpython-dev_2.7.11-1_amd64.deb ...

Unpacking libpython-dev:amd64 (2.7.11-1) ...

Selecting previously unselected package libpython-all-dev:amd64.

Preparing to unpack .../libpython-all-dev_2.7.11-1_amd64.deb ...

Unpacking libpython-all-dev:amd64 (2.7.11-1) ...

Selecting previously unselected package python-all.

Preparing to unpack .../python-all_2.7.11-1_amd64.deb ...

Unpacking python-all (2.7.11-1) ...

Selecting previously unselected package python2.7-dev.

Preparing to unpack .../python2.7-dev_2.7.12-1ubuntu0~16.04.1_amd64.deb ...

Unpacking python2.7-dev (2.7.12-1ubuntu0~16.04.1) ...

Selecting previously unselected package python-dev.

Preparing to unpack .../python-dev_2.7.11-1_amd64.deb ...

Unpacking python-dev (2.7.11-1) ...

Selecting previously unselected package python-all-dev.

Preparing to unpack .../python-all-dev_2.7.11-1_amd64.deb ...

Unpacking python-all-dev (2.7.11-1) ...

Selecting previously unselected package python-pip.

Preparing to unpack .../python-pip_8.1.1-2ubuntu0.4_all.deb ...

Unpacking python-pip (8.1.1-2ubuntu0.4) ...

Selecting previously unselected package python-wheel.

Preparing to unpack .../python-wheel_0.29.0-1_all.deb ...

Unpacking python-wheel (0.29.0-1) ...

Processing triggers for libc-bin (2.23-0ubuntu9) ...

Processing triggers for man-db (2.7.5-1) ...

Setting up libpython2.7:amd64 (2.7.12-1ubuntu0~16.04.1) ...

Setting up libpython2.7-dev:amd64 (2.7.12-1ubuntu0~16.04.1) ...

Setting up libpython-dev:amd64 (2.7.11-1) ...

Setting up libpython-all-dev:amd64 (2.7.11-1) ...

Setting up python-all (2.7.11-1) ...

Setting up python2.7-dev (2.7.12-1ubuntu0~16.04.1) ...

Setting up python-dev (2.7.11-1) ...

Setting up python-all-dev (2.7.11-1) ...

Setting up python-pip (8.1.1-2ubuntu0.4) ...

Setting up python-wheel (0.29.0-1) ...

Processing triggers for libc-bin (2.23-0ubuntu9) ...

libpython-all-dev set to automatically installed.

libpython-dev set to automatically installed.

libpython2.7 set to automatically installed.

libpython2.7-dev set to automatically installed.

python-all set to automatically installed.

python-all-dev set to automatically installed.

python-dev set to automatically installed.

python-pip set to automatically installed.

python-wheel set to automatically installed.

python2.7-dev set to automatically installed.

Preparing to pull python2-test

Fetching stage-packages {'python'}

Get:1 http://archive.ubuntu.com/ubuntu xenial InRelease [247 kB]

Get:2 http://security.ubuntu.com/ubuntu xenial-security InRelease [102 kB]

Get:3 http://security.ubuntu.com/ubuntu xenial-security/main Sources [97.9 kB]

Get:4 http://archive.ubuntu.com/ubuntu xenial-updates InRelease [102 kB]

Get:5 http://security.ubuntu.com/ubuntu xenial-security/restricted Sources [2600 B]

Get:6 http://archive.ubuntu.com/ubuntu xenial-backports InRelease [102 kB]

Get:7 http://security.ubuntu.com/ubuntu xenial-security/universe Sources [43.4 kB]

Get:8 http://archive.ubuntu.com/ubuntu xenial/main Sources [868 kB]

Get:9 http://security.ubuntu.com/ubuntu xenial-security/multiverse Sources [1140 B]

Get:10 http://security.ubuntu.com/ubuntu xenial-security/main amd64 Packages [370 kB]

Get:11 http://archive.ubuntu.com/ubuntu xenial/restricted Sources [4808 B]

Get:12 http://security.ubuntu.com/ubuntu xenial-security/main Translation-en [164 kB]

Get:13 http://archive.ubuntu.com/ubuntu xenial/universe Sources [7728 kB]

Get:14 http://security.ubuntu.com/ubuntu xenial-security/restricted amd64 Packages [7352 B]

Get:15 http://security.ubuntu.com/ubuntu xenial-security/restricted Translation-en [2432 B]

Get:16 http://security.ubuntu.com/ubuntu xenial-security/universe amd64 Packages [175 kB]

Get:17 http://security.ubuntu.com/ubuntu xenial-security/universe Translation-en [93.0 kB]

Get:18 http://security.ubuntu.com/ubuntu xenial-security/multiverse amd64 Packages [2756 B]

Get:19 http://security.ubuntu.com/ubuntu xenial-security/multiverse Translation-en [1236 B]

Get:20 http://archive.ubuntu.com/ubuntu xenial/multiverse Sources [179 kB]

Get:21 http://archive.ubuntu.com/ubuntu xenial/main amd64 Packages [1201 kB]

Get:22 http://archive.ubuntu.com/ubuntu xenial/main Translation-en [568 kB]

Get:23 http://archive.ubuntu.com/ubuntu xenial/restricted amd64 Packages [8344 B]

Get:24 http://archive.ubuntu.com/ubuntu xenial/restricted Translation-en [2908 B]

Get:25 http://archive.ubuntu.com/ubuntu xenial/universe amd64 Packages [7532 kB]

Get:26 http://archive.ubuntu.com/ubuntu xenial/universe Translation-en [4354 kB]

Get:27 http://archive.ubuntu.com/ubuntu xenial/multiverse amd64 Packages [144 kB]

Get:28 http://archive.ubuntu.com/ubuntu xenial/multiverse Translation-en [106 kB]

Get:29 http://archive.ubuntu.com/ubuntu xenial-updates/main Sources [278 kB]

Get:30 http://archive.ubuntu.com/ubuntu xenial-updates/restricted Sources [3404 B]

Get:31 http://archive.ubuntu.com/ubuntu xenial-updates/universe Sources [176 kB]

Get:32 http://archive.ubuntu.com/ubuntu xenial-updates/multiverse Sources [7208 B]

Get:33 http://archive.ubuntu.com/ubuntu xenial-updates/main amd64 Packages [642 kB]

Get:34 http://archive.ubuntu.com/ubuntu xenial-updates/main Translation-en [269 kB]

Get:35 http://archive.ubuntu.com/ubuntu xenial-updates/restricted amd64 Packages [7972 B]

Get:36 http://archive.ubuntu.com/ubuntu xenial-updates/restricted Translation-en [2692 B]

Get:37 http://archive.ubuntu.com/ubuntu xenial-updates/universe amd64 Packages [540 kB]

Get:38 http://archive.ubuntu.com/ubuntu xenial-updates/universe Translation-en [220 kB]

Get:39 http://archive.ubuntu.com/ubuntu xenial-updates/multiverse amd64 Packages [15.3 kB]

Get:40 http://archive.ubuntu.com/ubuntu xenial-updates/multiverse Translation-en [7544 B]

Get:41 http://archive.ubuntu.com/ubuntu xenial-backports/main Sources [3432 B]

Get:42 http://archive.ubuntu.com/ubuntu xenial-backports/universe Sources [4376 B]

Get:43 http://archive.ubuntu.com/ubuntu xenial-backports/main amd64 Packages [4860 B]

Get:44 http://archive.ubuntu.com/ubuntu xenial-backports/main Translation-en [3220 B]

Get:45 http://archive.ubuntu.com/ubuntu xenial-backports/universe amd64 Packages [5896 B]

Get:46 http://archive.ubuntu.com/ubuntu xenial-backports/universe Translation-en [3060 B]

Fetched 26.4 MB in 0s (0 B/s)

Marking 'python' (and its dependencies) to be fetched

Skipping blacklisted from manifest packages: ['adduser', 'apt', 'apt-utils', 'base-files', 'base-passwd', 'bash', 'bsdutils', 'coreutils', 'dash', 'debconf', 'debconf-i18n', 'debianutils', 'diffutils', 'dmsetup', 'dpkg', 'e2fslibs', 'e2fsprogs', 'file', 'findutils', 'gcc-4.9-base', 'gcc-5-base', 'gnupg', 'gpgv', 'grep', 'gzip', 'hostname', 'init', 'initscripts', 'insserv', 'libacl1', 'libapparmor1', 'libattr1', 'libaudit-common', 'libaudit1', 'libblkid1', 'libbz2-1.0', 'libc-bin', 'libc6', 'libcap2', 'libcap2-bin', 'libcomerr2', 'libcryptsetup4', 'libdb5.3', 'libdebconfclient0', 'libdevmapper1.02.1', 'libgcc1', 'libgcrypt20', 'libgpg-error0', 'libgpm2', 'libkmod2', 'liblocale-gettext-perl', 'liblzma5', 'libmagic1', 'libmount1', 'libncurses5', 'libncursesw5', 'libpam-modules', 'libpam-modules-bin', 'libpam-runtime', 'libpam0g', 'libpcre3', 'libreadline6', 'libselinux1', 'libsemanage-common', 'libsemanage1', 'libsepol1', 'libslang2', 'libsmartcols1', 'libss2', 'libstdc++6', 'libsystemd0', 'libtext-charwidth-perl', 'libtext-iconv-perl', 'libtext-wrapi18n-perl', 'libtinfo5', 'libudev1', 'libusb-0.1-4', 'libustr-1.0-1', 'libuuid1', 'locales', 'login', 'lsb-base', 'makedev', 'manpages', 'manpages-dev', 'mawk', 'mount', 'multiarch-support', 'ncurses-base', 'ncurses-bin', 'passwd', 'perl-base', 'procps', 'readline-common', 'sed', 'sensible-utils', 'systemd', 'systemd-sysv', 'sysv-rc', 'sysvinit-utils', 'tar', 'tzdata', 'ubuntu-keyring', 'udev', 'util-linux', 'zlib1g']

Get:1 python2.7-minimal_2.7.12-1ubuntu0~16.04.1_amd64.deb [1295 kB]

Fetched 1295 kB in 0s (0 B/s)

Get:1 libsqlite3-0_3.11.0-1ubuntu1_amd64.deb [396 kB]

Fetched 396 kB in 0s (0 B/s)

Get:1 python2.7_2.7.12-1ubuntu0~16.04.1_amd64.deb [224 kB]

Fetched 224 kB in 0s (0 B/s)

Get:1 mime-support_3.59ubuntu1_all.deb [31.0 kB]

Fetched 31.0 kB in 0s (0 B/s)

Get:1 gcc-6-base_6.0.1-0ubuntu1_amd64.deb [14.3 kB]

Fetched 14.3 kB in 0s (0 B/s)

Get:1 python_2.7.11-1_amd64.deb [137 kB]

Fetched 137 kB in 0s (0 B/s)

Get:1 libexpat1_2.1.0-7ubuntu0.16.04.3_amd64.deb [71.2 kB]

Fetched 71.2 kB in 0s (0 B/s)

Get:1 libpython-stdlib_2.7.11-1_amd64.deb [7656 B]

Fetched 7656 B in 0s (0 B/s)

Get:1 libpython2.7-minimal_2.7.12-1ubuntu0~16.04.1_amd64.deb [339 kB]

Fetched 339 kB in 0s (0 B/s)

Get:1 libffi6_3.2.1-4_amd64.deb [17.8 kB]

Fetched 17.8 kB in 0s (0 B/s)

Get:1 libpython2.7-stdlib_2.7.12-1ubuntu0~16.04.1_amd64.deb [1884 kB]

Fetched 1884 kB in 0s (0 B/s)

Get:1 python-minimal_2.7.11-1_amd64.deb [28.2 kB]

Fetched 28.2 kB in 0s (0 B/s)

Get:1 libssl1.0.0_1.0.2g-1ubuntu4.8_amd64.deb [1081 kB]

Fetched 1081 kB in 0s (0 B/s)

Unpacking stage-packages to '/tmp/tmpixpk5cpm/parts/python2-test/install'

Pulling python2-test

Fetching and installing pip...

Collecting pip

Downloading pip-9.0.1-py2.py3-none-any.whl (1.3MB)

Saved ./parts/python2-test/python-packages/pip-9.0.1-py2.py3-none-any.whl

Successfully downloaded pip

Ignoring indexes: https://pypi.python.org/simple

Collecting pip

Installing collected packages: pip

Successfully installed pip-8.1.1

Fetching and installing wheel...

Collecting wheel

Downloading wheel-0.30.0-py2.py3-none-any.whl (49kB)

Saved ./parts/python2-test/python-packages/wheel-0.30.0-py2.py3-none-any.whl

Successfully downloaded wheel

Collecting wheel

Installing collected packages: wheel

Successfully installed wheel-0.30.0

Fetching and installing setuptools...

Collecting setuptools

Downloading setuptools-36.6.0-py2.py3-none-any.whl (481kB)

Saved ./parts/python2-test/python-packages/setuptools-36.6.0-py2.py3-none-any.whl

Successfully downloaded setuptools

Collecting setuptools

Installing collected packages: setuptools

Successfully installed setuptools-36.6.0

Processing /tmp/tmpixpk5cpm/parts/python2-test/src

Link is a directory, ignoring download_dir

Successfully downloaded test

Preparing to pull python-test

Fetching stage-packages {'python3'}

Hit http://archive.ubuntu.com/ubuntu xenial InRelease

Get:1 http://security.ubuntu.com/ubuntu xenial-security InRelease [102 kB]

Hit http://archive.ubuntu.com/ubuntu xenial-updates InRelease

Hit http://archive.ubuntu.com/ubuntu xenial-backports InRelease

Fetched 102 kB in 0s (0 B/s)

Marking 'python3' (and its dependencies) to be fetched

Skipping blacklisted from manifest packages: ['adduser', 'apt', 'apt-utils', 'base-files', 'base-passwd', 'bash', 'bsdutils', 'coreutils', 'dash', 'debconf', 'debconf-i18n', 'debianutils', 'diffutils', 'dmsetup', 'dpkg', 'e2fslibs', 'e2fsprogs', 'file', 'findutils', 'gcc-4.9-base', 'gcc-5-base', 'gnupg', 'gpgv', 'grep', 'gzip', 'hostname', 'init', 'initscripts', 'insserv', 'libacl1', 'libapparmor1', 'libattr1', 'libaudit-common', 'libaudit1', 'libblkid1', 'libbz2-1.0', 'libc-bin', 'libc6', 'libcap2', 'libcap2-bin', 'libcomerr2', 'libcryptsetup4', 'libdb5.3', 'libdebconfclient0', 'libdevmapper1.02.1', 'libgcc1', 'libgcrypt20', 'libgpg-error0', 'libgpm2', 'libkmod2', 'liblocale-gettext-perl', 'liblzma5', 'libmagic1', 'libmount1', 'libncurses5', 'libncursesw5', 'libpam-modules', 'libpam-modules-bin', 'libpam-runtime', 'libpam0g', 'libpcre3', 'libreadline6', 'libselinux1', 'libsemanage-common', 'libsemanage1', 'libsepol1', 'libslang2', 'libsmartcols1', 'libss2', 'libstdc++6', 'libsystemd0', 'libtext-charwidth-perl', 'libtext-iconv-perl', 'libtext-wrapi18n-perl', 'libtinfo5', 'libudev1', 'libusb-0.1-4', 'libustr-1.0-1', 'libuuid1', 'locales', 'login', 'lsb-base', 'makedev', 'manpages', 'manpages-dev', 'mawk', 'mount', 'multiarch-support', 'ncurses-base', 'ncurses-bin', 'passwd', 'perl-base', 'procps', 'readline-common', 'sed', 'sensible-utils', 'systemd', 'systemd-sysv', 'sysv-rc', 'sysvinit-utils', 'tar', 'tzdata', 'ubuntu-keyring', 'udev', 'util-linux', 'zlib1g']

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libsqlite3-0_3.11.0-1ubuntu1_amd64.deb

Get:1 python3.5_3.5.2-2ubuntu0~16.04.3_amd64.deb [165 kB]

Fetched 165 kB in 0s (0 B/s)

Get:1 python3_3.5.1-3_amd64.deb [8710 B]

Fetched 8710 B in 0s (0 B/s)

Get:1 python3.5-minimal_3.5.2-2ubuntu0~16.04.3_amd64.deb [1596 kB]

Fetched 1596 kB in 0s (0 B/s)

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/mime-support_3.59ubuntu1_all.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/gcc-6-base_6.0.1-0ubuntu1_amd64.deb

Get:1 libmpdec2_2.4.2-1_amd64.deb [82.6 kB]

Fetched 82.6 kB in 0s (0 B/s)

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libexpat1_2.1.0-7ubuntu0.16.04.3_amd64.deb

Get:1 python3-minimal_3.5.1-3_amd64.deb [23.3 kB]

Fetched 23.3 kB in 0s (0 B/s)

Get:1 libpython3-stdlib_3.5.1-3_amd64.deb [6818 B]

Fetched 6818 B in 0s (0 B/s)

Get:1 dh-python_2.20151103ubuntu1.1_all.deb [74.1 kB]

Fetched 74.1 kB in 0s (0 B/s)

Get:1 libpython3.5-stdlib_3.5.2-2ubuntu0~16.04.3_amd64.deb [2132 kB]

Fetched 2132 kB in 0s (0 B/s)

Get:1 libpython3.5-minimal_3.5.2-2ubuntu0~16.04.3_amd64.deb [524 kB]

Fetched 524 kB in 0s (0 B/s)

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libssl1.0.0_1.0.2g-1ubuntu4.8_amd64.deb

Unpacking stage-packages to '/tmp/tmpixpk5cpm/parts/python-test/install'

Pulling python-test

Fetching and installing pip...

Collecting pip

Using cached pip-9.0.1-py2.py3-none-any.whl

Saved ./parts/python-test/python-packages/pip-9.0.1-py2.py3-none-any.whl

Successfully downloaded pip

Ignoring indexes: https://pypi.python.org/simple

Collecting pip

Installing collected packages: pip

Successfully installed pip-8.1.1

Fetching and installing wheel...

Collecting wheel

Using cached wheel-0.30.0-py2.py3-none-any.whl

Saved ./parts/python-test/python-packages/wheel-0.30.0-py2.py3-none-any.whl

Successfully downloaded wheel

Collecting wheel

Installing collected packages: wheel

Successfully installed wheel-0.30.0

Fetching and installing setuptools...

Collecting setuptools

Using cached setuptools-36.6.0-py2.py3-none-any.whl

Saved ./parts/python-test/python-packages/setuptools-36.6.0-py2.py3-none-any.whl

Successfully downloaded setuptools

Collecting setuptools

Installing collected packages: setuptools

Successfully installed setuptools-36.6.0

Processing /tmp/tmpixpk5cpm/parts/python-test/src

Link is a directory, ignoring download_dir

Successfully downloaded test

Preparing to pull python3-test

Fetching stage-packages {'python3'}

Hit http://security.ubuntu.com/ubuntu xenial-security InRelease

Hit http://archive.ubuntu.com/ubuntu xenial InRelease

Hit http://archive.ubuntu.com/ubuntu xenial-updates InRelease

Hit http://archive.ubuntu.com/ubuntu xenial-backports InRelease

Fetched 0 B in 0s (0 B/s)

Marking 'python3' (and its dependencies) to be fetched

Skipping blacklisted from manifest packages: ['adduser', 'apt', 'apt-utils', 'base-files', 'base-passwd', 'bash', 'bsdutils', 'coreutils', 'dash', 'debconf', 'debconf-i18n', 'debianutils', 'diffutils', 'dmsetup', 'dpkg', 'e2fslibs', 'e2fsprogs', 'file', 'findutils', 'gcc-4.9-base', 'gcc-5-base', 'gnupg', 'gpgv', 'grep', 'gzip', 'hostname', 'init', 'initscripts', 'insserv', 'libacl1', 'libapparmor1', 'libattr1', 'libaudit-common', 'libaudit1', 'libblkid1', 'libbz2-1.0', 'libc-bin', 'libc6', 'libcap2', 'libcap2-bin', 'libcomerr2', 'libcryptsetup4', 'libdb5.3', 'libdebconfclient0', 'libdevmapper1.02.1', 'libgcc1', 'libgcrypt20', 'libgpg-error0', 'libgpm2', 'libkmod2', 'liblocale-gettext-perl', 'liblzma5', 'libmagic1', 'libmount1', 'libncurses5', 'libncursesw5', 'libpam-modules', 'libpam-modules-bin', 'libpam-runtime', 'libpam0g', 'libpcre3', 'libreadline6', 'libselinux1', 'libsemanage-common', 'libsemanage1', 'libsepol1', 'libslang2', 'libsmartcols1', 'libss2', 'libstdc++6', 'libsystemd0', 'libtext-charwidth-perl', 'libtext-iconv-perl', 'libtext-wrapi18n-perl', 'libtinfo5', 'libudev1', 'libusb-0.1-4', 'libustr-1.0-1', 'libuuid1', 'locales', 'login', 'lsb-base', 'makedev', 'manpages', 'manpages-dev', 'mawk', 'mount', 'multiarch-support', 'ncurses-base', 'ncurses-bin', 'passwd', 'perl-base', 'procps', 'readline-common', 'sed', 'sensible-utils', 'systemd', 'systemd-sysv', 'sysv-rc', 'sysvinit-utils', 'tar', 'tzdata', 'ubuntu-keyring', 'udev', 'util-linux', 'zlib1g']

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libsqlite3-0_3.11.0-1ubuntu1_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/python3.5_3.5.2-2ubuntu0~16.04.3_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/python3_3.5.1-3_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/python3.5-minimal_3.5.2-2ubuntu0~16.04.3_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/mime-support_3.59ubuntu1_all.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/gcc-6-base_6.0.1-0ubuntu1_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libmpdec2_2.4.2-1_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libexpat1_2.1.0-7ubuntu0.16.04.3_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/python3-minimal_3.5.1-3_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libpython3-stdlib_3.5.1-3_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/dh-python_2.20151103ubuntu1.1_all.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libpython3.5-stdlib_3.5.2-2ubuntu0~16.04.3_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libpython3.5-minimal_3.5.2-2ubuntu0~16.04.3_amd64.deb

Ignoring already existing file: /tmp/tmpixpk5cpm/.cache/snapcraft/stage-packages/apt/c1310e5c61fe6263e3d14ffa4204a4b9cdcfc0806336f1e86b9a52db69c2a7ed29eef6374def93a13a81afdcf83f71cd/var/cache/apt/archives/libssl1.0.0_1.0.2g-1ubuntu4.8_amd64.deb

Unpacking stage-packages to '/tmp/tmpixpk5cpm/parts/python3-test/install'

Pulling python3-test

Fetching and installing pip...

Collecting pip

Using cached pip-9.0.1-py2.py3-none-any.whl

Saved ./parts/python3-test/python-packages/pip-9.0.1-py2.py3-none-any.whl

Successfully downloaded pip

Ignoring indexes: https://pypi.python.org/simple

Collecting pip

Installing collected packages: pip

Successfully installed pip-8.1.1

Fetching and installing wheel...

Collecting wheel

Using cached wheel-0.30.0-py2.py3-none-any.whl

Saved ./parts/python3-test/python-packages/wheel-0.30.0-py2.py3-none-any.whl

Successfully downloaded wheel

Collecting wheel

Installing collected packages: wheel

Successfully installed wheel-0.30.0

Fetching and installing setuptools...

Collecting setuptools

Using cached setuptools-36.6.0-py2.py3-none-any.whl

Saved ./parts/python3-test/python-packages/setuptools-36.6.0-py2.py3-none-any.whl

Successfully downloaded setuptools

Collecting setuptools

Installing collected packages: setuptools

Successfully installed setuptools-36.6.0

Processing /tmp/tmpixpk5cpm/parts/python3-test/src

Link is a directory, ignoring download_dir

Successfully downloaded test

Preparing to build python2-test

Unpacking stage-packages to '/tmp/tmpixpk5cpm/parts/python2-test/install'

Building python2-test

Processing /tmp/tmpixpk5cpm/parts/python2-test/src

Building wheels for collected packages: test

Running setup.py bdist_wheel for test: started

Running setup.py bdist_wheel for test: finished with status 'done'

Stored in directory: /tmp/tmpzteud70j

Successfully built test

Collecting test

Installing collected packages: test

Successfully installed test-0.1

running install

running build

running build_py

creating build

creating build/lib.linux-x86_64-2.7

creating build/lib.linux-x86_64-2.7/python2_test_package

copying python2_test_package/__main__.py -> build/lib.linux-x86_64-2.7/python2_test_package

copying python2_test_package/__init__.py -> build/lib.linux-x86_64-2.7/python2_test_package

running install_lib

byte-compiling /tmp/tmpixpk5cpm/parts/python2-test/install/lib/python2.7/site-packages/python2_test_package/__main__.py to __main__.pyc

byte-compiling /tmp/tmpixpk5cpm/parts/python2-test/install/lib/python2.7/site-packages/python2_test_package/__init__.py to __init__.pyc

running install_egg_info

running egg_info

creating test.egg-info

writing test.egg-info/PKG-INFO

writing top-level names to test.egg-info/top_level.txt

writing dependency_links to test.egg-info/dependency_links.txt

writing entry points to test.egg-info/entry_points.txt

writing manifest file 'test.egg-info/SOURCES.txt'

reading manifest file 'test.egg-info/SOURCES.txt'

writing manifest file 'test.egg-info/SOURCES.txt'

Copying test.egg-info to /tmp/tmpixpk5cpm/parts/python2-test/install/lib/python2.7/site-packages/test-0.1-py2.7.egg-info

running install_scripts

Installing python2_test script to /tmp/tmpixpk5cpm/parts/python2-test/install/bin

writing list of installed files to 'install.txt'

/tmp/tmpixpk5cpm/parts/python2-test/install/usr/bin/python2 setup.py --no-user-cfg install --single-version-externally-managed --user --record install.txt

"GET /v2/snaps HTTP/1.1" 200 79

Preparing to build python-test

Unpacking stage-packages to '/tmp/tmpixpk5cpm/parts/python-test/install'

Building python-test

Processing /tmp/tmpixpk5cpm/parts/python-test/src

Building wheels for collected packages: test

Running setup.py bdist_wheel for test: started

Running setup.py bdist_wheel for test: finished with status 'done'

Stored in directory: /tmp/tmp2u8jb7g5

Successfully built test

Collecting test

Installing collected packages: test

Successfully installed test-0.1

running install

running build

running build_py

creating build

creating build/lib

creating build/lib/python3_test_package

copying python3_test_package/__main__.py -> build/lib/python3_test_package

copying python3_test_package/__init__.py -> build/lib/python3_test_package

running install_lib

byte-compiling /tmp/tmpixpk5cpm/parts/python-test/install/lib/python3.5/site-packages/python3_test_package/__main__.py to __main__.cpython-35.pyc

byte-compiling /tmp/tmpixpk5cpm/parts/python-test/install/lib/python3.5/site-packages/python3_test_package/__init__.py to __init__.cpython-35.pyc

running install_egg_info

running egg_info

creating test.egg-info

writing top-level names to test.egg-info/top_level.txt

writing entry points to test.egg-info/entry_points.txt

writing test.egg-info/PKG-INFO

writing dependency_links to test.egg-info/dependency_links.txt

writing manifest file 'test.egg-info/SOURCES.txt'

reading manifest file 'test.egg-info/SOURCES.txt'

writing manifest file 'test.egg-info/SOURCES.txt'

Copying test.egg-info to /tmp/tmpixpk5cpm/parts/python-test/install/lib/python3.5/site-packages/test-0.1-py3.5.egg-info

running install_scripts

Installing python3_test script to /tmp/tmpixpk5cpm/parts/python-test/install/bin

writing list of installed files to 'install.txt'

/tmp/tmpixpk5cpm/parts/python-test/install/usr/bin/python3 setup.py --no-user-cfg install --single-version-externally-managed --user --record install.txt

"GET /v2/snaps HTTP/1.1" 200 79

Preparing to build python3-test

Unpacking stage-packages to '/tmp/tmpixpk5cpm/parts/python3-test/install'

Building python3-test

Processing /tmp/tmpixpk5cpm/parts/python3-test/src

Building wheels for collected packages: test

Running setup.py bdist_wheel for test: started

Running setup.py bdist_wheel for test: finished with status 'done'

Stored in directory: /tmp/tmp5eop5v5y

Successfully built test

Collecting test

Installing collected packages: test

Successfully installed test-0.1

running install

running build

running build_py

creating build

creating build/lib

creating build/lib/python3_test_package

copying python3_test_package/__main__.py -> build/lib/python3_test_package

copying python3_test_package/__init__.py -> build/lib/python3_test_package

running install_lib

byte-compiling /tmp/tmpixpk5cpm/parts/python3-test/install/lib/python3.5/site-packages/python3_test_package/__main__.py to __main__.cpython-35.pyc

byte-compiling /tmp/tmpixpk5cpm/parts/python3-test/install/liTraceback (most recent call last):

File "/usr/lib/python3.5/runpy.py", line 184, in _run_module_as_main

"__main__", mod_spec)

File "/usr/lib/python3.5/runpy.py", line 85, in _run_code

exec(code, run_globals)

File "/usr/lib/python3.5/unittest/__main__.py", line 18, in <module>

main(module=None)

File "/usr/lib/python3.5/unittest/main.py", line 94, in __init__

self.runTests()

File "/usr/lib/python3.5/unittest/main.py", line 255, in runTests

self.result = testRunner.run(self.test)

File "/usr/lib/python3.5/unittest/runner.py", line 183, in run

result.printErrors()

File "/usr/lib/python3.5/unittest/runner.py", line 109, in printErrors

self.printErrorList('ERROR', self.errors)

File "/usr/lib/python3.5/unittest/runner.py", line 117, in printErrorList

self.stream.writeln("%s" % err)

File "/usr/lib/python3.5/unittest/runner.py", line 25, in writeln

self.write(arg)

BlockingIOError: [Errno 11] write could not complete without blocking

Revision history for this message

Kyle Fazzari (kyrofa) wrote on 2017-10-19:

#3

It's getting hard to get tests pass because of this one. I know subprocess uses nonblocking IO to get stdout/stderr, I suspect our issues can be traced to that somehow.

Kyle Fazzari (kyrofa) on 2017-10-20

Changed in snapcraft:
status:	New → Confirmed

Snapcraft

CI: BlockingIOError about 50% of the time

Bug Description

Other bug subscribers

Remote bug watches