builder: mozilla-central_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-002
starttime: 1509619131.07
results: success (0)
revision: cb05c80b965520825841dfa003177790faff635b
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.073585) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.074063) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.074337) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.094542) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.094920) =========
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=a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-02 03:38:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 219M=0s
2017-11-02 03:38:51 (219 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.130498
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.243923) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.247277) =========
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=a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.019020
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.297038) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.297368) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb05c80b965520825841dfa003177790faff635b --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev cb05c80b965520825841dfa003177790faff635b --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=a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-02 03:38:51,696 truncating revision to first 12 chars
2017-11-02 03:38:51,696 Setting DEBUG logging.
2017-11-02 03:38:51,697 attempt 1/10
2017-11-02 03:38:51,697 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb05c80b9655?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-02 03:38:52,044 unpacking tar archive at: mozilla-central-cb05c80b9655/testing/mozharness/
program finished with exit code 0
elapsedTime=0.666734
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.985551) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.985856) =========
script_repo_revision: cb05c80b965520825841dfa003177790faff635b
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.989255) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:51.989521) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-02 03:38:52.006782) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 53 secs) (at 2017-11-02 03:38:52.007122) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
03:38:52 INFO - MultiFileLogger online at 20171102 03:38:52 in /builds/slave/test
03:38:52 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
03:38:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:38:52 INFO - {'append_to_log': False,
03:38:52 INFO - 'base_work_dir': '/builds/slave/test',
03:38:52 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
03:38:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:38:52 INFO - 'branch': 'Firefox-Non-PGO',
03:38:52 INFO - 'buildbot_json_path': 'buildprops.json',
03:38:52 INFO - 'code_coverage': False,
03:38:52 INFO - 'config_files': ('talos/linux_config.py',),
03:38:52 INFO - 'default_actions': ('clobber',
03:38:52 INFO - 'read-buildbot-config',
03:38:52 INFO - 'download-and-extract',
03:38:52 INFO - 'populate-webroot',
03:38:52 INFO - 'create-virtualenv',
03:38:52 INFO - 'install',
03:38:52 INFO - 'setup-mitmproxy',
03:38:52 INFO - 'run-tests'),
03:38:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:38:52 INFO - 'disable_ccov_upload': False,
03:38:52 INFO - 'disable_stylo': False,
03:38:52 INFO - 'download_minidump_stackwalk': True,
03:38:52 INFO - 'download_symbols': 'ondemand',
03:38:52 INFO - 'e10s': False,
03:38:52 INFO - 'enable_stylo': False,
03:38:52 INFO - 'enable_webrender': False,
03:38:52 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
03:38:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:38:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:38:52 INFO - 'gecko_profile': False,
03:38:52 INFO - 'gecko_profile_interval': 0,
03:38:52 INFO - 'installer_path': 'installer.exe',
03:38:52 INFO - 'jsd_code_coverage': False,
03:38:52 INFO - 'log_level': 'info',
03:38:52 INFO - 'log_name': 'talos',
03:38:52 INFO - 'log_to_console': True,
03:38:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
03:38:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
03:38:52 INFO - 'opt_config_files': (),
03:38:52 INFO - 'pip_index': False,
03:38:52 INFO - 'suite': 'g4-e10s',
03:38:52 INFO - 'system_bits': '32',
03:38:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:38:52 INFO - 'title': 'talos-linux64-ix-002',
03:38:52 INFO - 'tooltool_cache': '/builds/tooltool_cache',
03:38:52 INFO - 'use_talos_json': True,
03:38:52 INFO - 'verify': 'False',
03:38:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
03:38:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:38:52 INFO - 'work_dir': 'build'}
03:38:52 INFO - [mozharness: 2017-11-02 10:38:52.533661Z] Running clobber step.
03:38:52 INFO - Running pre-action listener: _resource_record_pre_action
03:38:52 INFO - Running main action method: clobber
03:38:52 INFO - rmtree: /builds/slave/test/build
03:38:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:38:53 INFO - Running post-action listener: _resource_record_post_action
03:38:53 INFO - [mozharness: 2017-11-02 10:38:53.694967Z] Finished clobber step (success)
03:38:53 INFO - [mozharness: 2017-11-02 10:38:53.695064Z] Running read-buildbot-config step.
03:38:53 INFO - Running pre-action listener: _resource_record_pre_action
03:38:53 INFO - Running main action method: read_buildbot_config
03:38:53 INFO - Using buildbot properties:
03:38:53 INFO - {
03:38:53 INFO - "project": "",
03:38:53 INFO - "product": "firefox",
03:38:53 INFO - "who": "apavel@mozilla.com",
03:38:53 INFO - "installer_path": "public/build/target.tar.bz2",
03:38:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
03:38:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
03:38:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-e10s",
03:38:53 INFO - "stage_platform": "linux64",
03:38:53 INFO - "basedir": "/builds/slave/test",
03:38:53 INFO - "buildnumber": 784,
03:38:53 INFO - "platform": "ubuntu64_hw",
03:38:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
03:38:53 INFO - "slavebuilddir": "test",
03:38:53 INFO - "taskId": "PF1aTTN4Qr6KrTqOiGPO6A",
03:38:53 INFO - "branch": "mozilla-central",
03:38:53 INFO - "script_repo_revision": "production",
03:38:53 INFO - "revision": "cb05c80b965520825841dfa003177790faff635b",
03:38:53 INFO - "slavename": "talos-linux64-ix-002",
03:38:53 INFO - "repo_path": "mozilla-central"
03:38:53 INFO - }
03:38:53 INFO - Finding installer, test and symbols from parent task.
03:38:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PF1aTTN4Qr6KrTqOiGPO6A'}, attempt #1
03:38:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PF1aTTN4Qr6KrTqOiGPO6A'}, attempt #1
03:38:54 INFO - Task dependencies: JpD9RANUQAWCO0ZgUw79-Q
03:38:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q'}, attempt #1
03:38:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.tar.bz2
03:38:55 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.test_packages.json
03:38:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.crashreporter-symbols.zip
03:38:55 INFO - Running post-action listener: _resource_record_post_action
03:38:55 INFO - [mozharness: 2017-11-02 10:38:55.077273Z] Finished read-buildbot-config step (success)
03:38:55 INFO - [mozharness: 2017-11-02 10:38:55.077488Z] Running download-and-extract step.
03:38:55 INFO - Running pre-action listener: _resource_record_pre_action
03:38:55 INFO - Running main action method: download_and_extract
03:38:55 INFO - mkdir: /builds/slave/test/build/tests
03:38: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')]}
03:38:55 INFO - trying https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.test_packages.json
03:38:55 INFO - Downloading https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
03:38:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
03:38:55 INFO - Downloaded 989 bytes.
03:38:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
03:38:55 INFO - Using the following test package requirements:
03:38:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
03:38:55 INFO - u'common': [u'target.common.tests.zip'],
03:38:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
03:38:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
03:38:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
03:38:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
03:38:55 INFO - u'mozbase': [u'target.common.tests.zip'],
03:38:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
03:38:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
03:38:55 INFO - u'web-platform': [u'target.common.tests.zip',
03:38:55 INFO - u'target.web-platform.tests.tar.gz'],
03:38:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
03:38:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
03:38:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.common.tests.zip
03:38:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.common.tests.zip'}, attempt #1
03:38:55 INFO - Fetch https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.common.tests.zip into memory
03:38:56 INFO - Content-Length response header: 56199525
03:38:56 INFO - Bytes received: 56199525
03:39:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.talos.tests.zip
03:39:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1
03:39:07 INFO - Fetch https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.talos.tests.zip into memory
03:39:08 INFO - Content-Length response header: 18771919
03:39:08 INFO - Bytes received: 18771919
03:39:09 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')]}
03:39:09 INFO - trying https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.tar.bz2
03:39:09 INFO - Downloading https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
03:39:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
03:39:10 INFO - Downloaded 62676762 bytes.
03:39:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.tar.bz2
03:39:10 INFO - mkdir: /builds/slave/test/properties
03:39:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:39:10 INFO - Writing to file /builds/slave/test/properties/build_url
03:39:10 INFO - Contents:
03:39:10 INFO - build_url:https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.tar.bz2
03:39:10 INFO - Running post-action listener: _resource_record_post_action
03:39:10 INFO - Running post-action listener: find_tests_for_verification
03:39:10 INFO - Running post-action listener: set_extra_try_arguments
03:39:10 INFO - [mozharness: 2017-11-02 10:39:10.390546Z] Finished download-and-extract step (success)
03:39:10 INFO - [mozharness: 2017-11-02 10:39:10.390643Z] Running populate-webroot step.
03:39:10 INFO - Running pre-action listener: _resource_record_pre_action
03:39:10 INFO - Running main action method: populate_webroot
03:39:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
03:39:10 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['tresize',
03:39:10 INFO - 'tcanvasmark']},
03:39:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
03:39:10 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['dromaeo_css',
03:39:10 INFO - 'kraken']},
03:39:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:39:10 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:39:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'tests': ['damp', 'tps']},
03:39:10 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['damp', 'tps']},
03:39:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
03:39:10 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['dromaeo_dom']},
03:39:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
03:39:10 INFO - 'glvideo',
03:39:10 INFO - 'displaylist_mutate']},
03:39:10 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['basic_compositor_video',
03:39:10 INFO - 'glvideo']},
03:39:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
03:39:10 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['ts_paint_webext',
03:39:10 INFO - 'tp5o_webext']},
03:39:10 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
03:39:10 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:39:10 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:39:10 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:39:10 INFO - 'talos_options': ['--mitmproxy',
03:39:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:39:10 INFO - '--firstNonBlankPaint'],
03:39:10 INFO - 'tests': ['tp6_google_heavy',
03:39:10 INFO - 'tp6_youtube_heavy',
03:39:10 INFO - 'tp6_amazon_heavy',
03:39:10 INFO - 'tp6_facebook_heavy']},
03:39:10 INFO - 'other-e10s': {'tests': ['a11yr',
03:39:10 INFO - 'ts_paint',
03:39:10 INFO - 'tpaint',
03:39:10 INFO - 'sessionrestore',
03:39:10 INFO - 'sessionrestore_many_windows',
03:39:10 INFO - 'sessionrestore_no_auto_restore',
03:39:10 INFO - 'tabpaint',
03:39:10 INFO - 'cpstartup']},
03:39:10 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['a11yr',
03:39:10 INFO - 'ts_paint',
03:39:10 INFO - 'tpaint',
03:39:10 INFO - 'sessionrestore',
03:39:10 INFO - 'sessionrestore_many_windows',
03:39:10 INFO - 'sessionrestore_no_auto_restore',
03:39:10 INFO - 'tabpaint',
03:39:10 INFO - 'cpstartup']},
03:39:10 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
03:39:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
03:39:10 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['perf_reftest_singletons']},
03:39:10 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['perf_reftest']},
03:39:10 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
03:39:10 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['speedometer']},
03:39:10 INFO - 'svgr-e10s': {'tests': ['tsvgx',
03:39:10 INFO - 'tsvgr_opacity',
03:39:10 INFO - 'tart',
03:39:10 INFO - 'tscrollx',
03:39:10 INFO - 'tsvg_static']},
03:39:10 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['tsvgx',
03:39:10 INFO - 'tsvgr_opacity',
03:39:10 INFO - 'tart',
03:39:10 INFO - 'tscrollx',
03:39:10 INFO - 'tsvg_static']},
03:39:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
03:39:10 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'talos_options': ['--disable-stylo'],
03:39:10 INFO - 'tests': ['tp5o']},
03:39:10 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:39:10 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:39:10 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:39:10 INFO - 'talos_options': ['--mitmproxy',
03:39:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:39:10 INFO - '--firstNonBlankPaint'],
03:39:10 INFO - 'tests': ['tp6_google',
03:39:10 INFO - 'tp6_youtube',
03:39:10 INFO - 'tp6_amazon',
03:39:10 INFO - 'tp6_facebook']},
03:39:10 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:39:10 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:39:10 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:39:10 INFO - 'talos_options': ['--disable-stylo',
03:39:10 INFO - '--mitmproxy',
03:39:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:39:10 INFO - '--firstNonBlankPaint'],
03:39:10 INFO - 'tests': ['tp6_google',
03:39:10 INFO - 'tp6_youtube',
03:39:10 INFO - 'tp6_amazon',
03:39:10 INFO - 'tp6_facebook']},
03:39:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:39:10 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:39:10 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:39:10 INFO - 'talos_options': ['--stylo-threads=1',
03:39:10 INFO - '--mitmproxy',
03:39:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:39:10 INFO - '--firstNonBlankPaint'],
03:39:10 INFO - 'tests': ['tp6_google',
03:39:10 INFO - 'tp6_youtube',
03:39:10 INFO - 'tp6_amazon',
03:39:10 INFO - 'tp6_facebook']},
03:39:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'talos_options': ['--xperf_path',
03:39:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:39:10 INFO - 'tests': ['tp5n']},
03:39:10 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:39:10 INFO - 'talos_options': ['--disable-stylo',
03:39:10 INFO - '--xperf_path',
03:39:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:39:10 INFO - 'tests': ['tp5n']}}}
03:39:10 INFO - Running post-action listener: _resource_record_post_action
03:39:10 INFO - [mozharness: 2017-11-02 10:39:10.417879Z] Finished populate-webroot step (success)
03:39:10 INFO - [mozharness: 2017-11-02 10:39:10.417980Z] Running create-virtualenv step.
03:39:10 INFO - Running pre-action listener: _resource_record_pre_action
03:39:10 INFO - Running main action method: create_virtualenv
03:39:10 INFO - Creating virtualenv /builds/slave/test/build/venv
03:39:10 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
03:39:10 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
03:39:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
03:39:10 INFO - Using real prefix '/usr'
03:39:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python
03:39:10 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
03:39:12 INFO - Installing setuptools, pip, wheel...done.
03:39:12 INFO - Return code: 0
03:39:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
03:39: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')]}
03:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39: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')]}
03:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:12 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:12 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
03:39:12 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
03:39:12 INFO - Using env: {'DISPLAY': ':0',
03:39:12 INFO - 'HOME': '/home/cltbld',
03:39:12 INFO - 'LANG': 'en_US.UTF-8',
03:39:12 INFO - 'LANGUAGE': 'en_US:en',
03:39:12 INFO - 'LOGNAME': 'cltbld',
03:39:12 INFO - 'MAIL': '/var/mail/cltbld',
03:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:12 INFO - 'MOZ_NO_REMOTE': '1',
03:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:12 INFO - 'NO_EM_RESTART': '1',
03:39:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:12 INFO - 'PWD': '/builds/slave/test',
03:39:12 INFO - 'SHELL': '/bin/bash',
03:39:12 INFO - 'SHLVL': '1',
03:39:12 INFO - 'TERM': 'linux',
03:39:12 INFO - 'TMOUT': '86400',
03:39:12 INFO - 'USER': 'cltbld',
03:39:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:12 INFO - '_': '/tools/buildbot/bin/python'}
03:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
03:39:12 INFO - Return code: 0
03:39:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
03:39: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')]}
03:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39: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')]}
03:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:12 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:12 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
03:39:12 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
03:39:12 INFO - Using env: {'DISPLAY': ':0',
03:39:12 INFO - 'HOME': '/home/cltbld',
03:39:12 INFO - 'LANG': 'en_US.UTF-8',
03:39:12 INFO - 'LANGUAGE': 'en_US:en',
03:39:12 INFO - 'LOGNAME': 'cltbld',
03:39:12 INFO - 'MAIL': '/var/mail/cltbld',
03:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:12 INFO - 'MOZ_NO_REMOTE': '1',
03:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:12 INFO - 'NO_EM_RESTART': '1',
03:39:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:12 INFO - 'PWD': '/builds/slave/test',
03:39:12 INFO - 'SHELL': '/bin/bash',
03:39:12 INFO - 'SHLVL': '1',
03:39:12 INFO - 'TERM': 'linux',
03:39:12 INFO - 'TMOUT': '86400',
03:39:12 INFO - 'USER': 'cltbld',
03:39:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:12 INFO - '_': '/tools/buildbot/bin/python'}
03:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:13 INFO - Collecting psutil>=3.1.1
03:39:14 INFO - Installing collected packages: psutil
03:39:14 INFO - Successfully installed psutil-3.1.1
03:39:14 INFO - Return code: 0
03:39:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
03:39:14 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')]}
03:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39:14 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')]}
03:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:14 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:14 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
03:39:14 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
03:39:14 INFO - Using env: {'DISPLAY': ':0',
03:39:14 INFO - 'HOME': '/home/cltbld',
03:39:14 INFO - 'LANG': 'en_US.UTF-8',
03:39:14 INFO - 'LANGUAGE': 'en_US:en',
03:39:14 INFO - 'LOGNAME': 'cltbld',
03:39:14 INFO - 'MAIL': '/var/mail/cltbld',
03:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:14 INFO - 'MOZ_NO_REMOTE': '1',
03:39:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:14 INFO - 'NO_EM_RESTART': '1',
03:39:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:14 INFO - 'PWD': '/builds/slave/test',
03:39:14 INFO - 'SHELL': '/bin/bash',
03:39:14 INFO - 'SHLVL': '1',
03:39:14 INFO - 'TERM': 'linux',
03:39:14 INFO - 'TMOUT': '86400',
03:39:14 INFO - 'USER': 'cltbld',
03:39:14 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:14 INFO - '_': '/tools/buildbot/bin/python'}
03:39:14 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:14 INFO - Collecting mozsystemmonitor==0.3
03:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
03:39:15 INFO - Installing collected packages: mozsystemmonitor
03:39:16 INFO - Successfully installed mozsystemmonitor-0.3
03:39:16 INFO - Return code: 0
03:39:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
03:39:16 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')]}
03:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39:16 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')]}
03:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:16 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:16 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
03:39:16 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
03:39:16 INFO - Using env: {'DISPLAY': ':0',
03:39:16 INFO - 'HOME': '/home/cltbld',
03:39:16 INFO - 'LANG': 'en_US.UTF-8',
03:39:16 INFO - 'LANGUAGE': 'en_US:en',
03:39:16 INFO - 'LOGNAME': 'cltbld',
03:39:16 INFO - 'MAIL': '/var/mail/cltbld',
03:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:16 INFO - 'MOZ_NO_REMOTE': '1',
03:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:16 INFO - 'NO_EM_RESTART': '1',
03:39:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:16 INFO - 'PWD': '/builds/slave/test',
03:39:16 INFO - 'SHELL': '/bin/bash',
03:39:16 INFO - 'SHLVL': '1',
03:39:16 INFO - 'TERM': 'linux',
03:39:16 INFO - 'TMOUT': '86400',
03:39:16 INFO - 'USER': 'cltbld',
03:39:16 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:16 INFO - '_': '/tools/buildbot/bin/python'}
03:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:16 INFO - Collecting jsonschema==2.5.1
03:39:17 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
03:39:17 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
03:39:18 INFO - Installing collected packages: functools32, jsonschema
03:39:18 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
03:39:18 INFO - Return code: 0
03:39:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
03:39:18 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')]}
03:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39:18 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')]}
03:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:18 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:18 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
03:39:18 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
03:39:18 INFO - Using env: {'DISPLAY': ':0',
03:39:18 INFO - 'HOME': '/home/cltbld',
03:39:18 INFO - 'LANG': 'en_US.UTF-8',
03:39:18 INFO - 'LANGUAGE': 'en_US:en',
03:39:18 INFO - 'LOGNAME': 'cltbld',
03:39:18 INFO - 'MAIL': '/var/mail/cltbld',
03:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:18 INFO - 'MOZ_NO_REMOTE': '1',
03:39:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:18 INFO - 'NO_EM_RESTART': '1',
03:39:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:18 INFO - 'PWD': '/builds/slave/test',
03:39:18 INFO - 'SHELL': '/bin/bash',
03:39:18 INFO - 'SHLVL': '1',
03:39:18 INFO - 'TERM': 'linux',
03:39:18 INFO - 'TMOUT': '86400',
03:39:18 INFO - 'USER': 'cltbld',
03:39:18 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:18 INFO - '_': '/tools/buildbot/bin/python'}
03:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
03:39:19 INFO - Return code: 0
03:39:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
03:39: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')]}
03:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39: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')]}
03:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:19 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:19 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
03:39:19 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
03:39:19 INFO - Using env: {'DISPLAY': ':0',
03:39:19 INFO - 'HOME': '/home/cltbld',
03:39:19 INFO - 'LANG': 'en_US.UTF-8',
03:39:19 INFO - 'LANGUAGE': 'en_US:en',
03:39:19 INFO - 'LOGNAME': 'cltbld',
03:39:19 INFO - 'MAIL': '/var/mail/cltbld',
03:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:19 INFO - 'MOZ_NO_REMOTE': '1',
03:39:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:19 INFO - 'NO_EM_RESTART': '1',
03:39:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:19 INFO - 'PWD': '/builds/slave/test',
03:39:19 INFO - 'SHELL': '/bin/bash',
03:39:19 INFO - 'SHLVL': '1',
03:39:19 INFO - 'TERM': 'linux',
03:39:19 INFO - 'TMOUT': '86400',
03:39:19 INFO - 'USER': 'cltbld',
03:39:19 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:19 INFO - '_': '/tools/buildbot/bin/python'}
03:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:19 INFO - Collecting blobuploader==1.2.4
03:39:20 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
03:39:21 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
03:39:22 INFO - Installing collected packages: requests, docopt, blobuploader
03:39:23 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
03:39:23 INFO - Return code: 0
03:39:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:39:23 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')]}
03:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39:23 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')]}
03:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:23 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:23 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
03:39:23 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
03:39:23 INFO - Using env: {'DISPLAY': ':0',
03:39:23 INFO - 'HOME': '/home/cltbld',
03:39:23 INFO - 'LANG': 'en_US.UTF-8',
03:39:23 INFO - 'LANGUAGE': 'en_US:en',
03:39:23 INFO - 'LOGNAME': 'cltbld',
03:39:23 INFO - 'MAIL': '/var/mail/cltbld',
03:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:23 INFO - 'MOZ_NO_REMOTE': '1',
03:39:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:23 INFO - 'NO_EM_RESTART': '1',
03:39:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:23 INFO - 'PWD': '/builds/slave/test',
03:39:23 INFO - 'SHELL': '/bin/bash',
03:39:23 INFO - 'SHLVL': '1',
03:39:23 INFO - 'TERM': 'linux',
03:39:23 INFO - 'TMOUT': '86400',
03:39:23 INFO - 'USER': 'cltbld',
03:39:23 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:23 INFO - '_': '/tools/buildbot/bin/python'}
03:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:23 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:39:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:39:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:39:26 INFO - Running setup.py install for manifestparser: started
03:39:27 INFO - Running setup.py install for manifestparser: finished with status 'done'
03:39:27 INFO - Running setup.py install for mozcrash: started
03:39:27 INFO - Running setup.py install for mozcrash: finished with status 'done'
03:39:27 INFO - Running setup.py install for mozdebug: started
03:39:27 INFO - Running setup.py install for mozdebug: finished with status 'done'
03:39:27 INFO - Running setup.py install for mozdevice: started
03:39:27 INFO - Running setup.py install for mozdevice: finished with status 'done'
03:39:27 INFO - Running setup.py install for mozfile: started
03:39:27 INFO - Running setup.py install for mozfile: finished with status 'done'
03:39:27 INFO - Running setup.py install for mozhttpd: started
03:39:28 INFO - Running setup.py install for mozhttpd: finished with status 'done'
03:39:28 INFO - Running setup.py install for mozinfo: started
03:39:28 INFO - Running setup.py install for mozinfo: finished with status 'done'
03:39:28 INFO - Running setup.py install for mozInstall: started
03:39:28 INFO - Running setup.py install for mozInstall: finished with status 'done'
03:39:28 INFO - Running setup.py install for mozleak: started
03:39:28 INFO - Running setup.py install for mozleak: finished with status 'done'
03:39:28 INFO - Running setup.py install for mozlog: started
03:39:29 INFO - Running setup.py install for mozlog: finished with status 'done'
03:39:29 INFO - Running setup.py install for moznetwork: started
03:39:29 INFO - Running setup.py install for moznetwork: finished with status 'done'
03:39:29 INFO - Running setup.py install for mozprocess: started
03:39:29 INFO - Running setup.py install for mozprocess: finished with status 'done'
03:39:29 INFO - Running setup.py install for mozprofile: started
03:39:29 INFO - Running setup.py install for mozprofile: finished with status 'done'
03:39:29 INFO - Running setup.py install for mozrunner: started
03:39:29 INFO - Running setup.py install for mozrunner: finished with status 'done'
03:39:29 INFO - Running setup.py install for mozscreenshot: started
03:39:30 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
03:39:30 INFO - Running setup.py install for moztest: started
03:39:30 INFO - Running setup.py install for moztest: finished with status 'done'
03:39:30 INFO - Running setup.py install for mozversion: started
03:39:30 INFO - Running setup.py install for mozversion: finished with status 'done'
03:39:30 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
03:39:30 INFO - Return code: 0
03:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:39:30 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')]}
03:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39:30 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')]}
03:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:30 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:30 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
03:39:30 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
03:39:30 INFO - Using env: {'DISPLAY': ':0',
03:39:30 INFO - 'HOME': '/home/cltbld',
03:39:30 INFO - 'LANG': 'en_US.UTF-8',
03:39:30 INFO - 'LANGUAGE': 'en_US:en',
03:39:30 INFO - 'LOGNAME': 'cltbld',
03:39:30 INFO - 'MAIL': '/var/mail/cltbld',
03:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:30 INFO - 'MOZ_NO_REMOTE': '1',
03:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:30 INFO - 'NO_EM_RESTART': '1',
03:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:30 INFO - 'PWD': '/builds/slave/test',
03:39:30 INFO - 'SHELL': '/bin/bash',
03:39:30 INFO - 'SHLVL': '1',
03:39:30 INFO - 'TERM': 'linux',
03:39:30 INFO - 'TMOUT': '86400',
03:39:30 INFO - 'USER': 'cltbld',
03:39:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:30 INFO - '_': '/tools/buildbot/bin/python'}
03:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:31 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:39:31 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))
03:39:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:39:31 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))
03:39:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:39:31 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))
03:39:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:39:32 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))
03:39:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:39:32 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))
03:39:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:39:32 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))
03:39:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:39:32 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))
03:39:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:39:32 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))
03:39:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:39:33 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))
03:39:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:39:33 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))
03:39:33 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:39:33 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))
03:39:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:39:33 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))
03:39:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:39:33 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))
03:39:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:39:34 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))
03:39:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:39:34 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))
03:39:34 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:39:34 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))
03:39:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:39:34 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))
03:39:34 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:39:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
03:39:35 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))
03:39:35 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))
03:39:35 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))
03:39:35 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))
03:39:35 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))
03:39:35 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))
03:39:35 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:39:36 INFO - Installing collected packages: six, blessings
03:39:37 INFO - Successfully installed blessings-1.6 six-1.10.0
03:39:37 INFO - Return code: 0
03:39:37 INFO - Done creating virtualenv /builds/slave/test/build/venv.
03:39:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:39:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:39:37 INFO - Reading from file tmpfile_stdout
03:39:37 INFO - Reading from file tmpfile_stderr
03:39:37 INFO - Current package versions:
03:39:37 INFO - blessings == 1.6
03:39:37 INFO - blobuploader == 1.2.4
03:39:37 INFO - docopt == 0.6.1
03:39:37 INFO - functools32 == 3.2.3.post2
03:39:37 INFO - jsonschema == 2.5.1
03:39:37 INFO - manifestparser == 1.2
03:39:37 INFO - mozInstall == 1.14
03:39:37 INFO - mozcrash == 1.0
03:39:37 INFO - mozdebug == 0.1
03:39:37 INFO - mozdevice == 0.51
03:39:37 INFO - mozfile == 1.2
03:39:37 INFO - mozhttpd == 0.7
03:39:37 INFO - mozinfo == 0.10
03:39:37 INFO - mozleak == 0.1
03:39:37 INFO - mozlog == 3.5
03:39:37 INFO - moznetwork == 0.27
03:39:37 INFO - mozprocess == 0.25
03:39:37 INFO - mozprofile == 0.29
03:39:37 INFO - mozrunner == 6.13
03:39:37 INFO - mozscreenshot == 0.1
03:39:37 INFO - mozsystemmonitor == 0.3
03:39:37 INFO - moztest == 0.8
03:39:37 INFO - mozversion == 1.4
03:39:37 INFO - psutil == 3.1.1
03:39:37 INFO - requests == 1.2.3
03:39:37 INFO - six == 1.10.0
03:39:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:39:37 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')]}
03:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39:37 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')]}
03:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:37 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:37 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
03:39:37 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
03:39:37 INFO - Using env: {'DISPLAY': ':0',
03:39:37 INFO - 'HOME': '/home/cltbld',
03:39:37 INFO - 'LANG': 'en_US.UTF-8',
03:39:37 INFO - 'LANGUAGE': 'en_US:en',
03:39:37 INFO - 'LOGNAME': 'cltbld',
03:39:37 INFO - 'MAIL': '/var/mail/cltbld',
03:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:37 INFO - 'MOZ_NO_REMOTE': '1',
03:39:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:37 INFO - 'NO_EM_RESTART': '1',
03:39:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:37 INFO - 'PWD': '/builds/slave/test',
03:39:37 INFO - 'SHELL': '/bin/bash',
03:39:37 INFO - 'SHLVL': '1',
03:39:37 INFO - 'TERM': 'linux',
03:39:37 INFO - 'TMOUT': '86400',
03:39:37 INFO - 'USER': 'cltbld',
03:39:37 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:37 INFO - '_': '/tools/buildbot/bin/python'}
03:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:37 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))
03:39:37 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))
03:39:37 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))
03:39:37 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))
03:39:37 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))
03:39:37 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))
03:39:37 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))
03:39:38 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))
03:39:38 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))
03:39:38 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
03:39:39 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:39:40 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
03:39:40 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))
03:39:40 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))
03:39:40 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))
03:39:40 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))
03:39:41 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
03:39:41 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:39:42 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
03:39:42 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:39:43 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
03:39:43 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:39:44 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
03:39:44 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
03:39:45 INFO - Found existing installation: requests 1.2.3
03:39:45 INFO - Uninstalling requests-1.2.3:
03:39:45 INFO - Successfully uninstalled requests-1.2.3
03:39:45 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
03:39:45 INFO - Return code: 0
03:39:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
03:39:45 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')]}
03:39:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:39:45 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')]}
03:39:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:39:45 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 0xfb62d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1331930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440', '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
03:39:45 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
03:39:45 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
03:39:45 INFO - Using env: {'DISPLAY': ':0',
03:39:45 INFO - 'HOME': '/home/cltbld',
03:39:45 INFO - 'LANG': 'en_US.UTF-8',
03:39:45 INFO - 'LANGUAGE': 'en_US:en',
03:39:45 INFO - 'LOGNAME': 'cltbld',
03:39:45 INFO - 'MAIL': '/var/mail/cltbld',
03:39:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:39:45 INFO - 'MOZ_NO_REMOTE': '1',
03:39:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:39:45 INFO - 'NO_EM_RESTART': '1',
03:39:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:39:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:39:45 INFO - 'PWD': '/builds/slave/test',
03:39:45 INFO - 'SHELL': '/bin/bash',
03:39:45 INFO - 'SHLVL': '1',
03:39:45 INFO - 'TERM': 'linux',
03:39:45 INFO - 'TMOUT': '86400',
03:39:45 INFO - 'USER': 'cltbld',
03:39:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:39:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:39:45 INFO - '_': '/tools/buildbot/bin/python'}
03:39:45 INFO - Ignoring indexes: https://pypi.python.org/simple
03:39:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
03:39:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
03:39:45 INFO - Return code: 0
03:39:45 INFO - Running post-action listener: _resource_record_post_action
03:39:45 INFO - Running post-action listener: _start_resource_monitoring
03:39:45 INFO - Starting resource monitoring.
03:39:45 INFO - [mozharness: 2017-11-02 10:39:45.781804Z] Finished create-virtualenv step (success)
03:39:45 INFO - [mozharness: 2017-11-02 10:39:45.782260Z] Running install step.
03:39:45 INFO - Running pre-action listener: _resource_record_pre_action
03:39:45 INFO - Running main action method: install
03:39:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:39:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:39:46 INFO - Reading from file tmpfile_stdout
03:39:46 INFO - Reading from file tmpfile_stderr
03:39:46 INFO - Detecting whether we're running mozinstall >=1.0...
03:39:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
03:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
03:39:46 INFO - Reading from file tmpfile_stdout
03:39:46 INFO - Output received:
03:39:46 INFO - Usage: mozinstall [options] installer
03:39:46 INFO - Options:
03:39:46 INFO - -h, --help show this help message and exit
03:39:46 INFO - -d DEST, --destination=DEST
03:39:46 INFO - Directory to install application into. [default:
03:39:46 INFO - "/builds/slave/test"]
03:39:46 INFO - --app=APP Application being installed. [default: firefox]
03:39:46 INFO - mkdir: /builds/slave/test/build/application
03:39:46 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']
03:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
03:40:04 INFO - Reading from file tmpfile_stdout
03:40:04 INFO - Output received:
03:40:04 INFO - /builds/slave/test/build/application/firefox/firefox
03:40:04 INFO - Running post-action listener: _resource_record_post_action
03:40:04 INFO - [mozharness: 2017-11-02 10:40:04.746465Z] Finished install step (success)
03:40:04 INFO - [mozharness: 2017-11-02 10:40:04.746611Z] Running setup-mitmproxy step.
03:40:04 INFO - Running pre-action listener: _resource_record_pre_action
03:40:04 INFO - Running main action method: setup_mitmproxy
03:40:04 INFO - Skipping: mitmproxy is not required
03:40:04 INFO - Running post-action listener: _resource_record_post_action
03:40:04 INFO - [mozharness: 2017-11-02 10:40:04.748001Z] Finished setup-mitmproxy step (success)
03:40:04 INFO - [mozharness: 2017-11-02 10:40:04.748309Z] Running run-tests step.
03:40:04 INFO - Running pre-action listener: _resource_record_pre_action
03:40:04 INFO - Running pre-action listener: _set_gcov_prefix
03:40:04 INFO - Running main action method: run_tests
03:40:04 WARNING - Try message not found.
03:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
03:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
03:40:04 INFO - Python 2.7.3
03:40:04 INFO - Return code: 0
03:40:04 INFO - grabbing minidump binary from tooltool
03:40: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')]}
03:40:04 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 0x132ead0>, '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 0x132f9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x132fe60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
03:40:04 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
03:40:04 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
03:40:04 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
03:40:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
03:40:04 INFO - Return code: 0
03:40:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
03:40:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
03:40:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
03:40:04 INFO - ENV: RUST_BACKTRACE is now full
03:40:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
03:40:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
03:40:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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
03:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-002 --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
03:40:04 INFO - Using env: {'DISPLAY': ':0',
03:40:04 INFO - 'HOME': '/home/cltbld',
03:40:04 INFO - 'LANG': 'en_US.UTF-8',
03:40:04 INFO - 'LANGUAGE': 'en_US:en',
03:40:04 INFO - 'LOGNAME': 'cltbld',
03:40:04 INFO - 'MAIL': '/var/mail/cltbld',
03:40:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:40:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:40:04 INFO - 'MOZ_NO_REMOTE': '1',
03:40:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:40:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:40:04 INFO - 'NO_EM_RESTART': '1',
03:40:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:40:04 INFO - 'PWD': '/builds/slave/test',
03:40:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:40:04 INFO - 'RUST_BACKTRACE': 'full',
03:40:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:40:04 INFO - 'SHELL': '/bin/bash',
03:40:04 INFO - 'SHLVL': '1',
03:40:04 INFO - 'TERM': 'linux',
03:40:04 INFO - 'TMOUT': '86400',
03:40:04 INFO - 'USER': 'cltbld',
03:40:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:40:04 INFO - '_': '/tools/buildbot/bin/python'}
03:40:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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
03:40:05 INFO - mozversion application_buildid: 20171102094130
03:40:05 INFO - mozversion application_changeset: cb05c80b965520825841dfa003177790faff635b
03:40:05 INFO - mozversion application_display_name: Nightly
03:40:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:40:05 INFO - mozversion application_name: Firefox
03:40:05 INFO - mozversion application_remotingname: firefox
03:40:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
03:40:05 INFO - mozversion application_vendor: Mozilla
03:40:05 INFO - mozversion application_version: 58.0a1
03:40:05 INFO - mozversion platform_buildid: 20171102094130
03:40:05 INFO - mozversion platform_changeset: cb05c80b965520825841dfa003177790faff635b
03:40:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
03:40:05 INFO - mozversion platform_version: 58.0a1
03:40:05 INFO - using testdate: 1509619205
03:40:05 INFO - actual date: 1509619205
03:40:05 INFO - starting webserver on 'localhost:53829'
03:40:05 INFO - SUITE-START | Running 3 tests
03:40:05 INFO - TEST-START | basic_compositor_video
03:40:05 INFO - Initialising browser for basic_compositor_video test...
03:40:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53829/getInfo.html -profile /tmp/tmpde0gEs/profile
03:40:05 INFO - TEST-INFO | started process 19959 (/builds/slave/test/build/application/firefox/firefox http://localhost:53829/getInfo.html)
03:40:11 INFO - TEST-INFO | 19959: exit 0
03:40:11 INFO - Browser initialized.
03:40:11 INFO - Running cycle 1/1 for basic_compositor_video test...
03:40:11 INFO - Using env: {'DISPLAY': ':0',
03:40:11 INFO - 'HOME': '/home/cltbld',
03:40:11 INFO - 'JSGC_DISABLE_POISONING': '1',
03:40:11 INFO - 'LANG': 'en_US.UTF-8',
03:40:11 INFO - 'LANGUAGE': 'en_US:en',
03:40:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:40:11 INFO - 'LOGNAME': 'cltbld',
03:40:11 INFO - 'MAIL': '/var/mail/cltbld',
03:40:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:40:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:40:11 INFO - 'MOZ_CRASHREPORTER': '1',
03:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:40:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:40:11 INFO - 'MOZ_NO_REMOTE': '1',
03:40:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:40:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:40:11 INFO - 'NO_EM_RESTART': '1',
03:40:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:40:11 INFO - 'PWD': '/builds/slave/test',
03:40:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:40:11 INFO - 'RUST_BACKTRACE': 'full',
03:40:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:40:11 INFO - 'SHELL': '/bin/bash',
03:40:11 INFO - 'SHLVL': '1',
03:40:11 INFO - 'TERM': 'linux',
03:40:11 INFO - 'TMOUT': '86400',
03:40:11 INFO - 'USER': 'cltbld',
03:40:11 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:40:11 INFO - '_': '/tools/buildbot/bin/python'}
03:40:11 INFO - TEST-INFO | started process 20230 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpde0gEs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
03:40:11 INFO - PID 20230 |
03:40:11 INFO - PID 20230 | (/builds/slave/test/build/application/firefox/firefox:20281): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:40:11 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.678358501118568 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6723160535117059 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.740281322505801 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7437140366172617 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.133595041322314 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.193249475890986 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.4806206496519723 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.560533807829182 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.74918582375479 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.684332386363632 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 9.711925566343044 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.903242574257435 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.36511363636362 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81275675675676 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.146194331983809 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.836715976331353 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.542250000000003 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.992609890109884 ms/frame
03:40:58 INFO - PID 20230 |
03:40:58 INFO - PID 20230 | Cycle 1(1): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:41:44 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6820936098654709 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6686613844870726 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7744145476049673 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7849464763603935 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 3.953959156785244 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.016425702811242 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.481403712296985 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4130204778156976 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.694487666034154 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.8309912536443225 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 9.773843648208468 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.664951690821258 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.323226415094348 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.947814207650257 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.504229166666665 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.699093567251495 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.324603773584894 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.990494505494528 ms/frame
03:41:44 INFO - PID 20230 |
03:41:44 INFO - PID 20230 | Cycle 1(2): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:42:30 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682975322490185 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6701794657762938 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.789108527131783 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7719530558015941 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.173198887343534 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.247600849256898 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.6232850241545913 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.472482638888885 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.838317120622569 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.733237822349574 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 9.616490384615377 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.757317073170732 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.630290697674411 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.870081521739136 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.712118644067786 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.768088235294144 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.587220077220092 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.055165745856357 ms/frame
03:42:30 INFO - PID 20230 |
03:42:30 INFO - PID 20230 | Cycle 1(3): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:43:16 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6809523809523812 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689366138448707 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8264850882531953 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7803914590747338 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0773505434782615 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1668125 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.488656976744185 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4021258503401373 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715009523809526 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.377661290322585 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 10.137162162162157 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 8.974103139013463 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.451183206106853 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.873478260869572 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.448423236514522 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.76605882352943 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584382239382252 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.933989071038242 ms/frame
03:43:16 INFO - PID 20230 |
03:43:16 INFO - PID 20230 | Cycle 1(4): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:44:03 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6856039325842698 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685613010842368 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7915970149253733 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7488592657342654 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.219746835443038 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.124082474226805 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.5255934195064635 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4256078767123292 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.71615238095238 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5584166666666635 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 9.871644736842104 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 10.155177664974618 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.19563432835821 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.870516304347802 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.50245833333335 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052891566265053 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58455598455599 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.989890109890089 ms/frame
03:44:03 INFO - PID 20230 |
03:44:03 INFO - PID 20230 | Cycle 1(5): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:44:49 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6811764705882353 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669891485809683 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7774200236966824 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7362109375000006 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.098927595628414 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.185230125523014 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.641674757281553 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5987230215827326 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.83672178988327 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.482191780821918 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 9.836475409836066 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.61600961538462 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.236704119850204 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.697433155080205 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.397768595041331 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.69587719298243 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.498218390804611 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.117222222222214 ms/frame
03:44:49 INFO - PID 20230 |
03:44:49 INFO - PID 20230 | Cycle 1(6): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:45:12 INFO - PID 20230 |
03:45:12 INFO - PID 20230 | (/builds/slave/test/build/application/firefox/firefox:20361): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:45:12 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6793788472299944 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6698664440734559 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8045820805772703 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7594371152154789 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.190907821229051 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.220369198312236 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.676770833333334 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.578184257602859 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.641043233082707 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.390902964959572 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 9.554538216560509 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.807426470588219 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.585984555984572 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.756182795698933 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.607310924369752 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.698099415204679 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.62895348837208 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.300593220338992 ms/frame
03:45:35 INFO - PID 20230 |
03:45:35 INFO - PID 20230 | Cycle 1(7): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:46:21 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681959080717489 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.665911740216486 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7775296208530806 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.772174490699734 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.244893917963224 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.032731854838711 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.6325423728813577 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5347614840989388 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.826174757281553 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.637183098591551 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 9.80549019607843 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.709393203883504 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.411026615969575 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.989313186813188 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.556108786610864 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.911309523809516 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.323301886792436 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.81645945945949 ms/frame
03:46:21 INFO - PID 20230 |
03:46:21 INFO - PID 20230 | Cycle 1(8): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:47:07 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6926706147772137 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6656577851790177 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7417382472431806 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.735151777970511 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.087976839237058 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.125298969072162 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.659250000000001 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5733482142857156 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.825902912621359 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.510592286501371 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 10.2050850340136 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.758146341463407 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.628953488372094 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.992115384615397 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.609453781512617 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.196768292682952 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.719902343750022 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.989065934065906 ms/frame
03:47:07 INFO - PID 20230 |
03:47:07 INFO - PID 20230 | Cycle 1(9): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:47:53 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800951847704368 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6728678929765883 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7872721858248957 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7361718750000006 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033823924731184 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.184696652719665 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.530652941176471 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.407734241908007 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.804429400386846 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.618623595505617 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 10.137956081081079 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.757341463414638 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.365568181818176 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.815459459459463 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39842975206614 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.765264705882316 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.496264367816098 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.366107954545441 ms/frame
03:47:53 INFO - PID 20230 |
03:47:53 INFO - PID 20230 | Cycle 1(10): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:48:40 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682099215246637 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6668000000000003 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.792625448028674 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7683068081343936 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.255914893617021 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.042454545454544 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.672050183598534 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5590124555160094 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.736644359464626 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.604859943977595 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 10.102592592592597 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.480071090047385 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.674669260700387 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.758736559139784 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.45273858921162 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.277147239263776 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.629321705426378 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113361111111105 ms/frame
03:48:40 INFO - PID 20230 |
03:48:40 INFO - PID 20230 | Cycle 1(11): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:49:26 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6845339696799553 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6695617696160265 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.800903361344538 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.8123867753623188 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1788927576601695 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.202951680672268 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_startup = 3.5259283196239704 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4496293103448274 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.826407766990296 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.748635057471258 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_startup = 9.68098387096774 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.480p.60fps.webm_scale_2_inclip = 9.481658767772517 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.451870229007644 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.869809782608685 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347304526748959 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766588235294115 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.63197674418603 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11200000000002 ms/frame
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | Cycle 1(12): loaded http://localhost:53829/tests/video/video_playback.html (next: http://localhost:53829/tests/video/video_playback.html)
03:49:26 INFO - PID 20230 | __start_tp_report
03:49:26 INFO - PID 20230 | _x_x_mozilla_page_load
03:49:26 INFO - PID 20230 | _x_x_mozilla_page_load_details
03:49:26 INFO - PID 20230 | |i|pagename|runs|
03:49:26 INFO - PID 20230 | |0;240p.120fps.mp4_scale_1_startup;1.678358501118568;1.6820936098654709;1.682975322490185;1.6809523809523812;1.6856039325842698;1.6811764705882353;1.6793788472299944;1.681959080717489;1.6926706147772137;1.6800951847704368;1.682099215246637;1.6845339696799553
03:49:26 INFO - PID 20230 | |1;240p.120fps.mp4_scale_1_inclip;1.6723160535117059;1.6686613844870726;1.6701794657762938;1.6689366138448707;1.6685613010842368;1.669891485809683;1.6698664440734559;1.665911740216486;1.6656577851790177;1.6728678929765883;1.6668000000000003;1.6695617696160265
03:49:26 INFO - PID 20230 | |2;240p.120fps.mp4_scale_1.1_startup;1.740281322505801;1.7744145476049673;1.789108527131783;1.8264850882531953;1.7915970149253733;1.7774200236966824;1.8045820805772703;1.7775296208530806;1.7417382472431806;1.7872721858248957;1.792625448028674;1.800903361344538
03:49:26 INFO - PID 20230 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7437140366172617;1.7849464763603935;1.7719530558015941;1.7803914590747338;1.7488592657342654;1.7362109375000006;1.7594371152154789;1.772174490699734;1.735151777970511;1.7361718750000006;1.7683068081343936;1.8123867753623188
03:49:26 INFO - PID 20230 | |4;240p.120fps.mp4_scale_2_startup;4.133595041322314;3.953959156785244;4.173198887343534;4.0773505434782615;4.219746835443038;4.098927595628414;4.190907821229051;4.244893917963224;4.087976839237058;4.033823924731184;4.255914893617021;4.1788927576601695
03:49:26 INFO - PID 20230 | |5;240p.120fps.mp4_scale_2_inclip;4.193249475890986;4.016425702811242;4.247600849256898;4.1668125;4.124082474226805;4.185230125523014;4.220369198312236;4.032731854838711;4.125298969072162;4.184696652719665;4.042454545454544;4.202951680672268
03:49:26 INFO - PID 20230 | |6;480p.60fps.webm_scale_1_startup;3.4806206496519723;3.481403712296985;3.6232850241545913;3.488656976744185;3.5255934195064635;3.641674757281553;3.676770833333334;3.6325423728813577;3.659250000000001;3.530652941176471;3.672050183598534;3.5259283196239704
03:49:26 INFO - PID 20230 | |7;480p.60fps.webm_scale_1_inclip;3.560533807829182;3.4130204778156976;3.472482638888885;3.4021258503401373;3.4256078767123292;3.5987230215827326;3.578184257602859;3.5347614840989388;3.5733482142857156;3.407734241908007;3.5590124555160094;3.4496293103448274
03:49:26 INFO - PID 20230 | |8;480p.60fps.webm_scale_1.1_startup;5.74918582375479;5.694487666034154;5.838317120622569;5.715009523809526;5.71615238095238;5.83672178988327;5.641043233082707;5.826174757281553;5.825902912621359;5.804429400386846;5.736644359464626;5.826407766990296
03:49:26 INFO - PID 20230 | |9;480p.60fps.webm_scale_1.1_inclip;5.684332386363632;5.8309912536443225;5.733237822349574;5.377661290322585;5.5584166666666635;5.482191780821918;5.390902964959572;5.637183098591551;5.510592286501371;5.618623595505617;5.604859943977595;5.748635057471258
03:49:26 INFO - PID 20230 | |10;480p.60fps.webm_scale_2_startup;9.711925566343044;9.773843648208468;9.616490384615377;10.137162162162157;9.871644736842104;9.836475409836066;9.554538216560509;9.80549019607843;10.2050850340136;10.137956081081079;10.102592592592597;9.68098387096774
03:49:26 INFO - PID 20230 | |11;480p.60fps.webm_scale_2_inclip;9.903242574257435;9.664951690821258;9.757317073170732;8.974103139013463;10.155177664974618;9.61600961538462;9.807426470588219;9.709393203883504;9.758146341463407;9.757341463414638;9.480071090047385;9.481658767772517
03:49:26 INFO - PID 20230 | |12;1080p.60fps.mp4_scale_1_startup;11.36511363636362;11.323226415094348;11.630290697674411;11.451183206106853;11.19563432835821;11.236704119850204;11.585984555984572;11.411026615969575;11.628953488372094;11.365568181818176;11.674669260700387;11.451870229007644
03:49:26 INFO - PID 20230 | |13;1080p.60fps.mp4_scale_1_inclip;10.81275675675676;10.947814207650257;10.870081521739136;10.873478260869572;10.870516304347802;10.697433155080205;10.756182795698933;10.989313186813188;10.992115384615397;10.815459459459463;10.758736559139784;10.869809782608685
03:49:26 INFO - PID 20230 | |14;1080p.60fps.mp4_scale_1.1_startup;12.146194331983809;12.504229166666665;12.712118644067786;12.448423236514522;12.50245833333335;12.397768595041331;12.607310924369752;12.556108786610864;12.609453781512617;12.39842975206614;12.45273858921162;12.347304526748959
03:49:26 INFO - PID 20230 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.836715976331353;11.699093567251495;11.768088235294144;11.76605882352943;12.052891566265053;11.69587719298243;11.698099415204679;11.911309523809516;12.196768292682952;11.765264705882316;12.277147239263776;11.766588235294115
03:49:26 INFO - PID 20230 | |16;1080p.60fps.mp4_scale_2_startup;11.542250000000003;11.324603773584894;11.587220077220092;11.584382239382252;11.58455598455599;11.498218390804611;11.62895348837208;11.323301886792436;11.719902343750022;11.496264367816098;11.629321705426378;11.63197674418603
03:49:26 INFO - PID 20230 | |17;1080p.60fps.mp4_scale_2_inclip;10.992609890109884;10.990494505494528;11.055165745856357;10.933989071038242;10.989890109890089;11.117222222222214;11.300593220338992;10.81645945945949;10.989065934065906;11.366107954545441;11.113361111111105;11.11200000000002
03:49:26 INFO - PID 20230 | __end_tp_report
03:49:26 INFO - PID 20230 | __start_cc_report
03:49:26 INFO - PID 20230 | _x_x_mozilla_cycle_collect,615
03:49:26 INFO - PID 20230 | __end_cc_report
03:49:26 INFO - PID 20230 | __startTimestamp1509619766515__endTimestamp
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | ------- Summary: start -------
03:49:26 INFO - PID 20230 | Number of tests: 18
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.2%) stddev-sans-first:0.00
03:49:26 INFO - PID 20230 | 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
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#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
03:49:26 INFO - PID 20230 | 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
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.78 Median:1.79 stddev:0.02 (1.4%) stddev-sans-first:0.02
03:49:26 INFO - PID 20230 | Values: 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.7 1.8 1.8 1.8
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.76 Median:1.77 stddev:0.02 (1.3%) stddev-sans-first:0.02
03:49:26 INFO - PID 20230 | Values: 1.7 1.8 1.8 1.8 1.7 1.7 1.8 1.8 1.7 1.7 1.8 1.8
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.14 Median:4.18 stddev:0.09 (2.2%) stddev-sans-first:0.09
03:49:26 INFO - PID 20230 | Values: 4.1 4.0 4.2 4.1 4.2 4.1 4.2 4.2 4.1 4.0 4.3 4.2
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.15 Median:4.18 stddev:0.08 (1.9%) stddev-sans-first:0.08
03:49:26 INFO - PID 20230 | Values: 4.2 4.0 4.2 4.2 4.1 4.2 4.2 4.0 4.1 4.2 4.0 4.2
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.58 Median:3.63 stddev:0.08 (2.2%) stddev-sans-first:0.08
03:49:26 INFO - PID 20230 | Values: 3.5 3.5 3.6 3.5 3.5 3.6 3.7 3.6 3.7 3.5 3.7 3.5
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.50 Median:3.55 stddev:0.08 (2.2%) stddev-sans-first:0.08
03:49:26 INFO - PID 20230 | Values: 3.6 3.4 3.5 3.4 3.4 3.6 3.6 3.5 3.6 3.4 3.6 3.4
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.77 Median:5.82 stddev:0.07 (1.2%) stddev-sans-first:0.07
03:49:26 INFO - PID 20230 | Values: 5.7 5.7 5.8 5.7 5.7 5.8 5.6 5.8 5.8 5.8 5.7 5.8
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.60 Median:5.63 stddev:0.14 (2.5%) stddev-sans-first:0.15
03:49:26 INFO - PID 20230 | Values: 5.7 5.8 5.7 5.4 5.6 5.5 5.4 5.6 5.5 5.6 5.6 5.7
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.87 Median:9.70 stddev:0.22 (2.3%) stddev-sans-first:0.23
03:49:26 INFO - PID 20230 | Values: 9.7 9.8 9.6 10.1 9.9 9.8 9.6 9.8 10.2 10.1 10.1 9.7
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.67 Median:9.73 stddev:0.28 (2.9%) stddev-sans-first:0.29
03:49:26 INFO - PID 20230 | Values: 9.9 9.7 9.8 9.0 10.2 9.6 9.8 9.7 9.8 9.8 9.5 9.5
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.44 Median:11.45 stddev:0.16 (1.4%) stddev-sans-first:0.16
03:49:26 INFO - PID 20230 | Values: 11.4 11.3 11.6 11.5 11.2 11.2 11.6 11.4 11.6 11.4 11.7 11.5
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.85 Median:10.87 stddev:0.09 (0.8%) stddev-sans-first:0.10
03:49:26 INFO - PID 20230 | Values: 10.8 10.9 10.9 10.9 10.9 10.7 10.8 11.0 11.0 10.8 10.8 10.9
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.47 Median:12.50 stddev:0.15 (1.2%) stddev-sans-first:0.11
03:49:26 INFO - PID 20230 | Values: 12.1 12.5 12.7 12.4 12.5 12.4 12.6 12.6 12.6 12.4 12.5 12.3
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.87 Median:11.80 stddev:0.20 (1.7%) stddev-sans-first:0.21
03:49:26 INFO - PID 20230 | Values: 11.8 11.7 11.8 11.8 12.1 11.7 11.7 11.9 12.2 11.8 12.3 11.8
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.55 Median:11.59 stddev:0.12 (1.0%) stddev-sans-first:0.13
03:49:26 INFO - PID 20230 | Values: 11.5 11.3 11.6 11.6 11.6 11.5 11.6 11.3 11.7 11.5 11.6 11.6
03:49:26 INFO - PID 20230 |
03:49:26 INFO - PID 20230 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.06 Median:11.08 stddev:0.15 (1.4%) stddev-sans-first:0.16
03:49:26 INFO - PID 20230 | Values: 11.0 11.0 11.1 10.9 11.0 11.1 11.3 10.8 11.0 11.4 11.1 11.1
03:49:26 INFO - PID 20230 | -------- Summary: end --------
03:49:26 INFO - PID 20230 |
03:49:27 INFO - TEST-INFO | 20230: exit 0
03:49:27 INFO - TEST-OK | basic_compositor_video | took 561963ms
03:49:27 INFO - TEST-START | glvideo
03:49:27 INFO - Initialising browser for glvideo test...
03:49:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53829/getInfo.html -profile /tmp/tmpeCPpN0/profile
03:49:27 INFO - TEST-INFO | started process 21539 (/builds/slave/test/build/application/firefox/firefox http://localhost:53829/getInfo.html)
03:49:33 INFO - TEST-INFO | 21539: exit 0
03:49:33 INFO - Browser initialized.
03:49:33 INFO - Running cycle 1/1 for glvideo test...
03:49:33 INFO - Using env: {'DISPLAY': ':0',
03:49:33 INFO - 'HOME': '/home/cltbld',
03:49:33 INFO - 'JSGC_DISABLE_POISONING': '1',
03:49:33 INFO - 'LANG': 'en_US.UTF-8',
03:49:33 INFO - 'LANGUAGE': 'en_US:en',
03:49:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:49:33 INFO - 'LOGNAME': 'cltbld',
03:49:33 INFO - 'MAIL': '/var/mail/cltbld',
03:49:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:49:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:49:33 INFO - 'MOZ_CRASHREPORTER': '1',
03:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:49:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:49:33 INFO - 'MOZ_NO_REMOTE': '1',
03:49:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:49:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:49:33 INFO - 'NO_EM_RESTART': '1',
03:49:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:49:33 INFO - 'PWD': '/builds/slave/test',
03:49:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:49:33 INFO - 'RUST_BACKTRACE': 'full',
03:49:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:49:33 INFO - 'SHELL': '/bin/bash',
03:49:33 INFO - 'SHLVL': '1',
03:49:33 INFO - 'TERM': 'linux',
03:49:33 INFO - 'TMOUT': '86400',
03:49:33 INFO - 'USER': 'cltbld',
03:49:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:49:33 INFO - '_': '/tools/buildbot/bin/python'}
03:49:33 INFO - TEST-INFO | started process 21810 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeCPpN0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:49:34 INFO - PID 21810 |
03:49:34 INFO - PID 21810 | (/builds/slave/test/build/application/firefox/firefox:21862): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:49:34 INFO - PID 21810 |
03:49:35 INFO - PID 21810 | [talos glvideo result] Mean tick time across 100 ticks: 6.811599999999999 ms
03:49:35 INFO - PID 21810 | Cycle 1(1): loaded http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html)
03:49:36 INFO - PID 21810 | [talos glvideo result] Mean tick time across 100 ticks: 6.531700000000001 ms
03:49:36 INFO - PID 21810 | Cycle 1(2): loaded http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html)
03:49:38 INFO - PID 21810 | [talos glvideo result] Mean tick time across 100 ticks: 6.443099999999999 ms
03:49:38 INFO - PID 21810 | Cycle 1(3): loaded http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html)
03:49:39 INFO - PID 21810 | [talos glvideo result] Mean tick time across 100 ticks: 6.644100000000001 ms
03:49:39 INFO - PID 21810 | Cycle 1(4): loaded http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html)
03:49:40 INFO - PID 21810 | [talos glvideo result] Mean tick time across 100 ticks: 6.6197 ms
03:49:40 INFO - PID 21810 | Cycle 1(5): loaded http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53829/tests/webgl/benchmarks/video/video_upload.html)
03:49:41 INFO - PID 21810 | __start_tp_report
03:49:41 INFO - PID 21810 | _x_x_mozilla_page_load
03:49:41 INFO - PID 21810 | _x_x_mozilla_page_load_details
03:49:41 INFO - PID 21810 | |i|pagename|runs|
03:49:41 INFO - PID 21810 | |0;Mean tick time across 100 ticks: ;6.811599999999999;6.531700000000001;6.443099999999999;6.644100000000001;6.6197
03:49:41 INFO - PID 21810 | __end_tp_report
03:49:41 INFO - PID 21810 | __start_cc_report
03:49:41 INFO - PID 21810 | _x_x_mozilla_cycle_collect,289
03:49:41 INFO - PID 21810 | __end_cc_report
03:49:41 INFO - PID 21810 | __startTimestamp1509619781023__endTimestamp
03:49:41 INFO - PID 21810 |
03:49:41 INFO - PID 21810 | ------- Summary: start -------
03:49:41 INFO - PID 21810 | Number of tests: 1
03:49:41 INFO - PID 21810 |
03:49:41 INFO - PID 21810 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.61 Median:6.62 stddev:0.14 (2.1%) stddev-sans-first:0.09
03:49:41 INFO - PID 21810 | Values: 6.8 6.5 6.4 6.6 6.6
03:49:41 INFO - PID 21810 | -------- Summary: end --------
03:49:41 INFO - PID 21810 |
03:49:41 INFO - PID 21810 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:49:41 INFO - TEST-INFO | 21810: exit 0
03:49:41 INFO - TEST-OK | glvideo | took 14532ms
03:49:41 INFO - TEST-START | displaylist_mutate
03:49:41 INFO - Initialising browser for displaylist_mutate test...
03:49:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53829/getInfo.html -profile /tmp/tmpAX11ou/profile
03:49:41 INFO - TEST-INFO | started process 22032 (/builds/slave/test/build/application/firefox/firefox http://localhost:53829/getInfo.html)
03:49:47 INFO - TEST-INFO | 22032: exit 0
03:49:47 INFO - Browser initialized.
03:49:47 INFO - Running cycle 1/1 for displaylist_mutate test...
03:49:47 INFO - Using env: {'DISPLAY': ':0',
03:49:47 INFO - 'HOME': '/home/cltbld',
03:49:47 INFO - 'JSGC_DISABLE_POISONING': '1',
03:49:47 INFO - 'LANG': 'en_US.UTF-8',
03:49:47 INFO - 'LANGUAGE': 'en_US:en',
03:49:47 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:49:47 INFO - 'LOGNAME': 'cltbld',
03:49:47 INFO - 'MAIL': '/var/mail/cltbld',
03:49:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:49:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:49:47 INFO - 'MOZ_CRASHREPORTER': '1',
03:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:49:47 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:49:47 INFO - 'MOZ_NO_REMOTE': '1',
03:49:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:49:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:49:47 INFO - 'NO_EM_RESTART': '1',
03:49:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:49:47 INFO - 'PWD': '/builds/slave/test',
03:49:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:49:47 INFO - 'RUST_BACKTRACE': 'full',
03:49:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:49:47 INFO - 'SHELL': '/bin/bash',
03:49:47 INFO - 'SHLVL': '1',
03:49:47 INFO - 'TERM': 'linux',
03:49:47 INFO - 'TMOUT': '86400',
03:49:47 INFO - 'USER': 'cltbld',
03:49:47 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440',
03:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:49:47 INFO - '_': '/tools/buildbot/bin/python'}
03:49:47 INFO - TEST-INFO | started process 22305 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAX11ou/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:49:48 INFO - PID 22305 |
03:49:48 INFO - PID 22305 | (/builds/slave/test/build/application/firefox/firefox:22356): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:49:48 INFO - PID 22305 |
03:50:09 INFO - PID 22305 | Cycle 1(1): loaded http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html)
03:50:09 INFO - PID 22305 | Waiting for idle-callback
03:50:30 INFO - PID 22305 | Cycle 1(2): loaded http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html)
03:50:52 INFO - PID 22305 | Cycle 1(3): loaded http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html)
03:51:13 INFO - PID 22305 | Cycle 1(4): loaded http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html)
03:51:34 INFO - PID 22305 | Cycle 1(5): loaded http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53829/tests/layout/benchmarks/displaylist_mutate.html)
03:51:35 INFO - PID 22305 | Waiting for idle-callback
03:51:35 INFO - PID 22305 | __start_tp_report
03:51:35 INFO - PID 22305 | _x_x_mozilla_page_load
03:51:35 INFO - PID 22305 | _x_x_mozilla_page_load_details
03:51:35 INFO - PID 22305 | |i|pagename|runs|
03:51:35 INFO - PID 22305 | |0;/displaylist_mutate.html;20293.055;20265.52;20553.07;20607.555;20335.715
03:51:35 INFO - PID 22305 | __end_tp_report
03:51:35 INFO - PID 22305 | __start_cc_report
03:51:35 INFO - PID 22305 | _x_x_mozilla_cycle_collect,224
03:51:35 INFO - PID 22305 | __end_cc_report
03:51:35 INFO - PID 22305 | __startTimestamp1509619895059__endTimestamp
03:51:35 INFO - PID 22305 |
03:51:35 INFO - PID 22305 | ------- Summary: start -------
03:51:35 INFO - PID 22305 | Number of tests: 1
03:51:35 INFO - PID 22305 |
03:51:35 INFO - PID 22305 | [#0] /displaylist_mutate.html Cycles:5 Average:20410.98 Median:20335.72 stddev:157.77 (0.8%) stddev-sans-first:165.51
03:51:35 INFO - PID 22305 | Values: 20293.1 20265.5 20553.1 20607.6 20335.7
03:51:35 INFO - PID 22305 | -------- Summary: end --------
03:51:35 INFO - PID 22305 |
03:51:35 INFO - TEST-INFO | 22305: exit 0
03:51:35 INFO - TEST-OK | displaylist_mutate | took 114010ms
03:51:35 INFO - SUITE-END | took 690s
03:51:35 INFO - Completed test suite (00:11:30)
03:51:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.631866859702686, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.678358501118568, 1.6820936098654709, 1.682975322490185, 1.6809523809523812, 1.6856039325842698, 1.6811764705882353, 1.6793788472299944, 1.681959080717489, 1.6926706147772137, 1.6800951847704368, 1.682099215246637, 1.6845339696799553], "value": 1.6820936098654709, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6723160535117059, 1.6686613844870726, 1.6701794657762938, 1.6689366138448707, 1.6685613010842368, 1.669891485809683, 1.6698664440734559, 1.665911740216486, 1.6656577851790177, 1.6728678929765883, 1.6668000000000003, 1.6695617696160265], "value": 1.6689366138448707, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.740281322505801, 1.7744145476049673, 1.789108527131783, 1.8264850882531953, 1.7915970149253733, 1.7774200236966824, 1.8045820805772703, 1.7775296208530806, 1.7417382472431806, 1.7872721858248957, 1.792625448028674, 1.800903361344538], "value": 1.789108527131783, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7437140366172617, 1.7849464763603935, 1.7719530558015941, 1.7803914590747338, 1.7488592657342654, 1.7362109375000006, 1.7594371152154789, 1.772174490699734, 1.735151777970511, 1.7361718750000006, 1.7683068081343936, 1.8123867753623188], "value": 1.7683068081343936, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.133595041322314, 3.953959156785244, 4.173198887343534, 4.0773505434782615, 4.219746835443038, 4.098927595628414, 4.190907821229051, 4.244893917963224, 4.087976839237058, 4.033823924731184, 4.255914893617021, 4.1788927576601695], "value": 4.173198887343534, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.193249475890986, 4.016425702811242, 4.247600849256898, 4.1668125, 4.124082474226805, 4.185230125523014, 4.220369198312236, 4.032731854838711, 4.125298969072162, 4.184696652719665, 4.042454545454544, 4.202951680672268], "value": 4.1668125, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4806206496519723, 3.481403712296985, 3.6232850241545913, 3.488656976744185, 3.5255934195064635, 3.641674757281553, 3.676770833333334, 3.6325423728813577, 3.659250000000001, 3.530652941176471, 3.672050183598534, 3.5259283196239704], "value": 3.6232850241545913, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.560533807829182, 3.4130204778156976, 3.472482638888885, 3.4021258503401373, 3.4256078767123292, 3.5987230215827326, 3.578184257602859, 3.5347614840989388, 3.5733482142857156, 3.407734241908007, 3.5590124555160094, 3.4496293103448274], "value": 3.472482638888885, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.74918582375479, 5.694487666034154, 5.838317120622569, 5.715009523809526, 5.71615238095238, 5.83672178988327, 5.641043233082707, 5.826174757281553, 5.825902912621359, 5.804429400386846, 5.736644359464626, 5.826407766990296], "value": 5.804429400386846, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.684332386363632, 5.8309912536443225, 5.733237822349574, 5.377661290322585, 5.5584166666666635, 5.482191780821918, 5.390902964959572, 5.637183098591551, 5.510592286501371, 5.618623595505617, 5.604859943977595, 5.748635057471258], "value": 5.604859943977595, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.711925566343044, 9.773843648208468, 9.616490384615377, 10.137162162162157, 9.871644736842104, 9.836475409836066, 9.554538216560509, 9.80549019607843, 10.2050850340136, 10.137956081081079, 10.102592592592597, 9.68098387096774], "value": 9.836475409836066, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.903242574257435, 9.664951690821258, 9.757317073170732, 8.974103139013463, 10.155177664974618, 9.61600961538462, 9.807426470588219, 9.709393203883504, 9.758146341463407, 9.757341463414638, 9.480071090047385, 9.481658767772517], "value": 9.709393203883504, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.36511363636362, 11.323226415094348, 11.630290697674411, 11.451183206106853, 11.19563432835821, 11.236704119850204, 11.585984555984572, 11.411026615969575, 11.628953488372094, 11.365568181818176, 11.674669260700387, 11.451870229007644], "value": 11.451183206106853, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.81275675675676, 10.947814207650257, 10.870081521739136, 10.873478260869572, 10.870516304347802, 10.697433155080205, 10.756182795698933, 10.989313186813188, 10.992115384615397, 10.815459459459463, 10.758736559139784, 10.869809782608685], "value": 10.870081521739136, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.146194331983809, 12.504229166666665, 12.712118644067786, 12.448423236514522, 12.50245833333335, 12.397768595041331, 12.607310924369752, 12.556108786610864, 12.609453781512617, 12.39842975206614, 12.45273858921162, 12.347304526748959], "value": 12.50245833333335, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.836715976331353, 11.699093567251495, 11.768088235294144, 11.76605882352943, 12.052891566265053, 11.69587719298243, 11.698099415204679, 11.911309523809516, 12.196768292682952, 11.765264705882316, 12.277147239263776, 11.766588235294115], "value": 11.766588235294115, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.542250000000003, 11.324603773584894, 11.587220077220092, 11.584382239382252, 11.58455598455599, 11.498218390804611, 11.62895348837208, 11.323301886792436, 11.719902343750022, 11.496264367816098, 11.629321705426378, 11.63197674418603], "value": 11.58455598455599, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.992609890109884, 10.990494505494528, 11.055165745856357, 10.933989071038242, 10.989890109890089, 11.117222222222214, 11.300593220338992, 10.81645945945949, 10.989065934065906, 11.366107954545441, 11.113361111111105, 11.11200000000002], "value": 11.055165745856357, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.811599999999999, 6.531700000000001, 6.443099999999999, 6.644100000000001, 6.6197], "value": 6.5757, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [20293.055, 20265.52, 20553.07, 20607.555, 20335.715], "value": 20444.3925, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
03:51:35 INFO - Return code: 0
03:51:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:51:35 INFO - # TBPL SUCCESS #
03:51:35 INFO - Running post-action listener: _package_coverage_data
03:51:35 INFO - Running post-action listener: _resource_record_post_action
03:51:35 INFO - [mozharness: 2017-11-02 10:51:35.952963Z] Finished run-tests step (success)
03:51:35 INFO - Running post-run listener: _resource_record_post_run
03:51:36 INFO - Total resource usage - Wall time: 710s; CPU: 35.0%; Read bytes: 2805760; Write bytes: 350646272; Read time: 372; Write time: 325768
03:51:36 INFO - TinderboxPrint: CPU usage
34.9%
03:51:36 INFO - TinderboxPrint: I/O read bytes / time
2,805,760 / 372
03:51:36 INFO - TinderboxPrint: I/O write bytes / time
350,646,272 / 325,768
03:51:36 INFO - TinderboxPrint: CPU idle
3,636.0 (65.1%)
03:51:36 INFO - TinderboxPrint: CPU system
319.4 (5.7%)
03:51:36 INFO - TinderboxPrint: CPU user
1,606.3 (28.8%)
03:51:36 INFO - TinderboxPrint: Swap in / out
0 / 0
03:51:36 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 7213056; Read time: 0; Write time: 756
03:51:36 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:51:36 INFO - run-tests - Wall time: 691s; CPU: 36.0%; Read bytes: 2805760; Write bytes: 342282240; Read time: 372; Write time: 324840
03:51:36 INFO - Running post-run listener: _upload_blobber_files
03:51:36 INFO - Blob upload gear active.
03:51:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:51:36 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
03:51:36 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
03:51:36 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
03:51:36 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
03:51:36 INFO - SNIMissingWarning
03:51:36 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
03:51:36 INFO - InsecurePlatformWarning
03:51:36 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.)
03:51:36 INFO - SubjectAltNameWarning
03:51:36 INFO - (blobuploader) - INFO - Open directory for files ...
03:51:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:51:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:51:36 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:51:36 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
03:51:36 INFO - InsecurePlatformWarning
03:51:37 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.)
03:51:37 INFO - SubjectAltNameWarning
03:51:37 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
03:51:37 INFO - InsecurePlatformWarning
03:51:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:51:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:51:37 INFO - (blobuploader) - INFO - Done attempting.
03:51:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
03:51:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:51:37 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:51:37 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
03:51:37 INFO - InsecurePlatformWarning
03:51:37 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.)
03:51:37 INFO - SubjectAltNameWarning
03:51:38 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
03:51:38 INFO - InsecurePlatformWarning
03:51:38 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
03:51:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:51:38 INFO - (blobuploader) - INFO - Done attempting.
03:51:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:51:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:51:38 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:51:38 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
03:51:38 INFO - InsecurePlatformWarning
03:51:38 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.)
03:51:38 INFO - SubjectAltNameWarning
03:51:43 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
03:51:43 INFO - InsecurePlatformWarning
03:51:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:51:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:51:44 INFO - (blobuploader) - INFO - Done attempting.
03:51:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
03:51:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:51:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:51:44 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
03:51:44 INFO - InsecurePlatformWarning
03:51:44 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.)
03:51:44 INFO - SubjectAltNameWarning
03:51:44 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
03:51:44 INFO - InsecurePlatformWarning
03:51:45 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
03:51:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:51:45 INFO - (blobuploader) - INFO - Done attempting.
03:51:45 INFO - (blobuploader) - INFO - Iteration through files over.
03:51:45 INFO - Return code: 0
03:51:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:51:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:51:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f86b74067b7b79c4ff61305de92753c083f84638b85eafc1ced5860a9a2b79a22e0a69c4940a53a1cdcee5d9714cfe6595ce5db9ea1c7df6f864b14489c1563", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef6d1bceaeb3590d1c888a66ab634950836aa7fe81212fbe406e61cf0da409c0221f96c967b3aa37e3534545870ff2ef2248f6722d12965af04ceaba363d92ab", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f18ebda8695bb23975d330ea34b30a22c150d8b145b57cfc0110b2f3c128da9b07d24fe416ae78fa3a5960ed2f57ce4f8be7deadba1f396ea214da66cf050d9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8451fa657cfb47ddcb07f2dad4329ad57d98ee4fc1fc237228048ba878a8160c5e54099d3e00697290e1d9200219dc1ae349dd08e215427688f6096074f7e0d"}
03:51:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:51:45 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:51:45 INFO - Contents:
03:51:45 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f86b74067b7b79c4ff61305de92753c083f84638b85eafc1ced5860a9a2b79a22e0a69c4940a53a1cdcee5d9714cfe6595ce5db9ea1c7df6f864b14489c1563", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef6d1bceaeb3590d1c888a66ab634950836aa7fe81212fbe406e61cf0da409c0221f96c967b3aa37e3534545870ff2ef2248f6722d12965af04ceaba363d92ab", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f18ebda8695bb23975d330ea34b30a22c150d8b145b57cfc0110b2f3c128da9b07d24fe416ae78fa3a5960ed2f57ce4f8be7deadba1f396ea214da66cf050d9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8451fa657cfb47ddcb07f2dad4329ad57d98ee4fc1fc237228048ba878a8160c5e54099d3e00697290e1d9200219dc1ae349dd08e215427688f6096074f7e0d"}
03:51:45 INFO - Running post-run listener: copy_logs_to_upload_dir
03:51:45 INFO - Copying logs to upload dir...
03:51:45 INFO - mkdir: /builds/slave/test/build/upload/logs
03:51:45 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=772.984890
========= master_lag: 0.06 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 53 secs) (at 2017-11-02 03:51:45.056845) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-02 03:51:45.064381) =========
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=a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f86b74067b7b79c4ff61305de92753c083f84638b85eafc1ced5860a9a2b79a22e0a69c4940a53a1cdcee5d9714cfe6595ce5db9ea1c7df6f864b14489c1563", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef6d1bceaeb3590d1c888a66ab634950836aa7fe81212fbe406e61cf0da409c0221f96c967b3aa37e3534545870ff2ef2248f6722d12965af04ceaba363d92ab", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f18ebda8695bb23975d330ea34b30a22c150d8b145b57cfc0110b2f3c128da9b07d24fe416ae78fa3a5960ed2f57ce4f8be7deadba1f396ea214da66cf050d9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8451fa657cfb47ddcb07f2dad4329ad57d98ee4fc1fc237228048ba878a8160c5e54099d3e00697290e1d9200219dc1ae349dd08e215427688f6096074f7e0d"}
build_url:https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011250
build_url: 'https://queue.taskcluster.net/v1/task/JpD9RANUQAWCO0ZgUw79-Q/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f86b74067b7b79c4ff61305de92753c083f84638b85eafc1ced5860a9a2b79a22e0a69c4940a53a1cdcee5d9714cfe6595ce5db9ea1c7df6f864b14489c1563", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef6d1bceaeb3590d1c888a66ab634950836aa7fe81212fbe406e61cf0da409c0221f96c967b3aa37e3534545870ff2ef2248f6722d12965af04ceaba363d92ab", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1f18ebda8695bb23975d330ea34b30a22c150d8b145b57cfc0110b2f3c128da9b07d24fe416ae78fa3a5960ed2f57ce4f8be7deadba1f396ea214da66cf050d9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b8451fa657cfb47ddcb07f2dad4329ad57d98ee4fc1fc237228048ba878a8160c5e54099d3e00697290e1d9200219dc1ae349dd08e215427688f6096074f7e0d"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-02 03:51:45.096458) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 03:51:45.096757) =========
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=a0d2d2de893abaa8702a9954000001a7-1509619130.197763-29110440
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004545
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-02 03:51:45.178054) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-02 03:51:45.178401) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-02 03:51:45.178718) =========
========= Total master_lag: 0.23 =========