1497317 - Intermittent Could not install python package: /builds/worker/workspace/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt...

Status: RESOLVED DUPLICATE of bug 1521378

(Testing :: Mochitest, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: aiakab [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=204048607&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/d6bhZcpbThqYBTGkqu3WMQ/runs/0/artifacts/public/logs/live_backing.log

Comment 1

[Arthur Iakab [arthur_iakab]]

 16:53:55     INFO - Installing None into virtualenv /builds/worker/workspace/build/venv
[task 2018-10-08T16:53:59.022Z] 16:53:59     INFO - retry: Calling run_command with args: [['/builds/worker/workspace/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'https://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f485b038490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3a9300>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c4f4bb8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3ab188>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c50cc60>, 'level': 'critical'}], 'cwd': '/builds/worker/workspace/build/tests/config', 'env': {'TASKCLUSTER_PORT_80_TCP_ADDR': '172.17.0.2', 'TASKCLUSTER_INSTANCE_TYPE': 'm3.large', 'TASKCLUSTER_WORKER_TYPE': 'gecko-t-linux-large', 'MOZ_AUTOMATION': '1', 'MOZ_SOURCE_CHANGESET': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOCHITEST_FLAVOR': 'plain', 'LOGNAME': 'worker', 'USER': 'worker', 'HOME': '/builds/worker', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/builds/worker/bin', 'DISPLAY': ':0', 'LANG': 'en_US.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/local/bin/node', 'RUN_ID': '0', 'HG_STORE_PATH': '/builds/worker/checkouts/hg-store', 'MOZILLA_BUILD_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/target.tar.bz2', 'TASKCLUSTER_PORT': 'tcp://172.17.0.2:80', 'MOZHARNESS_SCRIPT': 'desktop_unittest.py', 'GECKO_PATH': '/builds/worker/checkouts/gecko', 'TASKCLUSTER_NAME': '/epic_ardinghelli/taskcluster', 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/mozilla-inbound', 'SCCACHE_DISABLE': '1', 'TASKCLUSTER_PORT_80_TCP_PORT': '80', 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/mozilla-inbound', 'GECKO_HEAD_REV': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOZHARNESS_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/mozharness.zip', 'GECKO_BASE_REPOSITORY': 'https://hg.mozilla.org/mozilla-unified', 'LC_ALL': 'en_US.UTF-8', 'TASKCLUSTER_PORT_80_TCP_PROTO': 'tcp', 'TASK_ID': 'd6bhZcpbThqYBTGkqu3WMQ', 'TASKCLUSTER_PORT_80_TCP': 'tcp://172.17.0.2:80', 'OLDPWD': '/builds/worker/workspace', 'HOSTNAME': 'taskcluster-worker', 'SHLVL': '1', 'PWD': '/builds/worker/workspace', 'NEED_PULSEAUDIO': 'true', 'ENABLE_E10S': 'true', 'NEED_WINDOW_MANAGER': 'true', 'MOZHARNESS_CONFIG': 'unittests/linux_unittest.py remove_executables.py', 'TASKCLUSTER_WORKER_GROUP': 'us-west-1', 'TASKCLUSTER_PUBLIC_IP': '54.67.71.9'}}, attempt #1
[task 2018-10-08T16:53:59.022Z] 16:53:59     INFO - Running command: ['/builds/worker/workspace/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'https://pypi.pub.build.mozilla.org/pub'] in /builds/worker/workspace/build/tests/config
[task 2018-10-08T16:53:59.023Z] 16:53:59     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/pip install --timeout 120 -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt --no-index --find-links https://pypi.pub.build.mozilla.org/pub
[task 2018-10-08T16:53:59.023Z] 16:53:59     INFO - Using env: (same as previous command)
[task 2018-10-08T16:53:59.418Z] 16:53:59     INFO -  Ignoring indexes: https://pypi.python.org/simple
[task 2018-10-08T16:53:59.446Z] 16:53:59     INFO -  Processing /builds/worker/workspace/build/tests/tools/mozterm
[task 2018-10-08T16:53:59.702Z] 16:53:59     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozterm==1.0.0 from file:///builds/worker/workspace/build/tests/tools/mozterm in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 1))
[task 2018-10-08T16:53:59.704Z] 16:53:59     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/manifestparser
[task 2018-10-08T16:53:59.901Z] 16:53:59     INFO -    Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/worker/workspace/build/tests/mozbase/manifestparser in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 3))
[task 2018-10-08T16:53:59.904Z] 16:53:59     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozcrash
[task 2018-10-08T16:54:00.090Z] 16:54:00     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/worker/workspace/build/tests/mozbase/mozcrash in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 4))
[task 2018-10-08T16:54:00.094Z] 16:54:00     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozdebug
[task 2018-10-08T16:54:00.282Z] 16:54:00     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/worker/workspace/build/tests/mozbase/mozdebug in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 5))
[task 2018-10-08T16:54:00.284Z] 16:54:00     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozdevice
[task 2018-10-08T16:54:00.473Z] 16:54:00     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozdevice==1.1.2 from file:///builds/worker/workspace/build/tests/mozbase/mozdevice in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 6))
[task 2018-10-08T16:54:00.480Z] 16:54:00     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozfile
[task 2018-10-08T16:54:00.668Z] 16:54:00     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/worker/workspace/build/tests/mozbase/mozfile in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 7))
[task 2018-10-08T16:54:00.671Z] 16:54:00     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozhttpd
[task 2018-10-08T16:54:00.860Z] 16:54:00     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/worker/workspace/build/tests/mozbase/mozhttpd in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 8))
[task 2018-10-08T16:54:00.865Z] 16:54:00     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozinfo
[task 2018-10-08T16:54:01.053Z] 16:54:01     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozinfo==1.0.0 from file:///builds/worker/workspace/build/tests/mozbase/mozinfo in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 9))
[task 2018-10-08T16:54:01.055Z] 16:54:01     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozinstall
[task 2018-10-08T16:54:01.248Z] 16:54:01     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.16.0 from file:///builds/worker/workspace/build/tests/mozbase/mozinstall in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 10))
[task 2018-10-08T16:54:01.254Z] 16:54:01     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozleak
[task 2018-10-08T16:54:01.439Z] 16:54:01     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/worker/workspace/build/tests/mozbase/mozleak in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 11))
[task 2018-10-08T16:54:01.441Z] 16:54:01     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozlog
[task 2018-10-08T16:54:01.641Z] 16:54:01     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozlog==3.8 from file:///builds/worker/workspace/build/tests/mozbase/mozlog in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 12))
[task 2018-10-08T16:54:01.646Z] 16:54:01     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/moznetwork
[task 2018-10-08T16:54:01.834Z] 16:54:01     INFO -    Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/worker/workspace/build/tests/mozbase/moznetwork in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 13))
[task 2018-10-08T16:54:01.838Z] 16:54:01     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozprocess
[task 2018-10-08T16:54:02.029Z] 16:54:02     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.26 from file:///builds/worker/workspace/build/tests/mozbase/mozprocess in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 14))
[task 2018-10-08T16:54:02.031Z] 16:54:02     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozprofile
[task 2018-10-08T16:54:02.229Z] 16:54:02     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozprofile==2.0.0 from file:///builds/worker/workspace/build/tests/mozbase/mozprofile in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 15))
[task 2018-10-08T16:54:02.236Z] 16:54:02     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozrunner
[task 2018-10-08T16:54:02.431Z] 16:54:02     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozrunner==7.1.0 from file:///builds/worker/workspace/build/tests/mozbase/mozrunner in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 16))
[task 2018-10-08T16:54:02.443Z] 16:54:02     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozscreenshot
[task 2018-10-08T16:54:02.628Z] 16:54:02     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/worker/workspace/build/tests/mozbase/mozscreenshot in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 17))
[task 2018-10-08T16:54:02.632Z] 16:54:02     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/moztest
[task 2018-10-08T16:54:02.831Z] 16:54:02     INFO -    Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/worker/workspace/build/tests/mozbase/moztest in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 18))
[task 2018-10-08T16:54:02.833Z] 16:54:02     INFO -  Processing /builds/worker/workspace/build/tests/mozbase/mozversion
[task 2018-10-08T16:54:03.020Z] 16:54:03     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozversion==2.0.0 from file:///builds/worker/workspace/build/tests/mozbase/mozversion in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 19))
[task 2018-10-08T16:54:03.024Z] 16:54:03     INFO -  Processing /builds/worker/workspace/build/tests/tools/wptserve
[task 2018-10-08T16:54:03.225Z] 16:54:03     INFO -    Requirement already satisfied (use --upgrade to upgrade): wptserve==2.0 from file:///builds/worker/workspace/build/tests/tools/wptserve in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 3))
[task 2018-10-08T16:54:03.229Z] 16:54:03     INFO -  Processing /builds/worker/workspace/build/tests/tools/wpt_third_party/certifi
[task 2018-10-08T16:54:03.420Z] 16:54:03     INFO -    Requirement already satisfied (use --upgrade to upgrade): certifi==2018.4.16 from file:///builds/worker/workspace/build/tests/tools/wpt_third_party/certifi in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 4))
[task 2018-10-08T16:54:03.420Z] 16:54:03     INFO -  Processing /builds/worker/workspace/build/tests/tools/wpt_third_party/enum
[task 2018-10-08T16:54:03.614Z] 16:54:03     INFO -    Requirement already satisfied (use --upgrade to upgrade): enum34==1.1.6 from file:///builds/worker/workspace/build/tests/tools/wpt_third_party/enum in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 5))
[task 2018-10-08T16:54:03.614Z] 16:54:03     INFO -  Processing /builds/worker/workspace/build/tests/tools/wpt_third_party/h2
[task 2018-10-08T16:54:03.840Z] 16:54:03     INFO -    Requirement already satisfied (use --upgrade to upgrade): h2==3.0.1 from file:///builds/worker/workspace/build/tests/tools/wpt_third_party/h2 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 6))
[task 2018-10-08T16:54:03.847Z] 16:54:03     INFO -  Processing /builds/worker/workspace/build/tests/tools/wpt_third_party/hyperframe
[task 2018-10-08T16:54:04.038Z] 16:54:04     INFO -    Requirement already satisfied (use --upgrade to upgrade): hyperframe==5.1.0 from file:///builds/worker/workspace/build/tests/tools/wpt_third_party/hyperframe in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 7))
[task 2018-10-08T16:54:04.039Z] 16:54:04     INFO -  Processing /builds/worker/workspace/build/tests/tools/wpt_third_party/hpack
[task 2018-10-08T16:54:04.233Z] 16:54:04     INFO -    Requirement already satisfied (use --upgrade to upgrade): hpack==3.0.0 from file:///builds/worker/workspace/build/tests/tools/wpt_third_party/hpack in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 8))
[task 2018-10-08T16:54:04.235Z] 16:54:04     INFO -  Processing /builds/worker/workspace/build/tests/marionette/client
[task 2018-10-08T16:54:04.426Z] 16:54:04     INFO -    Requirement already satisfied (use --upgrade to upgrade): marionette-driver==2.7.0 from file:///builds/worker/workspace/build/tests/marionette/client in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 9))
[task 2018-10-08T16:54:04.431Z] 16:54:04     INFO -  Processing /builds/worker/workspace/build/tests/marionette/harness/marionette_harness/runner/mixins/browsermob-proxy-py
[task 2018-10-08T16:54:04.626Z] 16:54:04     INFO -    Requirement already satisfied (use --upgrade to upgrade): browsermob-proxy==0.6.0 from file:///builds/worker/workspace/build/tests/marionette/harness/marionette_harness/runner/mixins/browsermob-proxy-py in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 10))
[task 2018-10-08T16:54:04.637Z] 16:54:04     INFO -  Processing /builds/worker/workspace/build/tests/marionette/harness
[task 2018-10-08T16:54:04.859Z] 16:54:04     INFO -    Requirement already satisfied (use --upgrade to upgrade): marionette-harness==4.5.0 from file:///builds/worker/workspace/build/tests/marionette/harness in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 11))
[task 2018-10-08T16:54:04.878Z] 16:54:04     INFO -  Processing /builds/worker/workspace/build/tests/marionette/puppeteer/firefox
[task 2018-10-08T16:54:05.076Z] 16:54:05     INFO -    Requirement already satisfied (use --upgrade to upgrade): firefox-puppeteer==52.1.0 from file:///builds/worker/workspace/build/tests/marionette/puppeteer/firefox in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from -r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 14))
[task 2018-10-08T16:54:05.082Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from mozterm==1.0.0->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 1))
[task 2018-10-08T16:54:05.086Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 4))
[task 2018-10-08T16:54:05.090Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 4))
[task 2018-10-08T16:54:05.095Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 5))
[task 2018-10-08T16:54:05.099Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from mozdevice==1.1.2->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 6))
[task 2018-10-08T16:54:05.103Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from mozdevice==1.1.2->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 6))
[task 2018-10-08T16:54:05.106Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): requests in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from mozInstall==1.16.0->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 10))
[task 2018-10-08T16:54:05.124Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from mozlog==3.8->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 12))
[task 2018-10-08T16:54:05.128Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): h2==3.0.1 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from wptserve==2.0->-r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 3))
[task 2018-10-08T16:54:05.138Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): hyperframe<6,>=5.0 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from h2==3.0.1->-r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 6))
[task 2018-10-08T16:54:05.140Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): hpack<4,>=2.3 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from h2==3.0.1->-r /builds/worker/workspace/build/tests/config/marionette_requirements.txt (line 6))
[task 2018-10-08T16:54:05.143Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): chardet<3.1.0,>=3.0.2 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from requests->mozInstall==1.16.0->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 10))
[task 2018-10-08T16:54:05.146Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): urllib3<1.23,>=1.21.1 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from requests->mozInstall==1.16.0->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 10))
[task 2018-10-08T16:54:05.160Z] 16:54:05     INFO -  Requirement already satisfied (use --upgrade to upgrade): idna<2.6,>=2.5 in /builds/worker/workspace/build/venv/lib/python2.7/site-packages (from requests->mozInstall==1.16.0->-r /builds/worker/workspace/build/tests/config/mozbase_requirements.txt (line 10))
[task 2018-10-08T16:54:05.163Z] 16:54:05     INFO -  Building wheels for collected packages: browsermob-proxy
[task 2018-10-08T16:54:05.165Z] 16:54:05     INFO -    Running setup.py bdist_wheel for browsermob-proxy: started
[task 2018-10-08T16:54:05.399Z] 16:54:05     INFO -    Running setup.py bdist_wheel for browsermob-proxy: finished with status 'done'
[task 2018-10-08T16:54:05.401Z] 16:54:05     INFO -    Stored in directory: /builds/worker/.cache/pip/wheels/ce/da/90/f3874ec8a334da47e5cf86d509c4771b2054c236bf5c3ebda6
[task 2018-10-08T16:54:05.410Z] 16:54:05     INFO -  Successfully built browsermob-proxy
[task 2018-10-08T16:54:05.480Z] 16:54:05     INFO - Return code: 0
[task 2018-10-08T16:54:05.481Z] 16:54:05     INFO - Installing None into virtualenv /builds/worker/workspace/build/venv
[task 2018-10-08T16:54:23.056Z] 16:54:23     INFO - error resolving pypi.pub.build.mozilla.org (ignoring): 
[task 2018-10-08T16:54:23.057Z] 16:54:23     INFO - retry: Calling run_command with args: [['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f485b038490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3a9300>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c4f4bb8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3ab188>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c50cc60>, 'level': 'critical'}], 'cwd': '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge', 'env': {'TASKCLUSTER_PORT_80_TCP_ADDR': '172.17.0.2', 'TASKCLUSTER_INSTANCE_TYPE': 'm3.large', 'TASKCLUSTER_WORKER_TYPE': 'gecko-t-linux-large', 'MOZ_AUTOMATION': '1', 'MOZ_SOURCE_CHANGESET': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOCHITEST_FLAVOR': 'plain', 'LOGNAME': 'worker', 'USER': 'worker', 'HOME': '/builds/worker', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/builds/worker/bin', 'DISPLAY': ':0', 'LANG': 'en_US.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/local/bin/node', 'RUN_ID': '0', 'HG_STORE_PATH': '/builds/worker/checkouts/hg-store', 'MOZILLA_BUILD_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/target.tar.bz2', 'TASKCLUSTER_PORT': 'tcp://172.17.0.2:80', 'MOZHARNESS_SCRIPT': 'desktop_unittest.py', 'GECKO_PATH': '/builds/worker/checkouts/gecko', 'TASKCLUSTER_NAME': '/epic_ardinghelli/taskcluster', 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/mozilla-inbound', 'SCCACHE_DISABLE': '1', 'TASKCLUSTER_PORT_80_TCP_PORT': '80', 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/mozilla-inbound', 'GECKO_HEAD_REV': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOZHARNESS_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/mozharness.zip', 'GECKO_BASE_REPOSITORY': 'https://hg.mozilla.org/mozilla-unified', 'LC_ALL': 'en_US.UTF-8', 'TASKCLUSTER_PORT_80_TCP_PROTO': 'tcp', 'TASK_ID': 'd6bhZcpbThqYBTGkqu3WMQ', 'TASKCLUSTER_PORT_80_TCP': 'tcp://172.17.0.2:80', 'OLDPWD': '/builds/worker/workspace', 'HOSTNAME': 'taskcluster-worker', 'SHLVL': '1', 'PWD': '/builds/worker/workspace', 'NEED_PULSEAUDIO': 'true', 'ENABLE_E10S': 'true', 'NEED_WINDOW_MANAGER': 'true', 'MOZHARNESS_CONFIG': 'unittests/linux_unittest.py remove_executables.py', 'TASKCLUSTER_WORKER_GROUP': 'us-west-1', 'TASKCLUSTER_PUBLIC_IP': '54.67.71.9'}}, attempt #1
[task 2018-10-08T16:54:23.057Z] 16:54:23     INFO - Running command: ['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index'] in /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge
[task 2018-10-08T16:54:23.058Z] 16:54:23     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt --no-index
[task 2018-10-08T16:54:23.059Z] 16:54:23     INFO - Using env: (same as previous command)
[task 2018-10-08T16:54:23.461Z] 16:54:23     INFO -  Ignoring indexes: https://pypi.python.org/simple
[task 2018-10-08T16:54:23.480Z] 16:54:23     INFO -  Collecting six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T16:54:23.480Z] 16:54:23     INFO -    Could not find a version that satisfies the requirement six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2)) (from versions: )
[task 2018-10-08T16:54:23.480Z] 16:54:23     INFO -  No matching distribution found for six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T16:54:23.500Z] 16:54:23  WARNING - Return code: 1
[task 2018-10-08T16:54:23.515Z] 16:54:23     INFO - retry: Failed, sleeping 60 seconds before retrying
[task 2018-10-08T16:55:23.547Z] 16:55:23     INFO - retry: Calling run_command with args: [['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f485b038490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3a9300>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c4f4bb8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3ab188>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c50cc60>, 'level': 'critical'}], 'cwd': '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge', 'env': {'TASKCLUSTER_PORT_80_TCP_ADDR': '172.17.0.2', 'TASKCLUSTER_INSTANCE_TYPE': 'm3.large', 'TASKCLUSTER_WORKER_TYPE': 'gecko-t-linux-large', 'MOZ_AUTOMATION': '1', 'MOZ_SOURCE_CHANGESET': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOCHITEST_FLAVOR': 'plain', 'LOGNAME': 'worker', 'USER': 'worker', 'HOME': '/builds/worker', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/builds/worker/bin', 'DISPLAY': ':0', 'LANG': 'en_US.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/local/bin/node', 'RUN_ID': '0', 'HG_STORE_PATH': '/builds/worker/checkouts/hg-store', 'MOZILLA_BUILD_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/target.tar.bz2', 'TASKCLUSTER_PORT': 'tcp://172.17.0.2:80', 'MOZHARNESS_SCRIPT': 'desktop_unittest.py', 'GECKO_PATH': '/builds/worker/checkouts/gecko', 'TASKCLUSTER_NAME': '/epic_ardinghelli/taskcluster', 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/mozilla-inbound', 'SCCACHE_DISABLE': '1', 'TASKCLUSTER_PORT_80_TCP_PORT': '80', 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/mozilla-inbound', 'GECKO_HEAD_REV': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOZHARNESS_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/mozharness.zip', 'GECKO_BASE_REPOSITORY': 'https://hg.mozilla.org/mozilla-unified', 'LC_ALL': 'en_US.UTF-8', 'TASKCLUSTER_PORT_80_TCP_PROTO': 'tcp', 'TASK_ID': 'd6bhZcpbThqYBTGkqu3WMQ', 'TASKCLUSTER_PORT_80_TCP': 'tcp://172.17.0.2:80', 'OLDPWD': '/builds/worker/workspace', 'HOSTNAME': 'taskcluster-worker', 'SHLVL': '1', 'PWD': '/builds/worker/workspace', 'NEED_PULSEAUDIO': 'true', 'ENABLE_E10S': 'true', 'NEED_WINDOW_MANAGER': 'true', 'MOZHARNESS_CONFIG': 'unittests/linux_unittest.py remove_executables.py', 'TASKCLUSTER_WORKER_GROUP': 'us-west-1', 'TASKCLUSTER_PUBLIC_IP': '54.67.71.9'}}, attempt #2
[task 2018-10-08T16:55:23.547Z] 16:55:23     INFO - Running command: ['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index'] in /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge
[task 2018-10-08T16:55:23.547Z] 16:55:23     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt --no-index
[task 2018-10-08T16:55:23.547Z] 16:55:23     INFO - Using env: (same as previous command)
[task 2018-10-08T16:55:23.942Z] 16:55:23     INFO -  Ignoring indexes: https://pypi.python.org/simple
[task 2018-10-08T16:55:23.960Z] 16:55:23     INFO -  Collecting six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T16:55:23.960Z] 16:55:23     INFO -    Could not find a version that satisfies the requirement six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2)) (from versions: )
[task 2018-10-08T16:55:23.960Z] 16:55:23     INFO -  No matching distribution found for six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T16:55:23.978Z] 16:55:23  WARNING - Return code: 1
[task 2018-10-08T16:55:23.979Z] 16:55:23     INFO - retry: Failed, sleeping 120 seconds before retrying
[task 2018-10-08T16:57:24.037Z] 16:57:24     INFO - retry: Calling run_command with args: [['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f485b038490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3a9300>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c4f4bb8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3ab188>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c50cc60>, 'level': 'critical'}], 'cwd': '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge', 'env': {'TASKCLUSTER_PORT_80_TCP_ADDR': '172.17.0.2', 'TASKCLUSTER_INSTANCE_TYPE': 'm3.large', 'TASKCLUSTER_WORKER_TYPE': 'gecko-t-linux-large', 'MOZ_AUTOMATION': '1', 'MOZ_SOURCE_CHANGESET': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOCHITEST_FLAVOR': 'plain', 'LOGNAME': 'worker', 'USER': 'worker', 'HOME': '/builds/worker', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/builds/worker/bin', 'DISPLAY': ':0', 'LANG': 'en_US.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/local/bin/node', 'RUN_ID': '0', 'HG_STORE_PATH': '/builds/worker/checkouts/hg-store', 'MOZILLA_BUILD_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/target.tar.bz2', 'TASKCLUSTER_PORT': 'tcp://172.17.0.2:80', 'MOZHARNESS_SCRIPT': 'desktop_unittest.py', 'GECKO_PATH': '/builds/worker/checkouts/gecko', 'TASKCLUSTER_NAME': '/epic_ardinghelli/taskcluster', 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/mozilla-inbound', 'SCCACHE_DISABLE': '1', 'TASKCLUSTER_PORT_80_TCP_PORT': '80', 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/mozilla-inbound', 'GECKO_HEAD_REV': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOZHARNESS_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/mozharness.zip', 'GECKO_BASE_REPOSITORY': 'https://hg.mozilla.org/mozilla-unified', 'LC_ALL': 'en_US.UTF-8', 'TASKCLUSTER_PORT_80_TCP_PROTO': 'tcp', 'TASK_ID': 'd6bhZcpbThqYBTGkqu3WMQ', 'TASKCLUSTER_PORT_80_TCP': 'tcp://172.17.0.2:80', 'OLDPWD': '/builds/worker/workspace', 'HOSTNAME': 'taskcluster-worker', 'SHLVL': '1', 'PWD': '/builds/worker/workspace', 'NEED_PULSEAUDIO': 'true', 'ENABLE_E10S': 'true', 'NEED_WINDOW_MANAGER': 'true', 'MOZHARNESS_CONFIG': 'unittests/linux_unittest.py remove_executables.py', 'TASKCLUSTER_WORKER_GROUP': 'us-west-1', 'TASKCLUSTER_PUBLIC_IP': '54.67.71.9'}}, attempt #3
[task 2018-10-08T16:57:24.038Z] 16:57:24     INFO - Running command: ['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index'] in /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge
[task 2018-10-08T16:57:24.039Z] 16:57:24     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt --no-index
[task 2018-10-08T16:57:24.039Z] 16:57:24     INFO - Using env: (same as previous command)
[task 2018-10-08T16:57:24.444Z] 16:57:24     INFO -  Ignoring indexes: https://pypi.python.org/simple
[task 2018-10-08T16:57:24.463Z] 16:57:24     INFO -  Collecting six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T16:57:24.463Z] 16:57:24     INFO -    Could not find a version that satisfies the requirement six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2)) (from versions: )
[task 2018-10-08T16:57:24.464Z] 16:57:24     INFO -  No matching distribution found for six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T16:57:24.482Z] 16:57:24  WARNING - Return code: 1
[task 2018-10-08T16:57:24.483Z] 16:57:24     INFO - retry: Failed, sleeping 240 seconds before retrying
[task 2018-10-08T17:01:24.501Z] 17:01:24     INFO - retry: Calling run_command with args: [['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f485b038490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3a9300>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c4f4bb8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3ab188>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c50cc60>, 'level': 'critical'}], 'cwd': '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge', 'env': {'TASKCLUSTER_PORT_80_TCP_ADDR': '172.17.0.2', 'TASKCLUSTER_INSTANCE_TYPE': 'm3.large', 'TASKCLUSTER_WORKER_TYPE': 'gecko-t-linux-large', 'MOZ_AUTOMATION': '1', 'MOZ_SOURCE_CHANGESET': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOCHITEST_FLAVOR': 'plain', 'LOGNAME': 'worker', 'USER': 'worker', 'HOME': '/builds/worker', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/builds/worker/bin', 'DISPLAY': ':0', 'LANG': 'en_US.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/local/bin/node', 'RUN_ID': '0', 'HG_STORE_PATH': '/builds/worker/checkouts/hg-store', 'MOZILLA_BUILD_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/target.tar.bz2', 'TASKCLUSTER_PORT': 'tcp://172.17.0.2:80', 'MOZHARNESS_SCRIPT': 'desktop_unittest.py', 'GECKO_PATH': '/builds/worker/checkouts/gecko', 'TASKCLUSTER_NAME': '/epic_ardinghelli/taskcluster', 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/mozilla-inbound', 'SCCACHE_DISABLE': '1', 'TASKCLUSTER_PORT_80_TCP_PORT': '80', 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/mozilla-inbound', 'GECKO_HEAD_REV': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOZHARNESS_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/mozharness.zip', 'GECKO_BASE_REPOSITORY': 'https://hg.mozilla.org/mozilla-unified', 'LC_ALL': 'en_US.UTF-8', 'TASKCLUSTER_PORT_80_TCP_PROTO': 'tcp', 'TASK_ID': 'd6bhZcpbThqYBTGkqu3WMQ', 'TASKCLUSTER_PORT_80_TCP': 'tcp://172.17.0.2:80', 'OLDPWD': '/builds/worker/workspace', 'HOSTNAME': 'taskcluster-worker', 'SHLVL': '1', 'PWD': '/builds/worker/workspace', 'NEED_PULSEAUDIO': 'true', 'ENABLE_E10S': 'true', 'NEED_WINDOW_MANAGER': 'true', 'MOZHARNESS_CONFIG': 'unittests/linux_unittest.py remove_executables.py', 'TASKCLUSTER_WORKER_GROUP': 'us-west-1', 'TASKCLUSTER_PUBLIC_IP': '54.67.71.9'}}, attempt #4
[task 2018-10-08T17:01:24.502Z] 17:01:24     INFO - Running command: ['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index'] in /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge
[task 2018-10-08T17:01:24.502Z] 17:01:24     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt --no-index
[task 2018-10-08T17:01:24.503Z] 17:01:24     INFO - Using env: (same as previous command)
[task 2018-10-08T17:01:24.907Z] 17:01:24     INFO -  Ignoring indexes: https://pypi.python.org/simple
[task 2018-10-08T17:01:24.925Z] 17:01:24     INFO -  Collecting six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T17:01:24.927Z] 17:01:24     INFO -    Could not find a version that satisfies the requirement six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2)) (from versions: )
[task 2018-10-08T17:01:24.928Z] 17:01:24     INFO -  No matching distribution found for six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T17:01:24.944Z] 17:01:24  WARNING - Return code: 1
[task 2018-10-08T17:01:24.945Z] 17:01:24     INFO - retry: Failed, sleeping 300 seconds before retrying
[task 2018-10-08T17:06:24.965Z] 17:06:24     INFO - retry: Calling run_command with args: [['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f485b038490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3a9300>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c4f4bb8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c3ab188>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x7f485c50cc60>, 'level': 'critical'}], 'cwd': '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge', 'env': {'TASKCLUSTER_PORT_80_TCP_ADDR': '172.17.0.2', 'TASKCLUSTER_INSTANCE_TYPE': 'm3.large', 'TASKCLUSTER_WORKER_TYPE': 'gecko-t-linux-large', 'MOZ_AUTOMATION': '1', 'MOZ_SOURCE_CHANGESET': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOCHITEST_FLAVOR': 'plain', 'LOGNAME': 'worker', 'USER': 'worker', 'HOME': '/builds/worker', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/builds/worker/bin', 'DISPLAY': ':0', 'LANG': 'en_US.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/local/bin/node', 'RUN_ID': '0', 'HG_STORE_PATH': '/builds/worker/checkouts/hg-store', 'MOZILLA_BUILD_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/target.tar.bz2', 'TASKCLUSTER_PORT': 'tcp://172.17.0.2:80', 'MOZHARNESS_SCRIPT': 'desktop_unittest.py', 'GECKO_PATH': '/builds/worker/checkouts/gecko', 'TASKCLUSTER_NAME': '/epic_ardinghelli/taskcluster', 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/mozilla-inbound', 'SCCACHE_DISABLE': '1', 'TASKCLUSTER_PORT_80_TCP_PORT': '80', 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/integration/mozilla-inbound', 'GECKO_HEAD_REV': '6ea2076a39bc76c553d699bbe1ef1fa7e9e781d1', 'MOZHARNESS_URL': 'https://queue.taskcluster.net/v1/task/cAkJTe0lSOiu-_z4PBpazQ/artifacts/public/build/mozharness.zip', 'GECKO_BASE_REPOSITORY': 'https://hg.mozilla.org/mozilla-unified', 'LC_ALL': 'en_US.UTF-8', 'TASKCLUSTER_PORT_80_TCP_PROTO': 'tcp', 'TASK_ID': 'd6bhZcpbThqYBTGkqu3WMQ', 'TASKCLUSTER_PORT_80_TCP': 'tcp://172.17.0.2:80', 'OLDPWD': '/builds/worker/workspace', 'HOSTNAME': 'taskcluster-worker', 'SHLVL': '1', 'PWD': '/builds/worker/workspace', 'NEED_PULSEAUDIO': 'true', 'ENABLE_E10S': 'true', 'NEED_WINDOW_MANAGER': 'true', 'MOZHARNESS_CONFIG': 'unittests/linux_unittest.py remove_executables.py', 'TASKCLUSTER_WORKER_GROUP': 'us-west-1', 'TASKCLUSTER_PUBLIC_IP': '54.67.71.9'}}, attempt #5
[task 2018-10-08T17:06:24.965Z] 17:06:24     INFO - Running command: ['/builds/worker/workspace/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt', '--no-index'] in /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge
[task 2018-10-08T17:06:24.966Z] 17:06:24     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt --no-index
[task 2018-10-08T17:06:24.966Z] 17:06:24     INFO - Using env: (same as previous command)
[task 2018-10-08T17:06:25.367Z] 17:06:25     INFO -  Ignoring indexes: https://pypi.python.org/simple
[task 2018-10-08T17:06:25.386Z] 17:06:25     INFO -  Collecting six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T17:06:25.386Z] 17:06:25     INFO -    Could not find a version that satisfies the requirement six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2)) (from versions: )
[task 2018-10-08T17:06:25.386Z] 17:06:25     INFO -  No matching distribution found for six==1.10.0 (from -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt (line 2))
[task 2018-10-08T17:06:25.404Z] 17:06:25  WARNING - Return code: 1
[task 2018-10-08T17:06:25.405Z] 17:06:25    FATAL - Could not install python package: /builds/worker/workspace/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/worker/workspace/build/tests/mochitest/websocketprocessbridge/websocketprocessbridge_requirements.txt --no-index failed after 5 tries!
[task 2018-10-08T17:06:25.407Z] 17:06:25    FATAL - Running post_fatal callback...
[task 2018-10-08T17:06:25.408Z] 17:06:25    FATAL - Exiting -1
[task 2018-10-08T17:06:25.409Z] 17:06:25     INFO - Running post-action listener: _resource_record_post_action
[task 2018-10-08T17:06:25.411Z] 17:06:25     INFO - Running post-action listener: _start_resource_monitoring
[task 2018-10-08T17:06:25.435Z] 17:06:25     INFO - Starting resource monitoring.
[task 2018-10-08T17:06:25.447Z] 17:06:25     INFO - [mozharness: 2018-10-08 17:06:25.441958Z] Finished create-virtualenv step (failed)
[task 2018-10-08T17:06:25.447Z] 17:06:25     INFO - Running post-run listener: _resource_record_post_run
[task 2018-10-08T17:06:25.516Z] 17:06:25     INFO - Running post-run listener: copy_logs_to_upload_dir
[task 2018-10-08T17:06:25.517Z] 17:06:25     INFO - Copying logs to upload dir...
[task 2018-10-08T17:06:25.517Z] 17:06:25     INFO - mkdir: /builds/worker/workspace/build/upload/logs
[task 2018-10-08T17:06:25.540Z] cleanup
[task 2018-10-08T17:06:25.540Z] + cleanup
[task 2018-10-08T17:06:25.540Z] + local rv=255
[task 2018-10-08T17:06:25.540Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2018-10-08T17:06:25.541Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2018-10-08T17:06:25.544Z] + true
[task 2018-10-08T17:06:25.544Z] + cleanup_xvfb
[task 2018-10-08T17:06:25.545Z] pidof Xvfb
[task 2018-10-08T17:06:25.546Z] ++ pidof Xvfb
[task 2018-10-08T17:06:25.549Z] + local xvfb_pid=25
[task 2018-10-08T17:06:25.549Z] + local vnc=false
[task 2018-10-08T17:06:25.550Z] + local interactive=false
[task 2018-10-08T17:06:25.550Z] + '[' -n 25 ']'
[task 2018-10-08T17:06:25.550Z] + [[ false == false ]]
[task 2018-10-08T17:06:25.558Z] + [[ false == false ]]
[task 2018-10-08T17:06:25.558Z] + kill 25
[task 2018-10-08T17:06:25.560Z] XIO:  fatal IO error 11 (Resource temporarily unavailable) on X server ":0"
[task 2018-10-08T17:06:25.560Z]       after 795 requests (795 known processed) with 4 events remaining.
[task 2018-10-08T17:06:25.560Z] + screen -XS xvfb quit
[task 2018-10-08T17:06:25.561Z] compizconfig - Info: Backend     : ini
[task 2018-10-08T17:06:25.563Z] compizconfig - Info: Integration : true
[task 2018-10-08T17:06:25.563Z] compizconfig - Info: Profile     : default
[task 2018-10-08T17:06:25.778Z] No screen session found.
[task 2018-10-08T17:06:25.779Z] + true
[task 2018-10-08T17:06:25.780Z] + exit 255
[taskcluster 2018-10-08 17:06:26.084Z] === Task Finished ===
[taskcluster 2018-10-08 17:06:31.267Z] Unsuccessful task run with exit code: 255 completed in 981.918 seconds

Comment 2

[Intermittent Failures Robot]

2 failures in 2740 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 1
* mozilla-inbound: 1

Platform breakdown:
* linux32: 1
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1497317&startday=2018-10-08&endday=2018-10-14&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 3007 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1497317&startday=2018-11-05&endday=2018-11-11&tree=all

Comment 4

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 5

[Cristian Brindusan [:cbrindusan]]

Recent log failure:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=220910103&repo=autoland&lineNumber=1190

Comment 6

[Intermittent Failures Robot]

1 failures in 2627 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1497317&startday=2019-01-07&endday=2019-01-13&tree=all