builder: mozilla-beta_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-055
starttime: 1509645568.87
results: success (0)
revision: 73e0d89a540f1568387efa0e145e7853f9badb10
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:28.871138) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:28.871633) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:28.872107) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:28.919864) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:28.920279) =========
bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-02 10:59:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 45.6M=0s
2017-11-02 10:59:29 (45.6 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.182979
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:29.161701) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:29.162281) =========
rm -rf scripts properties
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.021723
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:29.214101) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-02 10:59:29.214455) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 73e0d89a540f1568387efa0e145e7853f9badb10 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 73e0d89a540f1568387efa0e145e7853f9badb10 --destination scripts --debug']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-02 10:59:29,265 truncating revision to first 12 chars
2017-11-02 10:59:29,265 Setting DEBUG logging.
2017-11-02 10:59:29,265 attempt 1/10
2017-11-02 10:59:29,266 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/73e0d89a540f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-02 10:59:30,498 unpacking tar archive at: mozilla-beta-73e0d89a540f/testing/mozharness/
program finished with exit code 0
elapsedTime=1.959979
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-02 10:59:31.194420) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:31.194735) =========
script_repo_revision: 73e0d89a540f1568387efa0e145e7853f9badb10
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:31.195119) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:31.195415) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-02 10:59:31.209748) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 50 secs) (at 2017-11-02 10:59:31.210156) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
10:59:31 INFO - MultiFileLogger online at 20171102 10:59:31 in /builds/slave/test
10:59:31 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
10:59:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
10:59:31 INFO - {'append_to_log': False,
10:59:31 INFO - 'base_work_dir': '/builds/slave/test',
10:59:31 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO',
10:59:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
10:59:31 INFO - 'branch': 'Mozilla-Beta-Non-PGO',
10:59:31 INFO - 'buildbot_json_path': 'buildprops.json',
10:59:31 INFO - 'code_coverage': False,
10:59:31 INFO - 'config_files': ('talos/linux_config.py',),
10:59:31 INFO - 'default_actions': ('clobber',
10:59:31 INFO - 'read-buildbot-config',
10:59:31 INFO - 'download-and-extract',
10:59:31 INFO - 'populate-webroot',
10:59:31 INFO - 'create-virtualenv',
10:59:31 INFO - 'install',
10:59:31 INFO - 'setup-mitmproxy',
10:59:31 INFO - 'run-tests'),
10:59:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
10:59:31 INFO - 'disable_ccov_upload': False,
10:59:31 INFO - 'disable_stylo': False,
10:59:31 INFO - 'download_minidump_stackwalk': True,
10:59:31 INFO - 'download_symbols': 'ondemand',
10:59:31 INFO - 'e10s': False,
10:59:31 INFO - 'enable_stylo': False,
10:59:31 INFO - 'enable_webrender': False,
10:59:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
10:59:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
10:59:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
10:59:31 INFO - 'gecko_profile': False,
10:59:31 INFO - 'gecko_profile_interval': 0,
10:59:31 INFO - 'installer_path': 'installer.exe',
10:59:31 INFO - 'jsd_code_coverage': False,
10:59:31 INFO - 'log_level': 'info',
10:59:31 INFO - 'log_name': 'talos',
10:59:31 INFO - 'log_to_console': True,
10:59:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
10:59:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
10:59:31 INFO - 'opt_config_files': (),
10:59:31 INFO - 'pip_index': False,
10:59:31 INFO - 'suite': 'g4-e10s',
10:59:31 INFO - 'system_bits': '32',
10:59:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
10:59:31 INFO - 'title': 'talos-linux64-ix-055',
10:59:31 INFO - 'tooltool_cache': '/builds/tooltool_cache',
10:59:31 INFO - 'use_talos_json': True,
10:59:31 INFO - 'verify': 'False',
10:59:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
10:59:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
10:59:31 INFO - 'work_dir': 'build'}
10:59:31 INFO - [mozharness: 2017-11-02 17:59:31.476533Z] Running clobber step.
10:59:31 INFO - Running pre-action listener: _resource_record_pre_action
10:59:31 INFO - Running main action method: clobber
10:59:31 INFO - rmtree: /builds/slave/test/build
10:59:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
10:59:32 INFO - Running post-action listener: _resource_record_post_action
10:59:32 INFO - [mozharness: 2017-11-02 17:59:32.481124Z] Finished clobber step (success)
10:59:32 INFO - [mozharness: 2017-11-02 17:59:32.481220Z] Running read-buildbot-config step.
10:59:32 INFO - Running pre-action listener: _resource_record_pre_action
10:59:32 INFO - Running main action method: read_buildbot_config
10:59:32 INFO - Using buildbot properties:
10:59:32 INFO - {
10:59:32 INFO - "project": "",
10:59:32 INFO - "product": "firefox",
10:59:32 INFO - "who": "jlorenzo@mozilla.com",
10:59:32 INFO - "installer_path": "public/build/target.tar.bz2",
10:59:32 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-g4-e10s",
10:59:32 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta",
10:59:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos g4-e10s",
10:59:32 INFO - "stage_platform": "linux64",
10:59:32 INFO - "basedir": "/builds/slave/test",
10:59:32 INFO - "buildnumber": 170,
10:59:32 INFO - "platform": "ubuntu64_hw",
10:59:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
10:59:32 INFO - "slavebuilddir": "test",
10:59:32 INFO - "taskId": "AIihiXzKQTOfdqqmM6quxA",
10:59:32 INFO - "branch": "mozilla-beta",
10:59:32 INFO - "script_repo_revision": "production",
10:59:32 INFO - "revision": "73e0d89a540f1568387efa0e145e7853f9badb10",
10:59:32 INFO - "slavename": "talos-linux64-ix-055",
10:59:32 INFO - "repo_path": "releases/mozilla-beta"
10:59:32 INFO - }
10:59:32 INFO - Finding installer, test and symbols from parent task.
10:59:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AIihiXzKQTOfdqqmM6quxA'}, attempt #1
10:59:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AIihiXzKQTOfdqqmM6quxA'}, attempt #1
10:59:33 INFO - Task dependencies: VE2Z-IYtS8qxN9Eev0MzMw
10:59:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw'}, attempt #1
10:59:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.tar.bz2
10:59:33 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.test_packages.json
10:59:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.crashreporter-symbols.zip
10:59:33 INFO - Running post-action listener: _resource_record_post_action
10:59:33 INFO - [mozharness: 2017-11-02 17:59:33.816852Z] Finished read-buildbot-config step (success)
10:59:33 INFO - [mozharness: 2017-11-02 17:59:33.817071Z] Running download-and-extract step.
10:59:33 INFO - Running pre-action listener: _resource_record_pre_action
10:59:33 INFO - Running main action method: download_and_extract
10:59:33 INFO - mkdir: /builds/slave/test/build/tests
10:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:33 INFO - trying https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.test_packages.json
10:59:33 INFO - Downloading https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
10:59:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
10:59:34 INFO - Downloaded 989 bytes.
10:59:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
10:59:34 INFO - Using the following test package requirements:
10:59:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
10:59:34 INFO - u'common': [u'target.common.tests.zip'],
10:59:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
10:59:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
10:59:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
10:59:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
10:59:34 INFO - u'mozbase': [u'target.common.tests.zip'],
10:59:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
10:59:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
10:59:34 INFO - u'web-platform': [u'target.common.tests.zip',
10:59:34 INFO - u'target.web-platform.tests.tar.gz'],
10:59:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
10:59:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
10:59:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.common.tests.zip
10:59:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.common.tests.zip'}, attempt #1
10:59:34 INFO - Fetch https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.common.tests.zip into memory
10:59:35 INFO - Content-Length response header: 56667284
10:59:35 INFO - Bytes received: 56667284
10:59:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.talos.tests.zip
10:59:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
10:59:47 INFO - Fetch https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.talos.tests.zip into memory
10:59:47 INFO - Content-Length response header: 18771919
10:59:47 INFO - Bytes received: 18771919
10:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:48 INFO - trying https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.tar.bz2
10:59:48 INFO - Downloading https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
10:59:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
10:59:49 INFO - Downloaded 54348206 bytes.
10:59:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.tar.bz2
10:59:49 INFO - mkdir: /builds/slave/test/properties
10:59:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
10:59:49 INFO - Writing to file /builds/slave/test/properties/build_url
10:59:49 INFO - Contents:
10:59:49 INFO - build_url:https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.tar.bz2
10:59:49 INFO - Running post-action listener: _resource_record_post_action
10:59:49 INFO - Running post-action listener: find_tests_for_verification
10:59:49 INFO - Running post-action listener: set_extra_try_arguments
10:59:49 INFO - [mozharness: 2017-11-02 17:59:49.371285Z] Finished download-and-extract step (success)
10:59:49 INFO - [mozharness: 2017-11-02 17:59:49.371428Z] Running populate-webroot step.
10:59:49 INFO - Running pre-action listener: _resource_record_pre_action
10:59:49 INFO - Running main action method: populate_webroot
10:59:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
10:59:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['tresize',
10:59:49 INFO - 'tcanvasmark']},
10:59:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
10:59:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['dromaeo_css',
10:59:49 INFO - 'kraken']},
10:59:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
10:59:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
10:59:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'tests': ['damp', 'tps']},
10:59:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['damp', 'tps']},
10:59:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
10:59:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['dromaeo_dom']},
10:59:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
10:59:49 INFO - 'glvideo',
10:59:49 INFO - 'displaylist_mutate']},
10:59:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['basic_compositor_video',
10:59:49 INFO - 'glvideo']},
10:59:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
10:59:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['ts_paint_webext',
10:59:49 INFO - 'tp5o_webext']},
10:59:49 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
10:59:49 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:59:49 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:59:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:59:49 INFO - 'talos_options': ['--mitmproxy',
10:59:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:59:49 INFO - '--firstNonBlankPaint'],
10:59:49 INFO - 'tests': ['tp6_google_heavy',
10:59:49 INFO - 'tp6_youtube_heavy',
10:59:49 INFO - 'tp6_amazon_heavy',
10:59:49 INFO - 'tp6_facebook_heavy']},
10:59:49 INFO - 'other-e10s': {'tests': ['a11yr',
10:59:49 INFO - 'ts_paint',
10:59:49 INFO - 'tpaint',
10:59:49 INFO - 'sessionrestore',
10:59:49 INFO - 'sessionrestore_many_windows',
10:59:49 INFO - 'sessionrestore_no_auto_restore',
10:59:49 INFO - 'tabpaint',
10:59:49 INFO - 'cpstartup']},
10:59:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['a11yr',
10:59:49 INFO - 'ts_paint',
10:59:49 INFO - 'tpaint',
10:59:49 INFO - 'sessionrestore',
10:59:49 INFO - 'sessionrestore_many_windows',
10:59:49 INFO - 'sessionrestore_no_auto_restore',
10:59:49 INFO - 'tabpaint',
10:59:49 INFO - 'cpstartup']},
10:59:49 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
10:59:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
10:59:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['perf_reftest_singletons']},
10:59:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['perf_reftest']},
10:59:49 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
10:59:49 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['speedometer']},
10:59:49 INFO - 'svgr-e10s': {'tests': ['tsvgx',
10:59:49 INFO - 'tsvgr_opacity',
10:59:49 INFO - 'tart',
10:59:49 INFO - 'tscrollx',
10:59:49 INFO - 'tsvg_static']},
10:59:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['tsvgx',
10:59:49 INFO - 'tsvgr_opacity',
10:59:49 INFO - 'tart',
10:59:49 INFO - 'tscrollx',
10:59:49 INFO - 'tsvg_static']},
10:59:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
10:59:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'talos_options': ['--disable-stylo'],
10:59:49 INFO - 'tests': ['tp5o']},
10:59:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:59:49 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:59:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:59:49 INFO - 'talos_options': ['--mitmproxy',
10:59:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:59:49 INFO - '--firstNonBlankPaint'],
10:59:49 INFO - 'tests': ['tp6_google',
10:59:49 INFO - 'tp6_youtube',
10:59:49 INFO - 'tp6_amazon',
10:59:49 INFO - 'tp6_facebook']},
10:59:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:59:49 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:59:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:59:49 INFO - 'talos_options': ['--disable-stylo',
10:59:49 INFO - '--mitmproxy',
10:59:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:59:49 INFO - '--firstNonBlankPaint'],
10:59:49 INFO - 'tests': ['tp6_google',
10:59:49 INFO - 'tp6_youtube',
10:59:49 INFO - 'tp6_amazon',
10:59:49 INFO - 'tp6_facebook']},
10:59:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:59:49 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:59:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:59:49 INFO - 'talos_options': ['--stylo-threads=1',
10:59:49 INFO - '--mitmproxy',
10:59:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:59:49 INFO - '--firstNonBlankPaint'],
10:59:49 INFO - 'tests': ['tp6_google',
10:59:49 INFO - 'tp6_youtube',
10:59:49 INFO - 'tp6_amazon',
10:59:49 INFO - 'tp6_facebook']},
10:59:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'talos_options': ['--xperf_path',
10:59:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:59:49 INFO - 'tests': ['tp5n']},
10:59:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:59:49 INFO - 'talos_options': ['--disable-stylo',
10:59:49 INFO - '--xperf_path',
10:59:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:59:49 INFO - 'tests': ['tp5n']}}}
10:59:49 INFO - Running post-action listener: _resource_record_post_action
10:59:49 INFO - [mozharness: 2017-11-02 17:59:49.500805Z] Finished populate-webroot step (success)
10:59:49 INFO - [mozharness: 2017-11-02 17:59:49.500899Z] Running create-virtualenv step.
10:59:49 INFO - Running pre-action listener: _resource_record_pre_action
10:59:49 INFO - Running main action method: create_virtualenv
10:59:49 INFO - Creating virtualenv /builds/slave/test/build/venv
10:59:49 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
10:59:49 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
10:59:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
10:59:51 INFO - Using real prefix '/usr'
10:59:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python
10:59:52 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
10:59:53 INFO - Installing setuptools, pip, wheel...done.
10:59:53 INFO - Return code: 0
10:59:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
10:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:59:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
10:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
10:59:53 INFO - Using env: {'DISPLAY': ':0',
10:59:53 INFO - 'HOME': '/home/cltbld',
10:59:53 INFO - 'LANG': 'en_US.UTF-8',
10:59:53 INFO - 'LANGUAGE': 'en_US:en',
10:59:53 INFO - 'LOGNAME': 'cltbld',
10:59:53 INFO - 'MAIL': '/var/mail/cltbld',
10:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:59:53 INFO - 'MOZ_NO_REMOTE': '1',
10:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:59:53 INFO - 'NO_EM_RESTART': '1',
10:59:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:59:53 INFO - 'PWD': '/builds/slave/test',
10:59:53 INFO - 'SHELL': '/bin/bash',
10:59:53 INFO - 'SHLVL': '1',
10:59:53 INFO - 'TERM': 'linux',
10:59:53 INFO - 'TMOUT': '86400',
10:59:53 INFO - 'USER': 'cltbld',
10:59:53 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
10:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:59:53 INFO - '_': '/tools/buildbot/bin/python'}
10:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple
10:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
10:59:54 INFO - Return code: 0
10:59:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
10:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:59:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:59:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
10:59:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
10:59:54 INFO - Using env: {'DISPLAY': ':0',
10:59:54 INFO - 'HOME': '/home/cltbld',
10:59:54 INFO - 'LANG': 'en_US.UTF-8',
10:59:54 INFO - 'LANGUAGE': 'en_US:en',
10:59:54 INFO - 'LOGNAME': 'cltbld',
10:59:54 INFO - 'MAIL': '/var/mail/cltbld',
10:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:59:54 INFO - 'MOZ_NO_REMOTE': '1',
10:59:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:59:54 INFO - 'NO_EM_RESTART': '1',
10:59:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:59:54 INFO - 'PWD': '/builds/slave/test',
10:59:54 INFO - 'SHELL': '/bin/bash',
10:59:54 INFO - 'SHLVL': '1',
10:59:54 INFO - 'TERM': 'linux',
10:59:54 INFO - 'TMOUT': '86400',
10:59:54 INFO - 'USER': 'cltbld',
10:59:54 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
10:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:59:54 INFO - '_': '/tools/buildbot/bin/python'}
10:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple
10:59:54 INFO - Collecting psutil>=3.1.1
10:59:55 INFO - Installing collected packages: psutil
10:59:55 INFO - Successfully installed psutil-3.1.1
10:59:55 INFO - Return code: 0
10:59:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
10:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:59:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
10:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
10:59:55 INFO - Using env: {'DISPLAY': ':0',
10:59:55 INFO - 'HOME': '/home/cltbld',
10:59:55 INFO - 'LANG': 'en_US.UTF-8',
10:59:55 INFO - 'LANGUAGE': 'en_US:en',
10:59:55 INFO - 'LOGNAME': 'cltbld',
10:59:55 INFO - 'MAIL': '/var/mail/cltbld',
10:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:59:55 INFO - 'MOZ_NO_REMOTE': '1',
10:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:59:55 INFO - 'NO_EM_RESTART': '1',
10:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:59:55 INFO - 'PWD': '/builds/slave/test',
10:59:55 INFO - 'SHELL': '/bin/bash',
10:59:55 INFO - 'SHLVL': '1',
10:59:55 INFO - 'TERM': 'linux',
10:59:55 INFO - 'TMOUT': '86400',
10:59:55 INFO - 'USER': 'cltbld',
10:59:55 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
10:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:59:55 INFO - '_': '/tools/buildbot/bin/python'}
10:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple
10:59:56 INFO - Collecting mozsystemmonitor==0.3
10:59:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
10:59:57 INFO - Installing collected packages: mozsystemmonitor
10:59:57 INFO - Successfully installed mozsystemmonitor-0.3
10:59:57 INFO - Return code: 0
10:59:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
10:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:59:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:59:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
10:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
10:59:57 INFO - Using env: {'DISPLAY': ':0',
10:59:57 INFO - 'HOME': '/home/cltbld',
10:59:57 INFO - 'LANG': 'en_US.UTF-8',
10:59:57 INFO - 'LANGUAGE': 'en_US:en',
10:59:57 INFO - 'LOGNAME': 'cltbld',
10:59:57 INFO - 'MAIL': '/var/mail/cltbld',
10:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:59:57 INFO - 'MOZ_NO_REMOTE': '1',
10:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:59:57 INFO - 'NO_EM_RESTART': '1',
10:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:59:57 INFO - 'PWD': '/builds/slave/test',
10:59:57 INFO - 'SHELL': '/bin/bash',
10:59:57 INFO - 'SHLVL': '1',
10:59:57 INFO - 'TERM': 'linux',
10:59:57 INFO - 'TMOUT': '86400',
10:59:57 INFO - 'USER': 'cltbld',
10:59:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
10:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:59:57 INFO - '_': '/tools/buildbot/bin/python'}
10:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple
10:59:57 INFO - Collecting jsonschema==2.5.1
10:59:59 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
10:59:59 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
11:00:00 INFO - Installing collected packages: functools32, jsonschema
11:00:00 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
11:00:00 INFO - Return code: 0
11:00:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
11:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:00:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
11:00:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
11:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
11:00:00 INFO - Using env: {'DISPLAY': ':0',
11:00:00 INFO - 'HOME': '/home/cltbld',
11:00:00 INFO - 'LANG': 'en_US.UTF-8',
11:00:00 INFO - 'LANGUAGE': 'en_US:en',
11:00:00 INFO - 'LOGNAME': 'cltbld',
11:00:00 INFO - 'MAIL': '/var/mail/cltbld',
11:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:00:00 INFO - 'MOZ_NO_REMOTE': '1',
11:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:00:00 INFO - 'NO_EM_RESTART': '1',
11:00:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:00:00 INFO - 'PWD': '/builds/slave/test',
11:00:00 INFO - 'SHELL': '/bin/bash',
11:00:00 INFO - 'SHLVL': '1',
11:00:00 INFO - 'TERM': 'linux',
11:00:00 INFO - 'TMOUT': '86400',
11:00:00 INFO - 'USER': 'cltbld',
11:00:00 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:00:00 INFO - '_': '/tools/buildbot/bin/python'}
11:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple
11:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
11:00:00 INFO - Return code: 0
11:00:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
11:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:00:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:00:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
11:00:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
11:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
11:00:00 INFO - Using env: {'DISPLAY': ':0',
11:00:00 INFO - 'HOME': '/home/cltbld',
11:00:00 INFO - 'LANG': 'en_US.UTF-8',
11:00:00 INFO - 'LANGUAGE': 'en_US:en',
11:00:00 INFO - 'LOGNAME': 'cltbld',
11:00:00 INFO - 'MAIL': '/var/mail/cltbld',
11:00:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:00:00 INFO - 'MOZ_NO_REMOTE': '1',
11:00:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:00:00 INFO - 'NO_EM_RESTART': '1',
11:00:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:00:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:00:00 INFO - 'PWD': '/builds/slave/test',
11:00:00 INFO - 'SHELL': '/bin/bash',
11:00:00 INFO - 'SHLVL': '1',
11:00:00 INFO - 'TERM': 'linux',
11:00:00 INFO - 'TMOUT': '86400',
11:00:00 INFO - 'USER': 'cltbld',
11:00:00 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:00:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:00:00 INFO - '_': '/tools/buildbot/bin/python'}
11:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple
11:00:01 INFO - Collecting blobuploader==1.2.4
11:00:02 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
11:00:03 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
11:00:04 INFO - Installing collected packages: requests, docopt, blobuploader
11:00:04 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
11:00:04 INFO - Return code: 0
11:00:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:00:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
11:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
11:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
11:00:04 INFO - Using env: {'DISPLAY': ':0',
11:00:04 INFO - 'HOME': '/home/cltbld',
11:00:04 INFO - 'LANG': 'en_US.UTF-8',
11:00:04 INFO - 'LANGUAGE': 'en_US:en',
11:00:04 INFO - 'LOGNAME': 'cltbld',
11:00:04 INFO - 'MAIL': '/var/mail/cltbld',
11:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:00:04 INFO - 'MOZ_NO_REMOTE': '1',
11:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:00:04 INFO - 'NO_EM_RESTART': '1',
11:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:00:04 INFO - 'PWD': '/builds/slave/test',
11:00:04 INFO - 'SHELL': '/bin/bash',
11:00:04 INFO - 'SHLVL': '1',
11:00:04 INFO - 'TERM': 'linux',
11:00:04 INFO - 'TMOUT': '86400',
11:00:04 INFO - 'USER': 'cltbld',
11:00:04 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:00:04 INFO - '_': '/tools/buildbot/bin/python'}
11:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple
11:00:04 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
11:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
11:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
11:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
11:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
11:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
11:00:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
11:00:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
11:00:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
11:00:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
11:00:06 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
11:00:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
11:00:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
11:00:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
11:00:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
11:00:07 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
11:00:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
11:00:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
11:00:08 INFO - Running setup.py install for manifestparser: started
11:00:08 INFO - Running setup.py install for manifestparser: finished with status 'done'
11:00:08 INFO - Running setup.py install for mozcrash: started
11:00:08 INFO - Running setup.py install for mozcrash: finished with status 'done'
11:00:08 INFO - Running setup.py install for mozdebug: started
11:00:08 INFO - Running setup.py install for mozdebug: finished with status 'done'
11:00:08 INFO - Running setup.py install for mozdevice: started
11:00:09 INFO - Running setup.py install for mozdevice: finished with status 'done'
11:00:09 INFO - Running setup.py install for mozfile: started
11:00:09 INFO - Running setup.py install for mozfile: finished with status 'done'
11:00:09 INFO - Running setup.py install for mozhttpd: started
11:00:09 INFO - Running setup.py install for mozhttpd: finished with status 'done'
11:00:09 INFO - Running setup.py install for mozinfo: started
11:00:09 INFO - Running setup.py install for mozinfo: finished with status 'done'
11:00:09 INFO - Running setup.py install for mozInstall: started
11:00:09 INFO - Running setup.py install for mozInstall: finished with status 'done'
11:00:10 INFO - Running setup.py install for mozleak: started
11:00:10 INFO - Running setup.py install for mozleak: finished with status 'done'
11:00:10 INFO - Running setup.py install for mozlog: started
11:00:10 INFO - Running setup.py install for mozlog: finished with status 'done'
11:00:10 INFO - Running setup.py install for moznetwork: started
11:00:10 INFO - Running setup.py install for moznetwork: finished with status 'done'
11:00:10 INFO - Running setup.py install for mozprocess: started
11:00:10 INFO - Running setup.py install for mozprocess: finished with status 'done'
11:00:10 INFO - Running setup.py install for mozprofile: started
11:00:11 INFO - Running setup.py install for mozprofile: finished with status 'done'
11:00:11 INFO - Running setup.py install for mozrunner: started
11:00:11 INFO - Running setup.py install for mozrunner: finished with status 'done'
11:00:11 INFO - Running setup.py install for mozscreenshot: started
11:00:11 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
11:00:11 INFO - Running setup.py install for moztest: started
11:00:11 INFO - Running setup.py install for moztest: finished with status 'done'
11:00:11 INFO - Running setup.py install for mozversion: started
11:00:12 INFO - Running setup.py install for mozversion: finished with status 'done'
11:00:12 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
11:00:12 INFO - Return code: 0
11:00:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
11:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
11:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
11:00:12 INFO - Using env: {'DISPLAY': ':0',
11:00:12 INFO - 'HOME': '/home/cltbld',
11:00:12 INFO - 'LANG': 'en_US.UTF-8',
11:00:12 INFO - 'LANGUAGE': 'en_US:en',
11:00:12 INFO - 'LOGNAME': 'cltbld',
11:00:12 INFO - 'MAIL': '/var/mail/cltbld',
11:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:00:12 INFO - 'MOZ_NO_REMOTE': '1',
11:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:00:12 INFO - 'NO_EM_RESTART': '1',
11:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:00:12 INFO - 'PWD': '/builds/slave/test',
11:00:12 INFO - 'SHELL': '/bin/bash',
11:00:12 INFO - 'SHLVL': '1',
11:00:12 INFO - 'TERM': 'linux',
11:00:12 INFO - 'TMOUT': '86400',
11:00:12 INFO - 'USER': 'cltbld',
11:00:12 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:00:12 INFO - '_': '/tools/buildbot/bin/python'}
11:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple
11:00:12 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
11:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
11:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
11:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
11:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
11:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
11:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
11:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
11:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
11:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
11:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
11:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
11:00:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
11:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
11:00:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
11:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
11:00:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
11:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
11:00:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
11:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
11:00:14 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
11:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
11:00:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
11:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
11:00:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
11:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
11:00:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
11:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
11:00:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
11:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
11:00:15 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
11:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
11:00:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
11:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
11:00:16 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
11:00:17 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
11:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
11:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
11:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
11:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
11:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
11:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
11:00:17 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
11:00:18 INFO - Installing collected packages: six, blessings
11:00:18 INFO - Successfully installed blessings-1.6 six-1.10.0
11:00:18 INFO - Return code: 0
11:00:18 INFO - Done creating virtualenv /builds/slave/test/build/venv.
11:00:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
11:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
11:00:19 INFO - Reading from file tmpfile_stdout
11:00:19 INFO - Reading from file tmpfile_stderr
11:00:19 INFO - Current package versions:
11:00:19 INFO - blessings == 1.6
11:00:19 INFO - blobuploader == 1.2.4
11:00:19 INFO - docopt == 0.6.1
11:00:19 INFO - functools32 == 3.2.3.post2
11:00:19 INFO - jsonschema == 2.5.1
11:00:19 INFO - manifestparser == 1.2
11:00:19 INFO - mozInstall == 1.14
11:00:19 INFO - mozcrash == 1.0
11:00:19 INFO - mozdebug == 0.1
11:00:19 INFO - mozdevice == 0.51
11:00:19 INFO - mozfile == 1.2
11:00:19 INFO - mozhttpd == 0.7
11:00:19 INFO - mozinfo == 0.10
11:00:19 INFO - mozleak == 0.1
11:00:19 INFO - mozlog == 3.5
11:00:19 INFO - moznetwork == 0.27
11:00:19 INFO - mozprocess == 0.25
11:00:19 INFO - mozprofile == 0.29
11:00:19 INFO - mozrunner == 6.13
11:00:19 INFO - mozscreenshot == 0.1
11:00:19 INFO - mozsystemmonitor == 0.3
11:00:19 INFO - moztest == 0.8
11:00:19 INFO - mozversion == 1.4
11:00:19 INFO - psutil == 3.1.1
11:00:19 INFO - requests == 1.2.3
11:00:19 INFO - six == 1.10.0
11:00:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
11:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
11:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
11:00:19 INFO - Using env: {'DISPLAY': ':0',
11:00:19 INFO - 'HOME': '/home/cltbld',
11:00:19 INFO - 'LANG': 'en_US.UTF-8',
11:00:19 INFO - 'LANGUAGE': 'en_US:en',
11:00:19 INFO - 'LOGNAME': 'cltbld',
11:00:19 INFO - 'MAIL': '/var/mail/cltbld',
11:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:00:19 INFO - 'MOZ_NO_REMOTE': '1',
11:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:00:19 INFO - 'NO_EM_RESTART': '1',
11:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:00:19 INFO - 'PWD': '/builds/slave/test',
11:00:19 INFO - 'SHELL': '/bin/bash',
11:00:19 INFO - 'SHLVL': '1',
11:00:19 INFO - 'TERM': 'linux',
11:00:19 INFO - 'TMOUT': '86400',
11:00:19 INFO - 'USER': 'cltbld',
11:00:19 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:00:19 INFO - '_': '/tools/buildbot/bin/python'}
11:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
11:00:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
11:00:19 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
11:00:20 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:00:21 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
11:00:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
11:00:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
11:00:21 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
11:00:21 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:00:22 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
11:00:22 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:00:23 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
11:00:23 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:00:24 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
11:00:24 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:00:25 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
11:00:25 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
11:00:26 INFO - Found existing installation: requests 1.2.3
11:00:26 INFO - Uninstalling requests-1.2.3:
11:00:26 INFO - Successfully uninstalled requests-1.2.3
11:00:26 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
11:00:26 INFO - Return code: 0
11:00:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
11:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbc52d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd9da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf40930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
11:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
11:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
11:00:26 INFO - Using env: {'DISPLAY': ':0',
11:00:26 INFO - 'HOME': '/home/cltbld',
11:00:26 INFO - 'LANG': 'en_US.UTF-8',
11:00:26 INFO - 'LANGUAGE': 'en_US:en',
11:00:26 INFO - 'LOGNAME': 'cltbld',
11:00:26 INFO - 'MAIL': '/var/mail/cltbld',
11:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:00:26 INFO - 'MOZ_NO_REMOTE': '1',
11:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:00:26 INFO - 'NO_EM_RESTART': '1',
11:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:00:26 INFO - 'PWD': '/builds/slave/test',
11:00:26 INFO - 'SHELL': '/bin/bash',
11:00:26 INFO - 'SHLVL': '1',
11:00:26 INFO - 'TERM': 'linux',
11:00:26 INFO - 'TMOUT': '86400',
11:00:26 INFO - 'USER': 'cltbld',
11:00:26 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:00:26 INFO - '_': '/tools/buildbot/bin/python'}
11:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple
11:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
11:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
11:00:26 INFO - Return code: 0
11:00:26 INFO - Running post-action listener: _resource_record_post_action
11:00:26 INFO - Running post-action listener: _start_resource_monitoring
11:00:26 INFO - Starting resource monitoring.
11:00:26 INFO - [mozharness: 2017-11-02 18:00:26.885079Z] Finished create-virtualenv step (success)
11:00:26 INFO - [mozharness: 2017-11-02 18:00:26.885578Z] Running install step.
11:00:26 INFO - Running pre-action listener: _resource_record_pre_action
11:00:26 INFO - Running main action method: install
11:00:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
11:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
11:00:27 INFO - Reading from file tmpfile_stdout
11:00:27 INFO - Reading from file tmpfile_stderr
11:00:27 INFO - Detecting whether we're running mozinstall >=1.0...
11:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
11:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
11:00:27 INFO - Reading from file tmpfile_stdout
11:00:27 INFO - Output received:
11:00:27 INFO - Usage: mozinstall [options] installer
11:00:27 INFO - Options:
11:00:27 INFO - -h, --help show this help message and exit
11:00:27 INFO - -d DEST, --destination=DEST
11:00:27 INFO - Directory to install application into. [default:
11:00:27 INFO - "/builds/slave/test"]
11:00:27 INFO - --app=APP Application being installed. [default: firefox]
11:00:27 INFO - mkdir: /builds/slave/test/build/application
11:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
11:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
11:00:42 INFO - Reading from file tmpfile_stdout
11:00:42 INFO - Output received:
11:00:42 INFO - /builds/slave/test/build/application/firefox/firefox
11:00:42 INFO - Running post-action listener: _resource_record_post_action
11:00:42 INFO - [mozharness: 2017-11-02 18:00:42.521495Z] Finished install step (success)
11:00:42 INFO - [mozharness: 2017-11-02 18:00:42.521851Z] Running setup-mitmproxy step.
11:00:42 INFO - Running pre-action listener: _resource_record_pre_action
11:00:42 INFO - Running main action method: setup_mitmproxy
11:00:42 INFO - Skipping: mitmproxy is not required
11:00:42 INFO - Running post-action listener: _resource_record_post_action
11:00:42 INFO - [mozharness: 2017-11-02 18:00:42.522973Z] Finished setup-mitmproxy step (success)
11:00:42 INFO - [mozharness: 2017-11-02 18:00:42.523191Z] Running run-tests step.
11:00:42 INFO - Running pre-action listener: _resource_record_pre_action
11:00:42 INFO - Running pre-action listener: _set_gcov_prefix
11:00:42 INFO - Running main action method: run_tests
11:00:42 WARNING - Try message not found.
11:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
11:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
11:00:42 INFO - Python 2.7.3
11:00:42 INFO - Return code: 0
11:00:42 INFO - grabbing minidump binary from tooltool
11:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:00:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf3dad0>, '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 0xf3e9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf3ee60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
11:00:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
11:00:42 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
11:00:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
11:00:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
11:00:42 INFO - Return code: 0
11:00:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
11:00:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
11:00:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
11:00:42 INFO - ENV: RUST_BACKTRACE is now full
11:00:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
11:00:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
11:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
11:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
11:00:42 INFO - Using env: {'DISPLAY': ':0',
11:00:42 INFO - 'HOME': '/home/cltbld',
11:00:42 INFO - 'LANG': 'en_US.UTF-8',
11:00:42 INFO - 'LANGUAGE': 'en_US:en',
11:00:42 INFO - 'LOGNAME': 'cltbld',
11:00:42 INFO - 'MAIL': '/var/mail/cltbld',
11:00:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:00:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:00:42 INFO - 'MOZ_NO_REMOTE': '1',
11:00:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:00:42 INFO - 'NO_EM_RESTART': '1',
11:00:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:00:42 INFO - 'PWD': '/builds/slave/test',
11:00:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:00:42 INFO - 'RUST_BACKTRACE': 'full',
11:00:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:00:42 INFO - 'SHELL': '/bin/bash',
11:00:42 INFO - 'SHLVL': '1',
11:00:42 INFO - 'TERM': 'linux',
11:00:42 INFO - 'TMOUT': '86400',
11:00:42 INFO - 'USER': 'cltbld',
11:00:42 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:00:42 INFO - '_': '/tools/buildbot/bin/python'}
11:00:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
11:00:42 INFO - mozversion application_buildid: 20171102163321
11:00:42 INFO - mozversion application_changeset: 73e0d89a540f1568387efa0e145e7853f9badb10
11:00:42 INFO - mozversion application_display_name: Firefox
11:00:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
11:00:42 INFO - mozversion application_name: Firefox
11:00:42 INFO - mozversion application_remotingname: firefox
11:00:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
11:00:42 INFO - mozversion application_vendor: Mozilla
11:00:42 INFO - mozversion application_version: 58.0
11:00:42 INFO - mozversion platform_buildid: 20171102163321
11:00:42 INFO - mozversion platform_changeset: 73e0d89a540f1568387efa0e145e7853f9badb10
11:00:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
11:00:42 INFO - mozversion platform_version: 58.0
11:00:42 INFO - using testdate: 1509645642
11:00:42 INFO - actual date: 1509645642
11:00:42 INFO - starting webserver on 'localhost:50754'
11:00:42 INFO - SUITE-START | Running 3 tests
11:00:42 INFO - TEST-START | basic_compositor_video
11:00:42 INFO - Initialising browser for basic_compositor_video test...
11:00:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50754/getInfo.html -profile /tmp/tmpLSB6b7/profile
11:00:42 INFO - TEST-INFO | started process 3715 (/builds/slave/test/build/application/firefox/firefox http://localhost:50754/getInfo.html)
11:00:51 INFO - TEST-INFO | 3715: exit 0
11:00:51 INFO - Browser initialized.
11:00:51 INFO - Running cycle 1/1 for basic_compositor_video test...
11:00:51 INFO - Using env: {'DISPLAY': ':0',
11:00:51 INFO - 'HOME': '/home/cltbld',
11:00:51 INFO - 'JSGC_DISABLE_POISONING': '1',
11:00:51 INFO - 'LANG': 'en_US.UTF-8',
11:00:51 INFO - 'LANGUAGE': 'en_US:en',
11:00:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
11:00:51 INFO - 'LOGNAME': 'cltbld',
11:00:51 INFO - 'MAIL': '/var/mail/cltbld',
11:00:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:00:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:00:51 INFO - 'MOZ_CRASHREPORTER': '1',
11:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:00:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
11:00:51 INFO - 'MOZ_NO_REMOTE': '1',
11:00:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:00:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:00:51 INFO - 'NO_EM_RESTART': '1',
11:00:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:00:51 INFO - 'PWD': '/builds/slave/test',
11:00:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:00:51 INFO - 'RUST_BACKTRACE': 'full',
11:00:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:00:51 INFO - 'SHELL': '/bin/bash',
11:00:51 INFO - 'SHLVL': '1',
11:00:51 INFO - 'TERM': 'linux',
11:00:51 INFO - 'TMOUT': '86400',
11:00:51 INFO - 'USER': 'cltbld',
11:00:51 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:00:51 INFO - '_': '/tools/buildbot/bin/python'}
11:00:51 INFO - TEST-INFO | started process 3993 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLSB6b7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
11:00:52 INFO - PID 3993 |
11:00:52 INFO - PID 3993 | (/builds/slave/test/build/application/firefox/firefox:4043): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
11:00:52 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6855702247191011 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6658576186511243 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7365393518518522 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.750279965004374 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033387096774193 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8690909090909087 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.5431759149940967 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4664471403812813 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.68409090909091 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.333839999999997 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.205187074829935 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 9.855615763546792 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.237565543071161 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.585714285714271 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.247816326530629 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.911071428571415 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.630523255813937 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.177513966480456 ms/frame
11:01:39 INFO - PID 3993 |
11:01:39 INFO - PID 3993 | Cycle 1(1): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:02:25 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681028011204482 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6690825688073392 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.736197916666667 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7079590093936803 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033461021505377 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8258030592734236 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.5134836065573762 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.420128205128208 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715714285714285 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.61924157303371 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.241808873720132 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 10.05281407035175 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.99025641025641 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.529289473684223 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.146214574898806 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.699415204678354 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.40973384030419 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113083333333352 ms/frame
11:02:25 INFO - PID 3993 |
11:02:25 INFO - PID 3993 | Cycle 1(2): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:03:11 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800111982082866 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6676791666666664 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7721972829297106 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7216222030981063 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.167840277777777 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0406262626262635 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.576889153754468 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.522288732394366 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.681827651515153 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.420542005420055 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.170305084745756 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 10.312268041237122 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.873460144927538 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.641223404255335 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.24610204081635 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.77044117647058 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.674319066147872 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.174469273742995 ms/frame
11:03:11 INFO - PID 3993 |
11:03:11 INFO - PID 3993 | Cycle 1(3): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:03:57 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.690712676056338 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6667708333333333 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7137321530553973 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6969635284139095 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.115836762688617 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0243762575452715 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.4644861431870657 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3843993231810496 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.608336448598129 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.540761772853186 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.346448275862073 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 9.804313725490205 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.07057195571957 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.530763157894743 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.504208333333342 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.274386503067475 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.324433962264145 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.811837837837851 ms/frame
11:03:57 INFO - PID 3993 |
11:03:57 INFO - PID 3993 | Cycle 1(4): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:04:43 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6897381756756755 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6702045075125211 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.740330626450116 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7051023017902813 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.150546334716458 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.150591286307052 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.4257876712328765 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3562667785234885 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.628986866791744 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.494917582417586 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.639432624113475 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 10.368549222797933 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.951733576642328 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.586666666666654 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.609285714285722 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.768411764705867 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.453225190839676 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.17617318435756 ms/frame
11:04:43 INFO - PID 3993 |
11:04:43 INFO - PID 3993 | Cycle 1(5): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:05:29 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6855056179775283 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673625 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7354453441295548 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6999235344095154 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9792904509283824 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.073553971486762 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.5256227967097535 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4739236111111103 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.62944652908068 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.436480978260869 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.276232876712328 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 9.9518407960199 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.031654411764695 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.471178010471219 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347798353909463 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83881656804735 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.410608365019003 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050828729281791 ms/frame
11:05:29 INFO - PID 3993 |
11:05:29 INFO - PID 3993 | Cycle 1(6): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:05:52 INFO - PID 3993 |
11:05:52 INFO - PID 3993 | (/builds/slave/test/build/application/firefox/firefox:4127): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
11:05:52 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6770681945220793 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685946622185157 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7495481049562684 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7517556917688266 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.060588633288229 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.175334029227555 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.440934633027522 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3675000000000033 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.566103896103898 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.263684210526308 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 9.680451612903221 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 10.42005208333334 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.194850746268662 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.584550264550243 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.297622950819683 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.910625000000003 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.542115384615373 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.115333333333345 ms/frame
11:06:16 INFO - PID 3993 |
11:06:16 INFO - PID 3993 | Cycle 1(7): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:07:02 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6793844432008955 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6697161936560931 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7427526132404183 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7182817869415807 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.190349162011175 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.13259297520661 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.387144469525962 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3738617200674486 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6822348484848435 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.571072423398337 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.00286666666667 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 9.302697674418596 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.95160583941605 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.641250000000001 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.76727659574467 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052801204819318 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.674902723735425 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113500000000002 ms/frame
11:07:02 INFO - PID 3993 |
11:07:02 INFO - PID 3993 | Cycle 1(8): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:07:48 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682818283791363 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6686864053377817 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.754897660818714 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7415230635335068 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.220042194092828 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.166739583333333 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.4408314220183494 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.385355329949237 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.618286516853934 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.557458333333327 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.067030201342277 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 10.585211640211638 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.239868913857672 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.530078947368441 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.34574074074074 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.125212121212142 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.538750000000016 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11644444444444 ms/frame
11:07:48 INFO - PID 3993 |
11:07:48 INFO - PID 3993 | Cycle 1(9): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:08:34 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6830510375771173 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670250000000002 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7213023522662079 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.704237649063032 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.098770491803278 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1605093555093555 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.4097897727272746 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3456521739130447 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682234848484851 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.436005434782611 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 10.000233333333345 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 10.001849999999996 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.03338235294117 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.476832460733007 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.6083823529412 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.051957831325302 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.452576335877852 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.695187165775401 ms/frame
11:08:34 INFO - PID 3993 |
11:08:34 INFO - PID 3993 | Cycle 1(10): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:09:20 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6789059876888641 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6723913043478256 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7570433255269324 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.733921143847486 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.139124137931034 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.832212643678159 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.445453501722158 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3743001686340626 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.660443396226415 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.571434540389974 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 9.901023102310237 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 9.954875621890531 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991153846153857 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.31288659793813 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.197073170731692 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.769735294117666 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.32181132075474 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05254143646406 ms/frame
11:09:20 INFO - PID 3993 |
11:09:20 INFO - PID 3993 | Cycle 1(11): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:10:06 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_startup = 1.687800899887514 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6686280233527944 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7434195235328298 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7190850515463914 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_startup = 4.043146900269541 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.13288223140496 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_startup = 3.4287542857142834 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1_inclip = 3.339148580968283 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.705484790874523 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.558611111111107 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_startup = 9.772198697068411 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.480p.60fps.webm_scale_2_inclip = 9.953955223880591 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.950693430656926 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640531914893646 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500104166666672 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907410714285712 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.496570881226038 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991401098901125 ms/frame
11:10:06 INFO - PID 3993 |
11:10:06 INFO - PID 3993 | Cycle 1(12): loaded http://localhost:50754/tests/video/video_playback.html (next: http://localhost:50754/tests/video/video_playback.html)
11:10:07 INFO - PID 3993 | __start_tp_report
11:10:07 INFO - PID 3993 | _x_x_mozilla_page_load
11:10:07 INFO - PID 3993 | _x_x_mozilla_page_load_details
11:10:07 INFO - PID 3993 | |i|pagename|runs|
11:10:07 INFO - PID 3993 | |0;240p.120fps.mp4_scale_1_startup;1.6855702247191011;1.681028011204482;1.6800111982082866;1.690712676056338;1.6897381756756755;1.6855056179775283;1.6770681945220793;1.6793844432008955;1.682818283791363;1.6830510375771173;1.6789059876888641;1.687800899887514
11:10:07 INFO - PID 3993 | |1;240p.120fps.mp4_scale_1_inclip;1.6658576186511243;1.6690825688073392;1.6676791666666664;1.6667708333333333;1.6702045075125211;1.6673625;1.6685946622185157;1.6697161936560931;1.6686864053377817;1.6670250000000002;1.6723913043478256;1.6686280233527944
11:10:07 INFO - PID 3993 | |2;240p.120fps.mp4_scale_1.1_startup;1.7365393518518522;1.736197916666667;1.7721972829297106;1.7137321530553973;1.740330626450116;1.7354453441295548;1.7495481049562684;1.7427526132404183;1.754897660818714;1.7213023522662079;1.7570433255269324;1.7434195235328298
11:10:07 INFO - PID 3993 | |3;240p.120fps.mp4_scale_1.1_inclip;1.750279965004374;1.7079590093936803;1.7216222030981063;1.6969635284139095;1.7051023017902813;1.6999235344095154;1.7517556917688266;1.7182817869415807;1.7415230635335068;1.704237649063032;1.733921143847486;1.7190850515463914
11:10:07 INFO - PID 3993 | |4;240p.120fps.mp4_scale_2_startup;4.033387096774193;4.033461021505377;4.167840277777777;4.115836762688617;4.150546334716458;3.9792904509283824;4.060588633288229;4.190349162011175;4.220042194092828;4.098770491803278;4.139124137931034;4.043146900269541
11:10:07 INFO - PID 3993 | |5;240p.120fps.mp4_scale_2_inclip;3.8690909090909087;3.8258030592734236;4.0406262626262635;4.0243762575452715;4.150591286307052;4.073553971486762;4.175334029227555;4.13259297520661;4.166739583333333;4.1605093555093555;3.832212643678159;4.13288223140496
11:10:07 INFO - PID 3993 | |6;480p.60fps.webm_scale_1_startup;3.5431759149940967;3.5134836065573762;3.576889153754468;3.4644861431870657;3.4257876712328765;3.5256227967097535;3.440934633027522;3.387144469525962;3.4408314220183494;3.4097897727272746;3.445453501722158;3.4287542857142834
11:10:07 INFO - PID 3993 | |7;480p.60fps.webm_scale_1_inclip;3.4664471403812813;3.420128205128208;3.522288732394366;3.3843993231810496;3.3562667785234885;3.4739236111111103;3.3675000000000033;3.3738617200674486;3.385355329949237;3.3456521739130447;3.3743001686340626;3.339148580968283
11:10:07 INFO - PID 3993 | |8;480p.60fps.webm_scale_1.1_startup;5.68409090909091;5.715714285714285;5.681827651515153;5.608336448598129;5.628986866791744;5.62944652908068;5.566103896103898;5.6822348484848435;5.618286516853934;5.682234848484851;5.660443396226415;5.705484790874523
11:10:07 INFO - PID 3993 | |9;480p.60fps.webm_scale_1.1_inclip;5.333839999999997;5.61924157303371;5.420542005420055;5.540761772853186;5.494917582417586;5.436480978260869;5.263684210526308;5.571072423398337;5.557458333333327;5.436005434782611;5.571434540389974;5.558611111111107
11:10:07 INFO - PID 3993 | |10;480p.60fps.webm_scale_2_startup;10.205187074829935;10.241808873720132;10.170305084745756;10.346448275862073;10.639432624113475;10.276232876712328;9.680451612903221;10.00286666666667;10.067030201342277;10.000233333333345;9.901023102310237;9.772198697068411
11:10:07 INFO - PID 3993 | |11;480p.60fps.webm_scale_2_inclip;9.855615763546792;10.05281407035175;10.312268041237122;9.804313725490205;10.368549222797933;9.9518407960199;10.42005208333334;9.302697674418596;10.585211640211638;10.001849999999996;9.954875621890531;9.953955223880591
11:10:07 INFO - PID 3993 | |12;1080p.60fps.mp4_scale_1_startup;11.237565543071161;10.99025641025641;10.873460144927538;11.07057195571957;10.951733576642328;11.031654411764695;11.194850746268662;10.95160583941605;11.239868913857672;11.03338235294117;10.991153846153857;10.950693430656926
11:10:07 INFO - PID 3993 | |13;1080p.60fps.mp4_scale_1_inclip;10.585714285714271;10.529289473684223;10.641223404255335;10.530763157894743;10.586666666666654;10.471178010471219;10.584550264550243;10.641250000000001;10.530078947368441;10.476832460733007;10.31288659793813;10.640531914893646
11:10:07 INFO - PID 3993 | |14;1080p.60fps.mp4_scale_1.1_startup;12.247816326530629;12.146214574898806;12.24610204081635;12.504208333333342;12.609285714285722;12.347798353909463;12.297622950819683;12.76727659574467;12.34574074074074;12.6083823529412;12.197073170731692;12.500104166666672
11:10:07 INFO - PID 3993 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.911071428571415;11.699415204678354;11.77044117647058;12.274386503067475;11.768411764705867;11.83881656804735;11.910625000000003;12.052801204819318;12.125212121212142;12.051957831325302;11.769735294117666;11.907410714285712
11:10:07 INFO - PID 3993 | |16;1080p.60fps.mp4_scale_2_startup;11.630523255813937;11.40973384030419;11.674319066147872;11.324433962264145;11.453225190839676;11.410608365019003;11.542115384615373;11.674902723735425;11.538750000000016;11.452576335877852;11.32181132075474;11.496570881226038
11:10:07 INFO - PID 3993 | |17;1080p.60fps.mp4_scale_2_inclip;11.177513966480456;11.113083333333352;11.174469273742995;10.811837837837851;11.17617318435756;11.050828729281791;11.115333333333345;11.113500000000002;11.11644444444444;10.695187165775401;11.05254143646406;10.991401098901125
11:10:07 INFO - PID 3993 | __end_tp_report
11:10:07 INFO - PID 3993 | __start_cc_report
11:10:07 INFO - PID 3993 | _x_x_mozilla_cycle_collect,557
11:10:07 INFO - PID 3993 | __end_cc_report
11:10:07 INFO - PID 3993 | __startTimestamp1509646207158__endTimestamp
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | ------- Summary: start -------
11:10:07 INFO - PID 3993 | Number of tests: 18
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
11:10:07 INFO - PID 3993 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
11:10:07 INFO - PID 3993 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.74 Median:1.74 stddev:0.02 (0.9%) stddev-sans-first:0.02
11:10:07 INFO - PID 3993 | Values: 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.7 1.8 1.7 1.8 1.7
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.72 Median:1.72 stddev:0.02 (1.1%) stddev-sans-first:0.02
11:10:07 INFO - PID 3993 | Values: 1.8 1.7 1.7 1.7 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.7
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.10 Median:4.13 stddev:0.07 (1.8%) stddev-sans-first:0.07
11:10:07 INFO - PID 3993 | Values: 4.0 4.0 4.2 4.1 4.2 4.0 4.1 4.2 4.2 4.1 4.1 4.0
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.05 Median:4.13 stddev:0.13 (3.2%) stddev-sans-first:0.13
11:10:07 INFO - PID 3993 | Values: 3.9 3.8 4.0 4.0 4.2 4.1 4.2 4.1 4.2 4.2 3.8 4.1
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.47 Median:3.45 stddev:0.06 (1.7%) stddev-sans-first:0.06
11:10:07 INFO - PID 3993 | Values: 3.5 3.5 3.6 3.5 3.4 3.5 3.4 3.4 3.4 3.4 3.4 3.4
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.40 Median:3.38 stddev:0.06 (1.7%) stddev-sans-first:0.06
11:10:07 INFO - PID 3993 | Values: 3.5 3.4 3.5 3.4 3.4 3.5 3.4 3.4 3.4 3.3 3.4 3.3
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.66 Median:5.68 stddev:0.04 (0.8%) stddev-sans-first:0.05
11:10:07 INFO - PID 3993 | Values: 5.7 5.7 5.7 5.6 5.6 5.6 5.6 5.7 5.6 5.7 5.7 5.7
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.48 Median:5.55 stddev:0.11 (1.9%) stddev-sans-first:0.10
11:10:07 INFO - PID 3993 | Values: 5.3 5.6 5.4 5.5 5.5 5.4 5.3 5.6 5.6 5.4 5.6 5.6
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.11 Median:10.31 stddev:0.26 (2.6%) stddev-sans-first:0.27
11:10:07 INFO - PID 3993 | Values: 10.2 10.2 10.2 10.3 10.6 10.3 9.7 10.0 10.1 10.0 9.9 9.8
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.05 Median:9.55 stddev:0.34 (3.6%) stddev-sans-first:0.35
11:10:07 INFO - PID 3993 | Values: 9.9 10.1 10.3 9.8 10.4 10.0 10.4 9.3 10.6 10.0 10.0 10.0
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.04 Median:11.03 stddev:0.12 (1.1%) stddev-sans-first:0.11
11:10:07 INFO - PID 3993 | Values: 11.2 11.0 10.9 11.1 11.0 11.0 11.2 11.0 11.2 11.0 11.0 11.0
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.54 Median:10.59 stddev:0.09 (0.9%) stddev-sans-first:0.10
11:10:07 INFO - PID 3993 | Values: 10.6 10.5 10.6 10.5 10.6 10.5 10.6 10.6 10.5 10.5 10.3 10.6
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.40 Median:12.42 stddev:0.19 (1.6%) stddev-sans-first:0.20
11:10:07 INFO - PID 3993 | Values: 12.2 12.1 12.2 12.5 12.6 12.3 12.3 12.8 12.3 12.6 12.2 12.5
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.92 Median:11.91 stddev:0.17 (1.4%) stddev-sans-first:0.18
11:10:07 INFO - PID 3993 | Values: 11.9 11.7 11.8 12.3 11.8 11.8 11.9 12.1 12.1 12.1 11.8 11.9
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.49 Median:11.52 stddev:0.12 (1.1%) stddev-sans-first:0.12
11:10:07 INFO - PID 3993 | Values: 11.6 11.4 11.7 11.3 11.5 11.4 11.5 11.7 11.5 11.5 11.3 11.5
11:10:07 INFO - PID 3993 |
11:10:07 INFO - PID 3993 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.05 Median:11.11 stddev:0.15 (1.4%) stddev-sans-first:0.15
11:10:07 INFO - PID 3993 | Values: 11.2 11.1 11.2 10.8 11.2 11.1 11.1 11.1 11.1 10.7 11.1 11.0
11:10:07 INFO - PID 3993 | -------- Summary: end --------
11:10:07 INFO - PID 3993 |
11:10:07 INFO - TEST-INFO | 3993: exit 0
11:10:07 INFO - TEST-OK | basic_compositor_video | took 564720ms
11:10:07 INFO - TEST-START | glvideo
11:10:07 INFO - Initialising browser for glvideo test...
11:10:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50754/getInfo.html -profile /tmp/tmpPuNr84/profile
11:10:07 INFO - TEST-INFO | started process 5307 (/builds/slave/test/build/application/firefox/firefox http://localhost:50754/getInfo.html)
11:10:12 INFO - TEST-INFO | 5307: exit 0
11:10:12 INFO - Browser initialized.
11:10:12 INFO - Running cycle 1/1 for glvideo test...
11:10:12 INFO - Using env: {'DISPLAY': ':0',
11:10:12 INFO - 'HOME': '/home/cltbld',
11:10:12 INFO - 'JSGC_DISABLE_POISONING': '1',
11:10:12 INFO - 'LANG': 'en_US.UTF-8',
11:10:12 INFO - 'LANGUAGE': 'en_US:en',
11:10:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
11:10:12 INFO - 'LOGNAME': 'cltbld',
11:10:12 INFO - 'MAIL': '/var/mail/cltbld',
11:10:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:10:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:10:12 INFO - 'MOZ_CRASHREPORTER': '1',
11:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:10:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
11:10:12 INFO - 'MOZ_NO_REMOTE': '1',
11:10:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:10:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:10:12 INFO - 'NO_EM_RESTART': '1',
11:10:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:10:12 INFO - 'PWD': '/builds/slave/test',
11:10:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:10:12 INFO - 'RUST_BACKTRACE': 'full',
11:10:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:10:12 INFO - 'SHELL': '/bin/bash',
11:10:12 INFO - 'SHLVL': '1',
11:10:12 INFO - 'TERM': 'linux',
11:10:12 INFO - 'TMOUT': '86400',
11:10:12 INFO - 'USER': 'cltbld',
11:10:12 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:10:12 INFO - '_': '/tools/buildbot/bin/python'}
11:10:12 INFO - TEST-INFO | started process 5582 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPuNr84/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
11:10:13 INFO - PID 5582 |
11:10:13 INFO - PID 5582 | (/builds/slave/test/build/application/firefox/firefox:5632): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
11:10:13 INFO - PID 5582 |
11:10:15 INFO - PID 5582 | [talos glvideo result] Mean tick time across 100 ticks: 7.572850000000001 ms
11:10:15 INFO - PID 5582 | Cycle 1(1): loaded http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html)
11:10:16 INFO - PID 5582 | [talos glvideo result] Mean tick time across 100 ticks: 6.6424 ms
11:10:16 INFO - PID 5582 | Cycle 1(2): loaded http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html)
11:10:17 INFO - PID 5582 | [talos glvideo result] Mean tick time across 100 ticks: 6.535899999999999 ms
11:10:17 INFO - PID 5582 | Cycle 1(3): loaded http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html)
11:10:19 INFO - PID 5582 | [talos glvideo result] Mean tick time across 100 ticks: 6.61875 ms
11:10:19 INFO - PID 5582 | Cycle 1(4): loaded http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html)
11:10:20 INFO - PID 5582 | [talos glvideo result] Mean tick time across 100 ticks: 6.6663 ms
11:10:20 INFO - PID 5582 | Cycle 1(5): loaded http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50754/tests/webgl/benchmarks/video/video_upload.html)
11:10:20 INFO - PID 5582 | __start_tp_report
11:10:20 INFO - PID 5582 | _x_x_mozilla_page_load
11:10:20 INFO - PID 5582 | _x_x_mozilla_page_load_details
11:10:20 INFO - PID 5582 | |i|pagename|runs|
11:10:20 INFO - PID 5582 | |0;Mean tick time across 100 ticks: ;7.572850000000001;6.6424;6.535899999999999;6.61875;6.6663
11:10:20 INFO - PID 5582 | __end_tp_report
11:10:20 INFO - PID 5582 | __start_cc_report
11:10:20 INFO - PID 5582 | _x_x_mozilla_cycle_collect,250
11:10:20 INFO - PID 5582 | __end_cc_report
11:10:20 INFO - PID 5582 | __startTimestamp1509646220780__endTimestamp
11:10:20 INFO - PID 5582 |
11:10:20 INFO - PID 5582 | ------- Summary: start -------
11:10:20 INFO - PID 5582 | Number of tests: 1
11:10:20 INFO - PID 5582 |
11:10:20 INFO - PID 5582 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.81 Median:6.64 stddev:0.43 (6.5%) stddev-sans-first:0.06
11:10:20 INFO - PID 5582 | Values: 7.6 6.6 6.5 6.6 6.7
11:10:20 INFO - PID 5582 | -------- Summary: end --------
11:10:20 INFO - PID 5582 |
11:10:21 INFO - PID 5582 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
11:10:21 INFO - TEST-INFO | 5582: exit 0
11:10:21 INFO - TEST-OK | glvideo | took 13749ms
11:10:21 INFO - TEST-START | displaylist_mutate
11:10:21 INFO - Initialising browser for displaylist_mutate test...
11:10:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50754/getInfo.html -profile /tmp/tmpcv_A1P/profile
11:10:21 INFO - TEST-INFO | started process 5803 (/builds/slave/test/build/application/firefox/firefox http://localhost:50754/getInfo.html)
11:10:27 INFO - TEST-INFO | 5803: exit 0
11:10:27 INFO - Browser initialized.
11:10:27 INFO - Running cycle 1/1 for displaylist_mutate test...
11:10:27 INFO - Using env: {'DISPLAY': ':0',
11:10:27 INFO - 'HOME': '/home/cltbld',
11:10:27 INFO - 'JSGC_DISABLE_POISONING': '1',
11:10:27 INFO - 'LANG': 'en_US.UTF-8',
11:10:27 INFO - 'LANGUAGE': 'en_US:en',
11:10:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
11:10:27 INFO - 'LOGNAME': 'cltbld',
11:10:27 INFO - 'MAIL': '/var/mail/cltbld',
11:10:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:10:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:10:27 INFO - 'MOZ_CRASHREPORTER': '1',
11:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:10:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
11:10:27 INFO - 'MOZ_NO_REMOTE': '1',
11:10:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:10:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:10:27 INFO - 'NO_EM_RESTART': '1',
11:10:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:10:27 INFO - 'PWD': '/builds/slave/test',
11:10:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:10:27 INFO - 'RUST_BACKTRACE': 'full',
11:10:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:10:27 INFO - 'SHELL': '/bin/bash',
11:10:27 INFO - 'SHLVL': '1',
11:10:27 INFO - 'TERM': 'linux',
11:10:27 INFO - 'TMOUT': '86400',
11:10:27 INFO - 'USER': 'cltbld',
11:10:27 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841',
11:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:10:27 INFO - '_': '/tools/buildbot/bin/python'}
11:10:27 INFO - TEST-INFO | started process 6073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcv_A1P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
11:10:27 INFO - PID 6073 |
11:10:27 INFO - PID 6073 | (/builds/slave/test/build/application/firefox/firefox:6124): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
11:10:27 INFO - PID 6073 |
11:10:36 INFO - PID 6073 | Cycle 1(1): loaded http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html)
11:10:45 INFO - PID 6073 | Cycle 1(2): loaded http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html)
11:10:54 INFO - PID 6073 | Cycle 1(3): loaded http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html)
11:11:03 INFO - PID 6073 | Cycle 1(4): loaded http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html)
11:11:12 INFO - PID 6073 | Cycle 1(5): loaded http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50754/tests/layout/benchmarks/displaylist_mutate.html)
11:11:12 INFO - PID 6073 | __start_tp_report
11:11:12 INFO - PID 6073 | _x_x_mozilla_page_load
11:11:12 INFO - PID 6073 | _x_x_mozilla_page_load_details
11:11:12 INFO - PID 6073 | |i|pagename|runs|
11:11:12 INFO - PID 6073 | |0;/displaylist_mutate.html;8061.965000000001;8169.7699999999995;8134.055;8177.900000000001;8185.9
11:11:12 INFO - PID 6073 | __end_tp_report
11:11:12 INFO - PID 6073 | __start_cc_report
11:11:12 INFO - PID 6073 | _x_x_mozilla_cycle_collect,197
11:11:12 INFO - PID 6073 | __end_cc_report
11:11:12 INFO - PID 6073 | __startTimestamp1509646272398__endTimestamp
11:11:12 INFO - PID 6073 |
11:11:12 INFO - PID 6073 | ------- Summary: start -------
11:11:12 INFO - PID 6073 | Number of tests: 1
11:11:12 INFO - PID 6073 |
11:11:12 INFO - PID 6073 | [#0] /displaylist_mutate.html Cycles:5 Average:8145.92 Median:8169.77 stddev:50.94 (0.6%) stddev-sans-first:22.87
11:11:12 INFO - PID 6073 | Values: 8062.0 8169.8 8134.1 8177.9 8185.9
11:11:12 INFO - PID 6073 | -------- Summary: end --------
11:11:12 INFO - PID 6073 |
11:11:12 INFO - PID 6073 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
11:11:13 INFO - TEST-INFO | 6073: exit 0
11:11:13 INFO - TEST-OK | displaylist_mutate | took 51582ms
11:11:13 INFO - SUITE-END | took 630s
11:11:13 INFO - Completed test suite (00:10:30)
11:11:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.569119991990425, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6855702247191011, 1.681028011204482, 1.6800111982082866, 1.690712676056338, 1.6897381756756755, 1.6855056179775283, 1.6770681945220793, 1.6793844432008955, 1.682818283791363, 1.6830510375771173, 1.6789059876888641, 1.687800899887514], "value": 1.682818283791363, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6658576186511243, 1.6690825688073392, 1.6676791666666664, 1.6667708333333333, 1.6702045075125211, 1.6673625, 1.6685946622185157, 1.6697161936560931, 1.6686864053377817, 1.6670250000000002, 1.6723913043478256, 1.6686280233527944], "value": 1.6686280233527944, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7365393518518522, 1.736197916666667, 1.7721972829297106, 1.7137321530553973, 1.740330626450116, 1.7354453441295548, 1.7495481049562684, 1.7427526132404183, 1.754897660818714, 1.7213023522662079, 1.7570433255269324, 1.7434195235328298], "value": 1.7427526132404183, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.750279965004374, 1.7079590093936803, 1.7216222030981063, 1.6969635284139095, 1.7051023017902813, 1.6999235344095154, 1.7517556917688266, 1.7182817869415807, 1.7415230635335068, 1.704237649063032, 1.733921143847486, 1.7190850515463914], "value": 1.7182817869415807, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.033387096774193, 4.033461021505377, 4.167840277777777, 4.115836762688617, 4.150546334716458, 3.9792904509283824, 4.060588633288229, 4.190349162011175, 4.220042194092828, 4.098770491803278, 4.139124137931034, 4.043146900269541], "value": 4.115836762688617, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.8690909090909087, 3.8258030592734236, 4.0406262626262635, 4.0243762575452715, 4.150591286307052, 4.073553971486762, 4.175334029227555, 4.13259297520661, 4.166739583333333, 4.1605093555093555, 3.832212643678159, 4.13288223140496], "value": 4.13259297520661, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5431759149940967, 3.5134836065573762, 3.576889153754468, 3.4644861431870657, 3.4257876712328765, 3.5256227967097535, 3.440934633027522, 3.387144469525962, 3.4408314220183494, 3.4097897727272746, 3.445453501722158, 3.4287542857142834], "value": 3.440934633027522, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4664471403812813, 3.420128205128208, 3.522288732394366, 3.3843993231810496, 3.3562667785234885, 3.4739236111111103, 3.3675000000000033, 3.3738617200674486, 3.385355329949237, 3.3456521739130447, 3.3743001686340626, 3.339148580968283], "value": 3.3743001686340626, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.68409090909091, 5.715714285714285, 5.681827651515153, 5.608336448598129, 5.628986866791744, 5.62944652908068, 5.566103896103898, 5.6822348484848435, 5.618286516853934, 5.682234848484851, 5.660443396226415, 5.705484790874523], "value": 5.660443396226415, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.333839999999997, 5.61924157303371, 5.420542005420055, 5.540761772853186, 5.494917582417586, 5.436480978260869, 5.263684210526308, 5.571072423398337, 5.557458333333327, 5.436005434782611, 5.571434540389974, 5.558611111111107], "value": 5.540761772853186, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.205187074829935, 10.241808873720132, 10.170305084745756, 10.346448275862073, 10.639432624113475, 10.276232876712328, 9.680451612903221, 10.00286666666667, 10.067030201342277, 10.000233333333345, 9.901023102310237, 9.772198697068411], "value": 10.067030201342277, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.855615763546792, 10.05281407035175, 10.312268041237122, 9.804313725490205, 10.368549222797933, 9.9518407960199, 10.42005208333334, 9.302697674418596, 10.585211640211638, 10.001849999999996, 9.954875621890531, 9.953955223880591], "value": 10.001849999999996, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.237565543071161, 10.99025641025641, 10.873460144927538, 11.07057195571957, 10.951733576642328, 11.031654411764695, 11.194850746268662, 10.95160583941605, 11.239868913857672, 11.03338235294117, 10.991153846153857, 10.950693430656926], "value": 10.991153846153857, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.585714285714271, 10.529289473684223, 10.641223404255335, 10.530763157894743, 10.586666666666654, 10.471178010471219, 10.584550264550243, 10.641250000000001, 10.530078947368441, 10.476832460733007, 10.31288659793813, 10.640531914893646], "value": 10.530763157894743, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.247816326530629, 12.146214574898806, 12.24610204081635, 12.504208333333342, 12.609285714285722, 12.347798353909463, 12.297622950819683, 12.76727659574467, 12.34574074074074, 12.6083823529412, 12.197073170731692, 12.500104166666672], "value": 12.347798353909463, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.911071428571415, 11.699415204678354, 11.77044117647058, 12.274386503067475, 11.768411764705867, 11.83881656804735, 11.910625000000003, 12.052801204819318, 12.125212121212142, 12.051957831325302, 11.769735294117666, 11.907410714285712], "value": 11.907410714285712, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.630523255813937, 11.40973384030419, 11.674319066147872, 11.324433962264145, 11.453225190839676, 11.410608365019003, 11.542115384615373, 11.674902723735425, 11.538750000000016, 11.452576335877852, 11.32181132075474, 11.496570881226038], "value": 11.453225190839676, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.177513966480456, 11.113083333333352, 11.174469273742995, 10.811837837837851, 11.17617318435756, 11.050828729281791, 11.115333333333345, 11.113500000000002, 11.11644444444444, 10.695187165775401, 11.05254143646406, 10.991401098901125], "value": 11.113083333333352, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.572850000000001, 6.6424, 6.535899999999999, 6.61875, 6.6663], "value": 6.630575, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8061.965000000001, 8169.7699999999995, 8134.055, 8177.900000000001, 8185.9], "value": 8173.835, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
11:11:13 INFO - Return code: 0
11:11:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
11:11:13 INFO - # TBPL SUCCESS #
11:11:13 INFO - Running post-action listener: _package_coverage_data
11:11:13 INFO - Running post-action listener: _resource_record_post_action
11:11:13 INFO - [mozharness: 2017-11-02 18:11:13.470875Z] Finished run-tests step (success)
11:11:13 INFO - Running post-run listener: _resource_record_post_run
11:11:13 INFO - Total resource usage - Wall time: 646s; CPU: 36.0%; Read bytes: 19832832; Write bytes: 299069440; Read time: 5136; Write time: 251976
11:11:13 INFO - TinderboxPrint: CPU usage
36.4%
11:11:13 INFO - TinderboxPrint: I/O read bytes / time
19,832,832 / 5,136
11:11:13 INFO - TinderboxPrint: I/O write bytes / time
299,069,440 / 251,976
11:11:13 INFO - TinderboxPrint: CPU idle
3,225.2 (63.6%)
11:11:13 INFO - TinderboxPrint: CPU system
313.1 (6.2%)
11:11:13 INFO - TinderboxPrint: CPU user
1,512.5 (29.8%)
11:11:13 INFO - TinderboxPrint: Swap in / out
0 / 0
11:11:13 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 2646016; Read time: 0; Write time: 3260
11:11:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
11:11:13 INFO - run-tests - Wall time: 631s; CPU: 37.0%; Read bytes: 17825792; Write bytes: 296423424; Read time: 5068; Write time: 248716
11:11:13 INFO - Running post-run listener: _upload_blobber_files
11:11:13 INFO - Blob upload gear active.
11:11:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
11:11:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
11:11:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
11:11:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
11:11:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:14 INFO - SNIMissingWarning
11:11:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:14 INFO - InsecurePlatformWarning
11:11:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
11:11:14 INFO - SubjectAltNameWarning
11:11:14 INFO - (blobuploader) - INFO - Open directory for files ...
11:11:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
11:11:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
11:11:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
11:11:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:14 INFO - InsecurePlatformWarning
11:11:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
11:11:14 INFO - SubjectAltNameWarning
11:11:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:19 INFO - InsecurePlatformWarning
11:11:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
11:11:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
11:11:19 INFO - (blobuploader) - INFO - Done attempting.
11:11:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
11:11:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
11:11:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
11:11:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:19 INFO - InsecurePlatformWarning
11:11:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
11:11:19 INFO - SubjectAltNameWarning
11:11:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:20 INFO - InsecurePlatformWarning
11:11:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
11:11:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
11:11:20 INFO - (blobuploader) - INFO - Done attempting.
11:11:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
11:11:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
11:11:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
11:11:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:20 INFO - InsecurePlatformWarning
11:11:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
11:11:20 INFO - SubjectAltNameWarning
11:11:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:20 INFO - InsecurePlatformWarning
11:11:21 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
11:11:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
11:11:21 INFO - (blobuploader) - INFO - Done attempting.
11:11:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
11:11:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
11:11:21 INFO - (blobuploader) - INFO - Uploading, attempt #1.
11:11:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:21 INFO - InsecurePlatformWarning
11:11:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
11:11:21 INFO - SubjectAltNameWarning
11:11:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
11:11:21 INFO - InsecurePlatformWarning
11:11:22 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
11:11:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
11:11:22 INFO - (blobuploader) - INFO - Done attempting.
11:11:22 INFO - (blobuploader) - INFO - Iteration through files over.
11:11:22 INFO - Return code: 0
11:11:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
11:11:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
11:11:22 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dd18bdac39a9604df393ac0fdf16a900b0d53e2fdd04b2af00fbb1e7c88dd279c2ed19158fe8e23d8c68e94987e7f28ae1b40c00a69c27d4274dcdbad6dde827", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9fa7edc9d44ff745541fb1bc0491bd3a56db579ac1abf0754ddd3e485ac673757cfb6c08e72017b45086967b40a3f478a267c8aa7f27bb12db6d3a9548880c93", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/56df495a57212b816601d398520f469c8b837b26ac5e006cd8eeed39de548f0e2c028cb03bf4173585dd086da580d325f9bdb2504c4d5571e0816c3f22959255", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8ed5b9f3b6b93218cf4b343d8876bcb1ca75dd921ccafcf6073e20b2d82c0724ad2712231f9392c17494db4e3d7df0b7ced064ce1ab5272bd7c69794576564c4"}
11:11:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
11:11:22 INFO - Writing to file /builds/slave/test/properties/blobber_files
11:11:22 INFO - Contents:
11:11:22 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dd18bdac39a9604df393ac0fdf16a900b0d53e2fdd04b2af00fbb1e7c88dd279c2ed19158fe8e23d8c68e94987e7f28ae1b40c00a69c27d4274dcdbad6dde827", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9fa7edc9d44ff745541fb1bc0491bd3a56db579ac1abf0754ddd3e485ac673757cfb6c08e72017b45086967b40a3f478a267c8aa7f27bb12db6d3a9548880c93", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/56df495a57212b816601d398520f469c8b837b26ac5e006cd8eeed39de548f0e2c028cb03bf4173585dd086da580d325f9bdb2504c4d5571e0816c3f22959255", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8ed5b9f3b6b93218cf4b343d8876bcb1ca75dd921ccafcf6073e20b2d82c0724ad2712231f9392c17494db4e3d7df0b7ced064ce1ab5272bd7c69794576564c4"}
11:11:22 INFO - Running post-run listener: copy_logs_to_upload_dir
11:11:22 INFO - Copying logs to upload dir...
11:11:22 INFO - mkdir: /builds/slave/test/build/upload/logs
11:11:22 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=710.874516
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 50 secs) (at 2017-11-02 11:11:22.133902) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-02 11:11:22.138017) =========
bash -c 'for file in `ls -1`; do cat $file; done'
in dir /builds/slave/test/properties (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test/properties
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dd18bdac39a9604df393ac0fdf16a900b0d53e2fdd04b2af00fbb1e7c88dd279c2ed19158fe8e23d8c68e94987e7f28ae1b40c00a69c27d4274dcdbad6dde827", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9fa7edc9d44ff745541fb1bc0491bd3a56db579ac1abf0754ddd3e485ac673757cfb6c08e72017b45086967b40a3f478a267c8aa7f27bb12db6d3a9548880c93", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/56df495a57212b816601d398520f469c8b837b26ac5e006cd8eeed39de548f0e2c028cb03bf4173585dd086da580d325f9bdb2504c4d5571e0816c3f22959255", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8ed5b9f3b6b93218cf4b343d8876bcb1ca75dd921ccafcf6073e20b2d82c0724ad2712231f9392c17494db4e3d7df0b7ced064ce1ab5272bd7c69794576564c4"}
build_url:https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011300
build_url: 'https://queue.taskcluster.net/v1/task/VE2Z-IYtS8qxN9Eev0MzMw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/dd18bdac39a9604df393ac0fdf16a900b0d53e2fdd04b2af00fbb1e7c88dd279c2ed19158fe8e23d8c68e94987e7f28ae1b40c00a69c27d4274dcdbad6dde827", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9fa7edc9d44ff745541fb1bc0491bd3a56db579ac1abf0754ddd3e485ac673757cfb6c08e72017b45086967b40a3f478a267c8aa7f27bb12db6d3a9548880c93", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/56df495a57212b816601d398520f469c8b837b26ac5e006cd8eeed39de548f0e2c028cb03bf4173585dd086da580d325f9bdb2504c4d5571e0816c3f22959255", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8ed5b9f3b6b93218cf4b343d8876bcb1ca75dd921ccafcf6073e20b2d82c0724ad2712231f9392c17494db4e3d7df0b7ced064ce1ab5272bd7c69794576564c4"}'
========= master_lag: 0.03 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-02 11:11:22.175805) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 11:11:22.176430) =========
rm -f oauth.txt
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1509644282.962728-34039841
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004512
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 11:11:22.258281) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-02 11:11:22.258611) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-02 11:11:22.258927) =========
========= Total master_lag: 0.26 =========