builder: mozilla-central_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-014
starttime: 1512005042.62
results: success (0)
revision: 91fc3a79606bdc7fb43fef12eff7e65b5b84c00e
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:02.623009) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:02.623526) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:02.623832) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:02.660431) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:02.660799) =========
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=31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-29 17:24:02-- 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% 68.5M=0s
2017-11-29 17:24:02 (68.5 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.083997
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:02.774704) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:02.775037) =========
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=31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.021452
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:02.828229) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-29 17:24:02.828564) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 91fc3a79606bdc7fb43fef12eff7e65b5b84c00e --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 91fc3a79606bdc7fb43fef12eff7e65b5b84c00e --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=31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-29 17:24:02,830 truncating revision to first 12 chars
2017-11-29 17:24:02,830 Setting DEBUG logging.
2017-11-29 17:24:02,830 attempt 1/10
2017-11-29 17:24:02,830 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/91fc3a79606b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-29 17:24:03,269 unpacking tar archive at: mozilla-central-91fc3a79606b/testing/mozharness/
program finished with exit code 0
elapsedTime=1.093127
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-29 17:24:03.939398) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:03.939704) =========
script_repo_revision: 91fc3a79606bdc7fb43fef12eff7e65b5b84c00e
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:03.940148) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:03.940434) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-29 17:24:03.955127) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 5 secs) (at 2017-11-29 17:24:03.955562) =========
/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=31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
17:24:04 INFO - MultiFileLogger online at 20171129 17:24:04 in /builds/slave/test
17:24:04 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
17:24:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
17:24:04 INFO - {'append_to_log': False,
17:24:04 INFO - 'base_work_dir': '/builds/slave/test',
17:24:04 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
17:24:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
17:24:04 INFO - 'branch': 'Firefox-Non-PGO',
17:24:04 INFO - 'buildbot_json_path': 'buildprops.json',
17:24:04 INFO - 'code_coverage': False,
17:24:04 INFO - 'config_files': ('talos/linux_config.py',),
17:24:04 INFO - 'default_actions': ('clobber',
17:24:04 INFO - 'read-buildbot-config',
17:24:04 INFO - 'download-and-extract',
17:24:04 INFO - 'populate-webroot',
17:24:04 INFO - 'create-virtualenv',
17:24:04 INFO - 'install',
17:24:04 INFO - 'setup-mitmproxy',
17:24:04 INFO - 'run-tests'),
17:24:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
17:24:04 INFO - 'disable_ccov_upload': False,
17:24:04 INFO - 'disable_stylo': False,
17:24:04 INFO - 'download_minidump_stackwalk': True,
17:24:04 INFO - 'download_symbols': 'ondemand',
17:24:04 INFO - 'e10s': False,
17:24:04 INFO - 'enable_stylo': False,
17:24:04 INFO - 'enable_webrender': False,
17:24:04 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
17:24:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
17:24:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
17:24:04 INFO - 'gecko_profile': False,
17:24:04 INFO - 'gecko_profile_interval': 0,
17:24:04 INFO - 'installer_path': 'installer.exe',
17:24:04 INFO - 'jsd_code_coverage': False,
17:24:04 INFO - 'log_level': 'info',
17:24:04 INFO - 'log_name': 'talos',
17:24:04 INFO - 'log_to_console': True,
17:24:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
17:24:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
17:24:04 INFO - 'opt_config_files': (),
17:24:04 INFO - 'pip_index': False,
17:24:04 INFO - 'suite': 'g4-e10s',
17:24:04 INFO - 'system_bits': '32',
17:24:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
17:24:04 INFO - 'title': 'talos-linux64-ix-014',
17:24:04 INFO - 'tooltool_cache': '/builds/tooltool_cache',
17:24:04 INFO - 'use_talos_json': True,
17:24:04 INFO - 'verify': 'False',
17:24:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
17:24:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
17:24:04 INFO - 'work_dir': 'build'}
17:24:04 INFO - [mozharness: 2017-11-30 01:24:04.098799Z] Running clobber step.
17:24:04 INFO - Running pre-action listener: _resource_record_pre_action
17:24:04 INFO - Running main action method: clobber
17:24:04 INFO - rmtree: /builds/slave/test/build
17:24:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
17:24:05 INFO - Running post-action listener: _resource_record_post_action
17:24:05 INFO - [mozharness: 2017-11-30 01:24:05.159988Z] Finished clobber step (success)
17:24:05 INFO - [mozharness: 2017-11-30 01:24:05.160085Z] Running read-buildbot-config step.
17:24:05 INFO - Running pre-action listener: _resource_record_pre_action
17:24:05 INFO - Running main action method: read_buildbot_config
17:24:05 INFO - Using buildbot properties:
17:24:05 INFO - {
17:24:05 INFO - "project": "",
17:24:05 INFO - "product": "firefox",
17:24:05 INFO - "who": "ryanvm@gmail.com",
17:24:05 INFO - "installer_path": "public/build/target.tar.bz2",
17:24:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
17:24:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
17:24:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-e10s",
17:24:05 INFO - "stage_platform": "linux64",
17:24:05 INFO - "basedir": "/builds/slave/test",
17:24:05 INFO - "buildnumber": 118,
17:24:05 INFO - "platform": "ubuntu64_hw",
17:24:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
17:24:05 INFO - "slavebuilddir": "test",
17:24:05 INFO - "taskId": "JGKI0JJiTnSnn0cKCJUSjA",
17:24:05 INFO - "branch": "mozilla-central",
17:24:05 INFO - "script_repo_revision": "production",
17:24:05 INFO - "revision": "91fc3a79606bdc7fb43fef12eff7e65b5b84c00e",
17:24:05 INFO - "slavename": "talos-linux64-ix-014",
17:24:05 INFO - "repo_path": "mozilla-central"
17:24:05 INFO - }
17:24:05 INFO - Finding installer, test and symbols from parent task.
17:24:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JGKI0JJiTnSnn0cKCJUSjA'}, attempt #1
17:24:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JGKI0JJiTnSnn0cKCJUSjA'}, attempt #1
17:24:06 INFO - Task dependencies: ADCpvu8ORQqcdxu_pad5PA
17:24:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA'}, attempt #1
17:24:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.tar.bz2
17:24:06 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.test_packages.json
17:24:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.crashreporter-symbols.zip
17:24:06 INFO - Running post-action listener: _resource_record_post_action
17:24:06 INFO - [mozharness: 2017-11-30 01:24:06.674569Z] Finished read-buildbot-config step (success)
17:24:06 INFO - [mozharness: 2017-11-30 01:24:06.674786Z] Running download-and-extract step.
17:24:06 INFO - Running pre-action listener: _resource_record_pre_action
17:24:06 INFO - Running main action method: download_and_extract
17:24:06 INFO - mkdir: /builds/slave/test/build/tests
17:24:06 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')]}
17:24:06 INFO - trying https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.test_packages.json
17:24:06 INFO - Downloading https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
17:24:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
17:24:07 INFO - Downloaded 989 bytes.
17:24:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
17:24:07 INFO - Using the following test package requirements:
17:24:07 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
17:24:07 INFO - u'common': [u'target.common.tests.zip'],
17:24:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
17:24:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
17:24:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
17:24:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
17:24:07 INFO - u'mozbase': [u'target.common.tests.zip'],
17:24:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
17:24:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
17:24:07 INFO - u'web-platform': [u'target.common.tests.zip',
17:24:07 INFO - u'target.web-platform.tests.tar.gz'],
17:24:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
17:24:07 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
17:24:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.common.tests.zip
17:24:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.common.tests.zip'}, attempt #1
17:24:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.common.tests.zip into memory
17:24:08 INFO - Content-Length response header: 59346129
17:24:08 INFO - Bytes received: 59346129
17:24:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.talos.tests.zip
17:24:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
17:24:19 INFO - Fetch https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.talos.tests.zip into memory
17:24:20 INFO - Content-Length response header: 18744804
17:24:20 INFO - Bytes received: 18744804
17:24:21 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')]}
17:24:21 INFO - trying https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.tar.bz2
17:24:21 INFO - Downloading https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
17:24:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
17:24:23 INFO - Downloaded 62687123 bytes.
17:24:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.tar.bz2
17:24:24 INFO - mkdir: /builds/slave/test/properties
17:24:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
17:24:24 INFO - Writing to file /builds/slave/test/properties/build_url
17:24:24 INFO - Contents:
17:24:24 INFO - build_url:https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.tar.bz2
17:24:24 INFO - Running post-action listener: _resource_record_post_action
17:24:24 INFO - Running post-action listener: find_tests_for_verification
17:24:24 INFO - Running post-action listener: set_extra_try_arguments
17:24:24 INFO - [mozharness: 2017-11-30 01:24:24.004161Z] Finished download-and-extract step (success)
17:24:24 INFO - [mozharness: 2017-11-30 01:24:24.004384Z] Running populate-webroot step.
17:24:24 INFO - Running pre-action listener: _resource_record_pre_action
17:24:24 INFO - Running main action method: populate_webroot
17:24:24 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
17:24:24 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['tresize',
17:24:24 INFO - 'tcanvasmark']},
17:24:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
17:24:24 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['dromaeo_css',
17:24:24 INFO - 'kraken']},
17:24:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
17:24:24 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
17:24:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'tests': ['damp', 'tps']},
17:24:24 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['damp', 'tps']},
17:24:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
17:24:24 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['dromaeo_dom']},
17:24:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
17:24:24 INFO - 'glvideo',
17:24:24 INFO - 'displaylist_mutate']},
17:24:24 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['basic_compositor_video',
17:24:24 INFO - 'glvideo']},
17:24:24 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
17:24:24 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['ts_paint_webext',
17:24:24 INFO - 'tp5o_webext']},
17:24:24 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
17:24:24 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
17:24:24 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
17:24:24 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
17:24:24 INFO - 'talos_options': ['--mitmproxy',
17:24:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
17:24:24 INFO - '--firstNonBlankPaint'],
17:24:24 INFO - 'tests': ['tp6_google_heavy',
17:24:24 INFO - 'tp6_youtube_heavy',
17:24:24 INFO - 'tp6_amazon_heavy',
17:24:24 INFO - 'tp6_facebook_heavy']},
17:24:24 INFO - 'other-e10s': {'tests': ['a11yr',
17:24:24 INFO - 'ts_paint',
17:24:24 INFO - 'tpaint',
17:24:24 INFO - 'sessionrestore',
17:24:24 INFO - 'sessionrestore_many_windows',
17:24:24 INFO - 'sessionrestore_no_auto_restore',
17:24:24 INFO - 'tabpaint',
17:24:24 INFO - 'cpstartup']},
17:24:24 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['a11yr',
17:24:24 INFO - 'ts_paint',
17:24:24 INFO - 'tpaint',
17:24:24 INFO - 'sessionrestore',
17:24:24 INFO - 'sessionrestore_many_windows',
17:24:24 INFO - 'sessionrestore_no_auto_restore',
17:24:24 INFO - 'tabpaint',
17:24:24 INFO - 'cpstartup']},
17:24:24 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
17:24:24 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
17:24:24 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['perf_reftest_singletons']},
17:24:24 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['perf_reftest']},
17:24:24 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
17:24:24 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['speedometer']},
17:24:24 INFO - 'svgr-e10s': {'tests': ['tsvgx',
17:24:24 INFO - 'tsvgr_opacity',
17:24:24 INFO - 'tart',
17:24:24 INFO - 'tscrollx',
17:24:24 INFO - 'tsvg_static']},
17:24:24 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['tsvgx',
17:24:24 INFO - 'tsvgr_opacity',
17:24:24 INFO - 'tart',
17:24:24 INFO - 'tscrollx',
17:24:24 INFO - 'tsvg_static']},
17:24:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
17:24:24 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'talos_options': ['--disable-stylo'],
17:24:24 INFO - 'tests': ['tp5o']},
17:24:24 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
17:24:24 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
17:24:24 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
17:24:24 INFO - 'talos_options': ['--mitmproxy',
17:24:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
17:24:24 INFO - '--firstNonBlankPaint'],
17:24:24 INFO - 'tests': ['tp6_google',
17:24:24 INFO - 'tp6_youtube',
17:24:24 INFO - 'tp6_amazon',
17:24:24 INFO - 'tp6_facebook']},
17:24:24 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
17:24:24 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
17:24:24 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
17:24:24 INFO - 'talos_options': ['--disable-stylo',
17:24:24 INFO - '--mitmproxy',
17:24:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
17:24:24 INFO - '--firstNonBlankPaint'],
17:24:24 INFO - 'tests': ['tp6_google',
17:24:24 INFO - 'tp6_youtube',
17:24:24 INFO - 'tp6_amazon',
17:24:24 INFO - 'tp6_facebook']},
17:24:24 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
17:24:24 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
17:24:24 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
17:24:24 INFO - 'talos_options': ['--stylo-threads=1',
17:24:24 INFO - '--mitmproxy',
17:24:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
17:24:24 INFO - '--firstNonBlankPaint'],
17:24:24 INFO - 'tests': ['tp6_google',
17:24:24 INFO - 'tp6_youtube',
17:24:24 INFO - 'tp6_amazon',
17:24:24 INFO - 'tp6_facebook']},
17:24:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'talos_options': ['--xperf_path',
17:24:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
17:24:24 INFO - 'tests': ['tp5n']},
17:24:24 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
17:24:24 INFO - 'talos_options': ['--disable-stylo',
17:24:24 INFO - '--xperf_path',
17:24:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
17:24:24 INFO - 'tests': ['tp5n']}}}
17:24:24 INFO - Running post-action listener: _resource_record_post_action
17:24:24 INFO - [mozharness: 2017-11-30 01:24:24.028507Z] Finished populate-webroot step (success)
17:24:24 INFO - [mozharness: 2017-11-30 01:24:24.028600Z] Running create-virtualenv step.
17:24:24 INFO - Running pre-action listener: _resource_record_pre_action
17:24:24 INFO - Running main action method: create_virtualenv
17:24:24 INFO - Creating virtualenv /builds/slave/test/build/venv
17:24:24 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
17:24:24 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
17:24:24 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
17:24:24 INFO - Using real prefix '/usr'
17:24:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python
17:24:24 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
17:24:25 INFO - Installing setuptools, pip, wheel...done.
17:24:25 INFO - Return code: 0
17:24:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
17:24:25 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')]}
17:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:25 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')]}
17:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:25 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:25 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
17:24:25 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
17:24:25 INFO - Using env: {'DISPLAY': ':0',
17:24:25 INFO - 'HOME': '/home/cltbld',
17:24:25 INFO - 'LANG': 'en_US.UTF-8',
17:24:25 INFO - 'LANGUAGE': 'en_US:en',
17:24:25 INFO - 'LOGNAME': 'cltbld',
17:24:25 INFO - 'MAIL': '/var/mail/cltbld',
17:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:25 INFO - 'MOZ_NO_REMOTE': '1',
17:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:25 INFO - 'NO_EM_RESTART': '1',
17:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:25 INFO - 'PWD': '/builds/slave/test',
17:24:25 INFO - 'SHELL': '/bin/bash',
17:24:25 INFO - 'SHLVL': '1',
17:24:25 INFO - 'TERM': 'linux',
17:24:25 INFO - 'TMOUT': '86400',
17:24:25 INFO - 'USER': 'cltbld',
17:24:25 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:25 INFO - '_': '/tools/buildbot/bin/python'}
17:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
17:24:26 INFO - Return code: 0
17:24:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
17:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
17:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
17:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
17:24:26 INFO - Using env: {'DISPLAY': ':0',
17:24:26 INFO - 'HOME': '/home/cltbld',
17:24:26 INFO - 'LANG': 'en_US.UTF-8',
17:24:26 INFO - 'LANGUAGE': 'en_US:en',
17:24:26 INFO - 'LOGNAME': 'cltbld',
17:24:26 INFO - 'MAIL': '/var/mail/cltbld',
17:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:26 INFO - 'MOZ_NO_REMOTE': '1',
17:24:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:26 INFO - 'NO_EM_RESTART': '1',
17:24:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:26 INFO - 'PWD': '/builds/slave/test',
17:24:26 INFO - 'SHELL': '/bin/bash',
17:24:26 INFO - 'SHLVL': '1',
17:24:26 INFO - 'TERM': 'linux',
17:24:26 INFO - 'TMOUT': '86400',
17:24:26 INFO - 'USER': 'cltbld',
17:24:26 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:26 INFO - '_': '/tools/buildbot/bin/python'}
17:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:26 INFO - Collecting psutil>=3.1.1
17:24:27 INFO - Installing collected packages: psutil
17:24:27 INFO - Successfully installed psutil-3.1.1
17:24:27 INFO - Return code: 0
17:24:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
17:24:27 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')]}
17:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:27 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')]}
17:24:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:27 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:27 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
17:24:27 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
17:24:27 INFO - Using env: {'DISPLAY': ':0',
17:24:27 INFO - 'HOME': '/home/cltbld',
17:24:27 INFO - 'LANG': 'en_US.UTF-8',
17:24:27 INFO - 'LANGUAGE': 'en_US:en',
17:24:27 INFO - 'LOGNAME': 'cltbld',
17:24:27 INFO - 'MAIL': '/var/mail/cltbld',
17:24:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:27 INFO - 'MOZ_NO_REMOTE': '1',
17:24:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:27 INFO - 'NO_EM_RESTART': '1',
17:24:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:27 INFO - 'PWD': '/builds/slave/test',
17:24:27 INFO - 'SHELL': '/bin/bash',
17:24:27 INFO - 'SHLVL': '1',
17:24:27 INFO - 'TERM': 'linux',
17:24:27 INFO - 'TMOUT': '86400',
17:24:27 INFO - 'USER': 'cltbld',
17:24:27 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:27 INFO - '_': '/tools/buildbot/bin/python'}
17:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:28 INFO - Collecting mozsystemmonitor==0.3
17:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
17:24:29 INFO - Installing collected packages: mozsystemmonitor
17:24:29 INFO - Successfully installed mozsystemmonitor-0.3
17:24:29 INFO - Return code: 0
17:24:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
17:24:29 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')]}
17:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:29 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')]}
17:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:29 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:29 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
17:24:29 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
17:24:29 INFO - Using env: {'DISPLAY': ':0',
17:24:29 INFO - 'HOME': '/home/cltbld',
17:24:29 INFO - 'LANG': 'en_US.UTF-8',
17:24:29 INFO - 'LANGUAGE': 'en_US:en',
17:24:29 INFO - 'LOGNAME': 'cltbld',
17:24:29 INFO - 'MAIL': '/var/mail/cltbld',
17:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:29 INFO - 'MOZ_NO_REMOTE': '1',
17:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:29 INFO - 'NO_EM_RESTART': '1',
17:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:29 INFO - 'PWD': '/builds/slave/test',
17:24:29 INFO - 'SHELL': '/bin/bash',
17:24:29 INFO - 'SHLVL': '1',
17:24:29 INFO - 'TERM': 'linux',
17:24:29 INFO - 'TMOUT': '86400',
17:24:29 INFO - 'USER': 'cltbld',
17:24:29 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:29 INFO - '_': '/tools/buildbot/bin/python'}
17:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:29 INFO - Collecting jsonschema==2.5.1
17:24:31 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
17:24:31 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
17:24:32 INFO - Installing collected packages: functools32, jsonschema
17:24:32 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
17:24:32 INFO - Return code: 0
17:24:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
17:24:32 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')]}
17:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:32 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')]}
17:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:32 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:32 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
17:24:32 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
17:24:32 INFO - Using env: {'DISPLAY': ':0',
17:24:32 INFO - 'HOME': '/home/cltbld',
17:24:32 INFO - 'LANG': 'en_US.UTF-8',
17:24:32 INFO - 'LANGUAGE': 'en_US:en',
17:24:32 INFO - 'LOGNAME': 'cltbld',
17:24:32 INFO - 'MAIL': '/var/mail/cltbld',
17:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:32 INFO - 'MOZ_NO_REMOTE': '1',
17:24:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:32 INFO - 'NO_EM_RESTART': '1',
17:24:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:32 INFO - 'PWD': '/builds/slave/test',
17:24:32 INFO - 'SHELL': '/bin/bash',
17:24:32 INFO - 'SHLVL': '1',
17:24:32 INFO - 'TERM': 'linux',
17:24:32 INFO - 'TMOUT': '86400',
17:24:32 INFO - 'USER': 'cltbld',
17:24:32 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:32 INFO - '_': '/tools/buildbot/bin/python'}
17:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
17:24:32 INFO - Return code: 0
17:24:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
17:24:32 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')]}
17:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:32 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')]}
17:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:32 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:32 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
17:24:32 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
17:24:32 INFO - Using env: {'DISPLAY': ':0',
17:24:32 INFO - 'HOME': '/home/cltbld',
17:24:32 INFO - 'LANG': 'en_US.UTF-8',
17:24:32 INFO - 'LANGUAGE': 'en_US:en',
17:24:32 INFO - 'LOGNAME': 'cltbld',
17:24:32 INFO - 'MAIL': '/var/mail/cltbld',
17:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:32 INFO - 'MOZ_NO_REMOTE': '1',
17:24:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:32 INFO - 'NO_EM_RESTART': '1',
17:24:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:32 INFO - 'PWD': '/builds/slave/test',
17:24:32 INFO - 'SHELL': '/bin/bash',
17:24:32 INFO - 'SHLVL': '1',
17:24:32 INFO - 'TERM': 'linux',
17:24:32 INFO - 'TMOUT': '86400',
17:24:32 INFO - 'USER': 'cltbld',
17:24:32 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:32 INFO - '_': '/tools/buildbot/bin/python'}
17:24:33 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:33 INFO - Collecting blobuploader==1.2.4
17:24:34 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
17:24:35 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
17:24:36 INFO - Installing collected packages: requests, docopt, blobuploader
17:24:36 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
17:24:36 INFO - Return code: 0
17:24:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv
17:24:36 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')]}
17:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:36 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')]}
17:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:36 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:36 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
17:24:36 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
17:24:36 INFO - Using env: {'DISPLAY': ':0',
17:24:36 INFO - 'HOME': '/home/cltbld',
17:24:36 INFO - 'LANG': 'en_US.UTF-8',
17:24:36 INFO - 'LANGUAGE': 'en_US:en',
17:24:36 INFO - 'LOGNAME': 'cltbld',
17:24:36 INFO - 'MAIL': '/var/mail/cltbld',
17:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:36 INFO - 'MOZ_NO_REMOTE': '1',
17:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:36 INFO - 'NO_EM_RESTART': '1',
17:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:36 INFO - 'PWD': '/builds/slave/test',
17:24:36 INFO - 'SHELL': '/bin/bash',
17:24:36 INFO - 'SHLVL': '1',
17:24:36 INFO - 'TERM': 'linux',
17:24:36 INFO - 'TMOUT': '86400',
17:24:36 INFO - 'USER': 'cltbld',
17:24:36 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:36 INFO - '_': '/tools/buildbot/bin/python'}
17:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:37 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
17:24:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
17:24:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
17:24:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
17:24:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
17:24:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
17:24:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
17:24:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
17:24:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
17:24:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
17:24:38 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
17:24:39 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
17:24:39 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
17:24:39 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
17:24:39 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
17:24:39 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
17:24:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
17:24:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
17:24:40 INFO - Running setup.py install for manifestparser: started
17:24:40 INFO - Running setup.py install for manifestparser: finished with status 'done'
17:24:40 INFO - Running setup.py install for mozcrash: started
17:24:40 INFO - Running setup.py install for mozcrash: finished with status 'done'
17:24:40 INFO - Running setup.py install for mozdebug: started
17:24:40 INFO - Running setup.py install for mozdebug: finished with status 'done'
17:24:40 INFO - Running setup.py install for mozdevice: started
17:24:41 INFO - Running setup.py install for mozdevice: finished with status 'done'
17:24:41 INFO - Running setup.py install for mozfile: started
17:24:41 INFO - Running setup.py install for mozfile: finished with status 'done'
17:24:41 INFO - Running setup.py install for mozhttpd: started
17:24:41 INFO - Running setup.py install for mozhttpd: finished with status 'done'
17:24:41 INFO - Running setup.py install for mozinfo: started
17:24:41 INFO - Running setup.py install for mozinfo: finished with status 'done'
17:24:41 INFO - Running setup.py install for mozInstall: started
17:24:41 INFO - Running setup.py install for mozInstall: finished with status 'done'
17:24:41 INFO - Running setup.py install for mozleak: started
17:24:42 INFO - Running setup.py install for mozleak: finished with status 'done'
17:24:42 INFO - Running setup.py install for mozlog: started
17:24:42 INFO - Running setup.py install for mozlog: finished with status 'done'
17:24:42 INFO - Running setup.py install for moznetwork: started
17:24:42 INFO - Running setup.py install for moznetwork: finished with status 'done'
17:24:42 INFO - Running setup.py install for mozprocess: started
17:24:42 INFO - Running setup.py install for mozprocess: finished with status 'done'
17:24:42 INFO - Running setup.py install for mozprofile: started
17:24:43 INFO - Running setup.py install for mozprofile: finished with status 'done'
17:24:43 INFO - Running setup.py install for mozrunner: started
17:24:43 INFO - Running setup.py install for mozrunner: finished with status 'done'
17:24:43 INFO - Running setup.py install for mozscreenshot: started
17:24:43 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
17:24:43 INFO - Running setup.py install for moztest: started
17:24:43 INFO - Running setup.py install for moztest: finished with status 'done'
17:24:43 INFO - Running setup.py install for mozversion: started
17:24:44 INFO - Running setup.py install for mozversion: finished with status 'done'
17:24:44 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.14 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
17:24:44 INFO - Return code: 0
17:24:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv
17:24:44 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')]}
17:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:44 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')]}
17:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:44 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:44 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
17:24:44 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
17:24:44 INFO - Using env: {'DISPLAY': ':0',
17:24:44 INFO - 'HOME': '/home/cltbld',
17:24:44 INFO - 'LANG': 'en_US.UTF-8',
17:24:44 INFO - 'LANGUAGE': 'en_US:en',
17:24:44 INFO - 'LOGNAME': 'cltbld',
17:24:44 INFO - 'MAIL': '/var/mail/cltbld',
17:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:44 INFO - 'MOZ_NO_REMOTE': '1',
17:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:44 INFO - 'NO_EM_RESTART': '1',
17:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:44 INFO - 'PWD': '/builds/slave/test',
17:24:44 INFO - 'SHELL': '/bin/bash',
17:24:44 INFO - 'SHLVL': '1',
17:24:44 INFO - 'TERM': 'linux',
17:24:44 INFO - 'TMOUT': '86400',
17:24:44 INFO - 'USER': 'cltbld',
17:24:44 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:44 INFO - '_': '/tools/buildbot/bin/python'}
17:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:44 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
17:24:44 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))
17:24:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
17:24:45 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))
17:24:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
17:24:45 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))
17:24:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
17:24:45 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))
17:24:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
17:24:45 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))
17:24:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
17:24:46 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))
17:24:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
17:24:46 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))
17:24:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
17:24:46 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))
17:24:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
17:24:46 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))
17:24:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
17:24:46 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))
17:24:46 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
17:24:47 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))
17:24:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
17:24:47 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))
17:24:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
17:24:47 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))
17:24:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
17:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.14 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))
17:24:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
17:24:47 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))
17:24:47 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
17:24:48 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))
17:24:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
17:24:48 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))
17:24:48 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
17:24:49 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
17:24:49 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))
17:24:49 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))
17:24:49 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))
17:24:49 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))
17:24:49 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))
17:24:49 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))
17:24:49 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
17:24:50 INFO - Installing collected packages: six, blessings
17:24:50 INFO - Successfully installed blessings-1.6 six-1.10.0
17:24:50 INFO - Return code: 0
17:24:50 INFO - Done creating virtualenv /builds/slave/test/build/venv.
17:24:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
17:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
17:24:51 INFO - Reading from file tmpfile_stdout
17:24:51 INFO - Reading from file tmpfile_stderr
17:24:51 INFO - Current package versions:
17:24:51 INFO - blessings == 1.6
17:24:51 INFO - blobuploader == 1.2.4
17:24:51 INFO - docopt == 0.6.1
17:24:51 INFO - functools32 == 3.2.3.post2
17:24:51 INFO - jsonschema == 2.5.1
17:24:51 INFO - manifestparser == 1.2
17:24:51 INFO - mozInstall == 1.14
17:24:51 INFO - mozcrash == 1.0
17:24:51 INFO - mozdebug == 0.1
17:24:51 INFO - mozdevice == 0.51
17:24:51 INFO - mozfile == 1.2
17:24:51 INFO - mozhttpd == 0.7
17:24:51 INFO - mozinfo == 0.10
17:24:51 INFO - mozleak == 0.1
17:24:51 INFO - mozlog == 3.5
17:24:51 INFO - moznetwork == 0.27
17:24:51 INFO - mozprocess == 0.25
17:24:51 INFO - mozprofile == 0.29
17:24:51 INFO - mozrunner == 6.14
17:24:51 INFO - mozscreenshot == 0.1
17:24:51 INFO - mozsystemmonitor == 0.3
17:24:51 INFO - moztest == 0.8
17:24:51 INFO - mozversion == 1.4
17:24:51 INFO - psutil == 3.1.1
17:24:51 INFO - requests == 1.2.3
17:24:51 INFO - six == 1.10.0
17:24:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv
17:24:51 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')]}
17:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:51 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')]}
17:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:51 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:51 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
17:24:51 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
17:24:51 INFO - Using env: {'DISPLAY': ':0',
17:24:51 INFO - 'HOME': '/home/cltbld',
17:24:51 INFO - 'LANG': 'en_US.UTF-8',
17:24:51 INFO - 'LANGUAGE': 'en_US:en',
17:24:51 INFO - 'LOGNAME': 'cltbld',
17:24:51 INFO - 'MAIL': '/var/mail/cltbld',
17:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:51 INFO - 'MOZ_NO_REMOTE': '1',
17:24:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:51 INFO - 'NO_EM_RESTART': '1',
17:24:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:51 INFO - 'PWD': '/builds/slave/test',
17:24:51 INFO - 'SHELL': '/bin/bash',
17:24:51 INFO - 'SHLVL': '1',
17:24:51 INFO - 'TERM': 'linux',
17:24:51 INFO - 'TMOUT': '86400',
17:24:51 INFO - 'USER': 'cltbld',
17:24:51 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:51 INFO - '_': '/tools/buildbot/bin/python'}
17:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:51 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))
17:24:51 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))
17:24:51 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))
17:24:51 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))
17:24:51 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))
17:24:51 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))
17:24:51 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))
17:24:51 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))
17:24:51 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))
17:24:51 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
17:24:52 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
17:24:53 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
17:24:53 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))
17:24:53 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))
17:24:53 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))
17:24:53 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))
17:24:54 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
17:24:54 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
17:24:55 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
17:24:55 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
17:24:57 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
17:24:57 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
17:24:58 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
17:24:58 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
17:24:58 INFO - Found existing installation: requests 1.2.3
17:24:58 INFO - Uninstalling requests-1.2.3:
17:24:58 INFO - Successfully uninstalled requests-1.2.3
17:24:58 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
17:24:58 INFO - Return code: 0
17:24:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
17:24:58 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')]}
17:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
17:24:58 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')]}
17:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
17:24:58 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 0x25b5d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2594a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2738380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, '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': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469', '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
17:24:58 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
17:24:58 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
17:24:58 INFO - Using env: {'DISPLAY': ':0',
17:24:58 INFO - 'HOME': '/home/cltbld',
17:24:58 INFO - 'LANG': 'en_US.UTF-8',
17:24:58 INFO - 'LANGUAGE': 'en_US:en',
17:24:58 INFO - 'LOGNAME': 'cltbld',
17:24:58 INFO - 'MAIL': '/var/mail/cltbld',
17:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:24:58 INFO - 'MOZ_NO_REMOTE': '1',
17:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:24:58 INFO - 'NO_EM_RESTART': '1',
17:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:24:58 INFO - 'PWD': '/builds/slave/test',
17:24:58 INFO - 'SHELL': '/bin/bash',
17:24:58 INFO - 'SHLVL': '1',
17:24:58 INFO - 'TERM': 'linux',
17:24:58 INFO - 'TMOUT': '86400',
17:24:58 INFO - 'USER': 'cltbld',
17:24:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:24:58 INFO - '_': '/tools/buildbot/bin/python'}
17:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple
17:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
17:24:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
17:24:59 INFO - Return code: 0
17:24:59 INFO - Running post-action listener: _resource_record_post_action
17:24:59 INFO - Running post-action listener: _start_resource_monitoring
17:24:59 INFO - Starting resource monitoring.
17:24:59 INFO - [mozharness: 2017-11-30 01:24:59.114882Z] Finished create-virtualenv step (success)
17:24:59 INFO - [mozharness: 2017-11-30 01:24:59.115340Z] Running install step.
17:24:59 INFO - Running pre-action listener: _resource_record_pre_action
17:24:59 INFO - Running main action method: install
17:24:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
17:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
17:24:59 INFO - Reading from file tmpfile_stdout
17:24:59 INFO - Reading from file tmpfile_stderr
17:24:59 INFO - Detecting whether we're running mozinstall >=1.0...
17:24:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
17:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
17:24:59 INFO - Reading from file tmpfile_stdout
17:24:59 INFO - Output received:
17:24:59 INFO - Usage: mozinstall [options] installer
17:24:59 INFO - Options:
17:24:59 INFO - -h, --help show this help message and exit
17:24:59 INFO - -d DEST, --destination=DEST
17:24:59 INFO - Directory to install application into. [default:
17:24:59 INFO - "/builds/slave/test"]
17:24:59 INFO - --app=APP Application being installed. [default: firefox]
17:24:59 INFO - mkdir: /builds/slave/test/build/application
17:24:59 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']
17:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
17:25:18 INFO - Reading from file tmpfile_stdout
17:25:18 INFO - Output received:
17:25:18 INFO - /builds/slave/test/build/application/firefox/firefox
17:25:18 INFO - Running post-action listener: _resource_record_post_action
17:25:18 INFO - [mozharness: 2017-11-30 01:25:18.073076Z] Finished install step (success)
17:25:18 INFO - [mozharness: 2017-11-30 01:25:18.073525Z] Running setup-mitmproxy step.
17:25:18 INFO - Running pre-action listener: _resource_record_pre_action
17:25:18 INFO - Running main action method: setup_mitmproxy
17:25:18 INFO - Skipping: mitmproxy is not required
17:25:18 INFO - Running post-action listener: _resource_record_post_action
17:25:18 INFO - [mozharness: 2017-11-30 01:25:18.075104Z] Finished setup-mitmproxy step (success)
17:25:18 INFO - [mozharness: 2017-11-30 01:25:18.075410Z] Running run-tests step.
17:25:18 INFO - Running pre-action listener: _resource_record_pre_action
17:25:18 INFO - Running pre-action listener: _set_gcov_prefix
17:25:18 INFO - Running main action method: run_tests
17:25:18 WARNING - Try message not found.
17:25:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
17:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
17:25:18 INFO - Python 2.7.3
17:25:18 INFO - Return code: 0
17:25:18 INFO - grabbing minidump binary from tooltool
17:25: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')]}
17:25:18 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 0x2735520>, '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 0x2736420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27368b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
17:25:18 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
17:25:18 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
17:25:18 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
17:25:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
17:25:18 INFO - Return code: 0
17:25:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
17:25:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
17:25:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
17:25:18 INFO - ENV: RUST_BACKTRACE is now full
17:25:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
17:25:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
17:25:18 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/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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
17:25:18 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/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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
17:25:18 INFO - Using env: {'DISPLAY': ':0',
17:25:18 INFO - 'HOME': '/home/cltbld',
17:25:18 INFO - 'LANG': 'en_US.UTF-8',
17:25:18 INFO - 'LANGUAGE': 'en_US:en',
17:25:18 INFO - 'LOGNAME': 'cltbld',
17:25:18 INFO - 'MAIL': '/var/mail/cltbld',
17:25:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
17:25:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
17:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:25:18 INFO - 'MOZ_NO_REMOTE': '1',
17:25:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
17:25:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:25:18 INFO - 'NO_EM_RESTART': '1',
17:25:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:25:18 INFO - 'PWD': '/builds/slave/test',
17:25:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
17:25:18 INFO - 'RUST_BACKTRACE': 'full',
17:25:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
17:25:18 INFO - 'SHELL': '/bin/bash',
17:25:18 INFO - 'SHLVL': '1',
17:25:18 INFO - 'TERM': 'linux',
17:25:18 INFO - 'TMOUT': '86400',
17:25:18 INFO - 'USER': 'cltbld',
17:25:18 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:25:18 INFO - '_': '/tools/buildbot/bin/python'}
17:25:18 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/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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
17:25:18 INFO - mozversion application_buildid: 20171129220039
17:25:18 INFO - mozversion application_changeset: 91fc3a79606bdc7fb43fef12eff7e65b5b84c00e
17:25:18 INFO - mozversion application_display_name: Nightly
17:25:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
17:25:18 INFO - mozversion application_name: Firefox
17:25:18 INFO - mozversion application_remotingname: firefox
17:25:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
17:25:18 INFO - mozversion application_vendor: Mozilla
17:25:18 INFO - mozversion application_version: 59.0a1
17:25:18 INFO - mozversion platform_buildid: 20171129220039
17:25:18 INFO - mozversion platform_changeset: 91fc3a79606bdc7fb43fef12eff7e65b5b84c00e
17:25:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
17:25:18 INFO - mozversion platform_version: 59.0a1
17:25:18 INFO - using testdate: 1512005118
17:25:18 INFO - actual date: 1512005118
17:25:18 INFO - starting webserver on 'localhost:43328'
17:25:18 INFO - SUITE-START | Running 3 tests
17:25:18 INFO - TEST-START | basic_compositor_video
17:25:18 INFO - Initialising browser for basic_compositor_video test...
17:25:18 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
17:25:18 INFO - Installing Add-ons
17:25:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43328/getInfo.html -profile /tmp/tmpIQCAUL/profile
17:25:18 INFO - TEST-INFO | started process 19625 (/builds/slave/test/build/application/firefox/firefox http://localhost:43328/getInfo.html)
17:25:28 INFO - TEST-INFO | 19625: exit 0
17:25:28 INFO - Browser initialized.
17:25:28 INFO - Running cycle 1/1 for basic_compositor_video test...
17:25:28 INFO - Using env: {'DISPLAY': ':0',
17:25:28 INFO - 'HOME': '/home/cltbld',
17:25:28 INFO - 'JSGC_DISABLE_POISONING': '1',
17:25:28 INFO - 'LANG': 'en_US.UTF-8',
17:25:28 INFO - 'LANGUAGE': 'en_US:en',
17:25:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
17:25:28 INFO - 'LOGNAME': 'cltbld',
17:25:28 INFO - 'MAIL': '/var/mail/cltbld',
17:25:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
17:25:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
17:25:28 INFO - 'MOZ_CRASHREPORTER': '1',
17:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:25:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
17:25:28 INFO - 'MOZ_NO_REMOTE': '1',
17:25:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
17:25:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:25:28 INFO - 'NO_EM_RESTART': '1',
17:25:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:25:28 INFO - 'PWD': '/builds/slave/test',
17:25:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
17:25:28 INFO - 'RUST_BACKTRACE': 'full',
17:25:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
17:25:28 INFO - 'SHELL': '/bin/bash',
17:25:28 INFO - 'SHLVL': '1',
17:25:28 INFO - 'TERM': 'linux',
17:25:28 INFO - 'TMOUT': '86400',
17:25:28 INFO - 'USER': 'cltbld',
17:25:28 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:25:28 INFO - '_': '/tools/buildbot/bin/python'}
17:25:28 INFO - TEST-INFO | started process 19911 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIQCAUL/profile)
17:25:29 INFO - PID 19911 |
17:25:29 INFO - PID 19911 | (/builds/slave/test/build/application/firefox/firefox:19962): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
17:25:29 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6802155655095183 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6726128762541803 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7261910241657077 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7069795221843003 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 3.375163104611924 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.168104166666668 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.5346996466431087 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4134982935153606 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6608773584905645 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.543365650969536 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.71085760517799 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.094909090909095 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.367765151515153 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.992390109890138 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.607121848739506 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.495028735632154 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.45438931297711 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.174553072625672 ms/frame
17:26:15 INFO - PID 19911 |
17:26:15 INFO - PID 19911 | Cycle 1(1): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:27:01 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6789199776161163 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6663530391340553 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7752485207100592 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7517775831873912 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.156350415512466 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.212010526315789 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.699475955610355 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5794991055456213 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.705684410646392 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.63692957746478 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 10.135929054054056 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.307116279069762 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.238820224719099 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81389189189189 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.554016736401689 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.908749999999989 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.542384615384604 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.93333333333335 ms/frame
17:27:01 INFO - PID 19911 |
17:27:01 INFO - PID 19911 | Cycle 1(2): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:27:47 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6803275475923853 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6744225941422597 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.783048128342246 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7766651865008878 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.105198358413133 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.914863013698628 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.5428748524203053 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.515949033391917 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.609859813084115 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634971830985908 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.836360655737705 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.620649038461544 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.674143968871599 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.816621621621605 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.196585365853661 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.908452380952397 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58409266409266 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.367954545454557 ms/frame
17:27:47 INFO - PID 19911 |
17:27:47 INFO - PID 19911 | Cycle 1(3): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:28:34 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6829977565900167 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6755025125628138 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.735485829959514 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7308347750865054 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.060115020297701 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.107577002053387 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.5979076738609144 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.541725663716809 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.781907514450863 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5891620111731894 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 10.102356902356908 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.136187214611876 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.3649053030303 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.177346368715098 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.609327731092424 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.977365269461085 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.676848249027254 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.364971590909095 ms/frame
17:28:34 INFO - PID 19911 |
17:28:34 INFO - PID 19911 | Cycle 1(4): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:29:20 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6857921348314606 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6709607351712619 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7779739336492888 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.733479202772964 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.104876880984953 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9154305283757336 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.4525834292289987 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4027891156462586 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704990494296579 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.495659340659346 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.969019933554815 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.664154589371979 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.62870155038759 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.050552486187854 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.399276859504132 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.198292682926855 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.367481060606059 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.699010695187146 ms/frame
17:29:20 INFO - PID 19911 |
17:29:20 INFO - PID 19911 | Cycle 1(5): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:30:06 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6838355780022447 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6717000835421885 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7397507246376815 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7143958868894593 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.127283356258597 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.09922131147541 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.6634981684981693 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6438615664845164 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.771173076923074 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.602829131652669 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.937516556291381 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.479099526066351 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.279530075187969 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.753655913978497 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.557092050209192 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050210843373488 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.677451361867718 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.812702702702694 ms/frame
17:30:06 INFO - PID 19911 |
17:30:06 INFO - PID 19911 | Cycle 1(6): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:30:52 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6857893258426966 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668586321934946 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7476616191030865 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7241982758620686 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.144109116022101 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1687812499999986 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.624323671497585 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.449543103448277 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.629803001876175 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587835195530725 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.232400000000007 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.345560747663537 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.237734082397012 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.695721925133682 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.50477083333332 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766823529411768 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.541711538461543 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.052292817679579 ms/frame
17:30:52 INFO - PID 19911 |
17:30:52 INFO - PID 19911 | Cycle 1(7): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:31:38 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6817909192825113 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687447873227688 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.779546263345196 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7427177700348426 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.173894297635603 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.033921370967743 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.4649249422632793 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4138907849829367 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715171428571429 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5869832402234625 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 10.000816666666664 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.30730232558141 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.32435849056603 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.875054347826097 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39729338842977 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.978952095808387 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.630310077519361 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111861111111123 ms/frame
17:31:38 INFO - PID 19911 |
17:31:38 INFO - PID 19911 | Cycle 1(8): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:32:24 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.685823033707865 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6744351464435148 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7755473372781065 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7189690721649478 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.149854771784232 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.192903563941303 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.456993087557602 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.379628378378381 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.726469465648852 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.635042253521133 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.70935275080907 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.904554455445533 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.323528301886794 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.813837837837825 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.198109756097534 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.976167664670683 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.631550387596908 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050635359116027 ms/frame
17:32:24 INFO - PID 19911 |
17:32:24 INFO - PID 19911 | Cycle 1(9): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:33:10 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6907690140845069 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6703797996661103 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7715643447461629 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7468340611353712 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.054979729729729 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.177181628392486 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.6200603136308835 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6434972677595607 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650216572504707 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.465368852459019 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.901699669966996 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.90257425742574 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.326037735849063 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.64156914893616 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.295553278688516 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.909375000000008 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.768725490196084 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.930437158469928 ms/frame
17:33:10 INFO - PID 19911 |
17:33:10 INFO - PID 19911 | Cycle 1(10): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:33:57 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6893890765765767 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6671416666666665 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7505221703617266 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7502099737532806 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.127159559834937 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9001851851851854 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.453550057537402 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3676094276094286 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.545471349353054 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.636450704225348 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.967707641196009 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.855665024630545 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.45391221374047 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.86967391304346 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.345987654320975 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.769764705882356 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.239962546816479 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.816081081081073 ms/frame
17:33:57 INFO - PID 19911 |
17:33:57 INFO - PID 19911 | Cycle 1(11): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:34:43 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6826892877173305 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6738702928870293 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8108660229330118 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7668904593639576 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_startup = 4.202114845938374 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.042676767676768 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_startup = 3.568121284185492 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4433734939759013 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.738001912045894 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6993019943019885 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_startup = 9.616314102564106 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.480p.60fps.webm_scale_2_inclip = 9.711213592233019 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.40910646387833 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.931612021857926 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.399545454545464 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97739520958083 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.497337164750931 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.813189189189202 ms/frame
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | Cycle 1(12): loaded http://localhost:43328/tests/video/video_playback.html (next: http://localhost:43328/tests/video/video_playback.html)
17:34:43 INFO - PID 19911 | __start_tp_report
17:34:43 INFO - PID 19911 | _x_x_mozilla_page_load
17:34:43 INFO - PID 19911 | _x_x_mozilla_page_load_details
17:34:43 INFO - PID 19911 | |i|pagename|runs|
17:34:43 INFO - PID 19911 | |0;240p.120fps.mp4_scale_1_startup;1.6802155655095183;1.6789199776161163;1.6803275475923853;1.6829977565900167;1.6857921348314606;1.6838355780022447;1.6857893258426966;1.6817909192825113;1.685823033707865;1.6907690140845069;1.6893890765765767;1.6826892877173305
17:34:43 INFO - PID 19911 | |1;240p.120fps.mp4_scale_1_inclip;1.6726128762541803;1.6663530391340553;1.6744225941422597;1.6755025125628138;1.6709607351712619;1.6717000835421885;1.668586321934946;1.6687447873227688;1.6744351464435148;1.6703797996661103;1.6671416666666665;1.6738702928870293
17:34:43 INFO - PID 19911 | |2;240p.120fps.mp4_scale_1.1_startup;1.7261910241657077;1.7752485207100592;1.783048128342246;1.735485829959514;1.7779739336492888;1.7397507246376815;1.7476616191030865;1.779546263345196;1.7755473372781065;1.7715643447461629;1.7505221703617266;1.8108660229330118
17:34:43 INFO - PID 19911 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7069795221843003;1.7517775831873912;1.7766651865008878;1.7308347750865054;1.733479202772964;1.7143958868894593;1.7241982758620686;1.7427177700348426;1.7189690721649478;1.7468340611353712;1.7502099737532806;1.7668904593639576
17:34:43 INFO - PID 19911 | |4;240p.120fps.mp4_scale_2_startup;3.375163104611924;4.156350415512466;4.105198358413133;4.060115020297701;4.104876880984953;4.127283356258597;4.144109116022101;4.173894297635603;4.149854771784232;4.054979729729729;4.127159559834937;4.202114845938374
17:34:43 INFO - PID 19911 | |5;240p.120fps.mp4_scale_2_inclip;4.168104166666668;4.212010526315789;3.914863013698628;4.107577002053387;3.9154305283757336;4.09922131147541;4.1687812499999986;4.033921370967743;4.192903563941303;4.177181628392486;3.9001851851851854;4.042676767676768
17:34:43 INFO - PID 19911 | |6;480p.60fps.webm_scale_1_startup;3.5346996466431087;3.699475955610355;3.5428748524203053;3.5979076738609144;3.4525834292289987;3.6634981684981693;3.624323671497585;3.4649249422632793;3.456993087557602;3.6200603136308835;3.453550057537402;3.568121284185492
17:34:43 INFO - PID 19911 | |7;480p.60fps.webm_scale_1_inclip;3.4134982935153606;3.5794991055456213;3.515949033391917;3.541725663716809;3.4027891156462586;3.6438615664845164;3.449543103448277;3.4138907849829367;3.379628378378381;3.6434972677595607;3.3676094276094286;3.4433734939759013
17:34:43 INFO - PID 19911 | |8;480p.60fps.webm_scale_1.1_startup;5.6608773584905645;5.705684410646392;5.609859813084115;5.781907514450863;5.704990494296579;5.771173076923074;5.629803001876175;5.715171428571429;5.726469465648852;5.650216572504707;5.545471349353054;5.738001912045894
17:34:43 INFO - PID 19911 | |9;480p.60fps.webm_scale_1.1_inclip;5.543365650969536;5.63692957746478;5.634971830985908;5.5891620111731894;5.495659340659346;5.602829131652669;5.587835195530725;5.5869832402234625;5.635042253521133;5.465368852459019;5.636450704225348;5.6993019943019885
17:34:43 INFO - PID 19911 | |10;480p.60fps.webm_scale_2_startup;9.71085760517799;10.135929054054056;9.836360655737705;10.102356902356908;9.969019933554815;9.937516556291381;9.232400000000007;10.000816666666664;9.70935275080907;9.901699669966996;9.967707641196009;9.616314102564106
17:34:43 INFO - PID 19911 | |11;480p.60fps.webm_scale_2_inclip;9.094909090909095;9.307116279069762;9.620649038461544;9.136187214611876;9.664154589371979;9.479099526066351;9.345560747663537;9.30730232558141;9.904554455445533;9.90257425742574;9.855665024630545;9.711213592233019
17:34:43 INFO - PID 19911 | |12;1080p.60fps.mp4_scale_1_startup;11.367765151515153;11.238820224719099;11.674143968871599;11.3649053030303;11.62870155038759;11.279530075187969;11.237734082397012;11.32435849056603;11.323528301886794;11.326037735849063;11.45391221374047;11.40910646387833
17:34:43 INFO - PID 19911 | |13;1080p.60fps.mp4_scale_1_inclip;10.992390109890138;10.81389189189189;10.816621621621605;11.177346368715098;11.050552486187854;10.753655913978497;10.695721925133682;10.875054347826097;10.813837837837825;10.64156914893616;10.86967391304346;10.931612021857926
17:34:43 INFO - PID 19911 | |14;1080p.60fps.mp4_scale_1.1_startup;12.607121848739506;12.554016736401689;12.196585365853661;12.609327731092424;12.399276859504132;12.557092050209192;12.50477083333332;12.39729338842977;12.198109756097534;12.295553278688516;12.345987654320975;12.399545454545464
17:34:43 INFO - PID 19911 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.495028735632154;11.908749999999989;11.908452380952397;11.977365269461085;12.198292682926855;12.050210843373488;11.766823529411768;11.978952095808387;11.976167664670683;11.909375000000008;11.769764705882356;11.97739520958083
17:34:43 INFO - PID 19911 | |16;1080p.60fps.mp4_scale_2_startup;11.45438931297711;11.542384615384604;11.58409266409266;11.676848249027254;11.367481060606059;11.677451361867718;11.541711538461543;11.630310077519361;11.631550387596908;11.768725490196084;11.239962546816479;11.497337164750931
17:34:43 INFO - PID 19911 | |17;1080p.60fps.mp4_scale_2_inclip;11.174553072625672;10.93333333333335;11.367954545454557;11.364971590909095;10.699010695187146;10.812702702702694;11.052292817679579;11.111861111111123;11.050635359116027;10.930437158469928;10.816081081081073;10.813189189189202
17:34:43 INFO - PID 19911 | __end_tp_report
17:34:43 INFO - PID 19911 | __start_cc_report
17:34:43 INFO - PID 19911 | _x_x_mozilla_cycle_collect,580
17:34:43 INFO - PID 19911 | __end_cc_report
17:34:43 INFO - PID 19911 | __startTimestamp1512005683536__endTimestamp
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | ------- Summary: start -------
17:34:43 INFO - PID 19911 | Number of tests: 18
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#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
17:34:43 INFO - PID 19911 | 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
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.2%) stddev-sans-first:0.00
17:34:43 INFO - PID 19911 | 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
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.76 Median:1.78 stddev:0.02 (1.4%) stddev-sans-first:0.02
17:34:43 INFO - PID 19911 | Values: 1.7 1.8 1.8 1.7 1.8 1.7 1.7 1.8 1.8 1.8 1.8 1.8
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.74 Median:1.74 stddev:0.02 (1.2%) stddev-sans-first:0.02
17:34:43 INFO - PID 19911 | Values: 1.7 1.8 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.8 1.8
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.07 Median:4.14 stddev:0.22 (5.4%) stddev-sans-first:0.04
17:34:43 INFO - PID 19911 | Values: 3.4 4.2 4.1 4.1 4.1 4.1 4.1 4.2 4.1 4.1 4.1 4.2
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.08 Median:4.14 stddev:0.12 (2.8%) stddev-sans-first:0.12
17:34:43 INFO - PID 19911 | Values: 4.2 4.2 3.9 4.1 3.9 4.1 4.2 4.0 4.2 4.2 3.9 4.0
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.56 Median:3.58 stddev:0.09 (2.4%) stddev-sans-first:0.09
17:34:43 INFO - PID 19911 | Values: 3.5 3.7 3.5 3.6 3.5 3.7 3.6 3.5 3.5 3.6 3.5 3.6
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.48 Median:3.48 stddev:0.10 (2.8%) stddev-sans-first:0.10
17:34:43 INFO - PID 19911 | Values: 3.4 3.6 3.5 3.5 3.4 3.6 3.4 3.4 3.4 3.6 3.4 3.4
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.69 Median:5.71 stddev:0.07 (1.2%) stddev-sans-first:0.07
17:34:43 INFO - PID 19911 | Values: 5.7 5.7 5.6 5.8 5.7 5.8 5.6 5.7 5.7 5.7 5.5 5.7
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.59 Median:5.62 stddev:0.07 (1.2%) stddev-sans-first:0.07
17:34:43 INFO - PID 19911 | Values: 5.5 5.6 5.6 5.6 5.5 5.6 5.6 5.6 5.6 5.5 5.6 5.7
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.84 Median:9.77 stddev:0.25 (2.5%) stddev-sans-first:0.26
17:34:43 INFO - PID 19911 | Values: 9.7 10.1 9.8 10.1 10.0 9.9 9.2 10.0 9.7 9.9 10.0 9.6
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.53 Median:9.64 stddev:0.29 (3.0%) stddev-sans-first:0.27
17:34:43 INFO - PID 19911 | Values: 9.1 9.3 9.6 9.1 9.7 9.5 9.3 9.3 9.9 9.9 9.9 9.7
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.39 Median:11.37 stddev:0.14 (1.2%) stddev-sans-first:0.15
17:34:43 INFO - PID 19911 | Values: 11.4 11.2 11.7 11.4 11.6 11.3 11.2 11.3 11.3 11.3 11.5 11.4
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.87 Median:10.87 stddev:0.15 (1.4%) stddev-sans-first:0.15
17:34:43 INFO - PID 19911 | Values: 11.0 10.8 10.8 11.2 11.1 10.8 10.7 10.9 10.8 10.6 10.9 10.9
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.42 Median:12.45 stddev:0.15 (1.2%) stddev-sans-first:0.14
17:34:43 INFO - PID 19911 | Values: 12.6 12.6 12.2 12.6 12.4 12.6 12.5 12.4 12.2 12.3 12.3 12.4
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.91 Median:11.98 stddev:0.17 (1.5%) stddev-sans-first:0.12
17:34:43 INFO - PID 19911 | Values: 11.5 11.9 11.9 12.0 12.2 12.1 11.8 12.0 12.0 11.9 11.8 12.0
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.55 Median:11.61 stddev:0.15 (1.3%) stddev-sans-first:0.15
17:34:43 INFO - PID 19911 | Values: 11.5 11.5 11.6 11.7 11.4 11.7 11.5 11.6 11.6 11.8 11.2 11.5
17:34:43 INFO - PID 19911 |
17:34:43 INFO - PID 19911 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.01 Median:11.05 stddev:0.22 (2.0%) stddev-sans-first:0.22
17:34:43 INFO - PID 19911 | Values: 11.2 10.9 11.4 11.4 10.7 10.8 11.1 11.1 11.1 10.9 10.8 10.8
17:34:43 INFO - PID 19911 | -------- Summary: end --------
17:34:43 INFO - PID 19911 |
17:34:49 INFO - TEST-INFO | 19911: exit 0
17:34:49 INFO - TEST-OK | basic_compositor_video | took 570843ms
17:34:49 INFO - TEST-START | glvideo
17:34:49 INFO - Initialising browser for glvideo test...
17:34:49 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
17:34:49 INFO - Installing Add-ons
17:34:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43328/getInfo.html -profile /tmp/tmpUG9SVW/profile
17:34:49 INFO - TEST-INFO | started process 21181 (/builds/slave/test/build/application/firefox/firefox http://localhost:43328/getInfo.html)
17:34:59 INFO - TEST-INFO | 21181: exit 0
17:34:59 INFO - Browser initialized.
17:34:59 INFO - Running cycle 1/1 for glvideo test...
17:34:59 INFO - Using env: {'DISPLAY': ':0',
17:34:59 INFO - 'HOME': '/home/cltbld',
17:34:59 INFO - 'JSGC_DISABLE_POISONING': '1',
17:34:59 INFO - 'LANG': 'en_US.UTF-8',
17:34:59 INFO - 'LANGUAGE': 'en_US:en',
17:34:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
17:34:59 INFO - 'LOGNAME': 'cltbld',
17:34:59 INFO - 'MAIL': '/var/mail/cltbld',
17:34:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
17:34:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
17:34:59 INFO - 'MOZ_CRASHREPORTER': '1',
17:34:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:34:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
17:34:59 INFO - 'MOZ_NO_REMOTE': '1',
17:34:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
17:34:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:34:59 INFO - 'NO_EM_RESTART': '1',
17:34:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:34:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:34:59 INFO - 'PWD': '/builds/slave/test',
17:34:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
17:34:59 INFO - 'RUST_BACKTRACE': 'full',
17:34:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
17:34:59 INFO - 'SHELL': '/bin/bash',
17:34:59 INFO - 'SHLVL': '1',
17:34:59 INFO - 'TERM': 'linux',
17:34:59 INFO - 'TMOUT': '86400',
17:34:59 INFO - 'USER': 'cltbld',
17:34:59 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:34:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:34:59 INFO - '_': '/tools/buildbot/bin/python'}
17:34:59 INFO - TEST-INFO | started process 21510 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUG9SVW/profile)
17:35:00 INFO - PID 21510 |
17:35:00 INFO - PID 21510 | (/builds/slave/test/build/application/firefox/firefox:21561): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
17:35:00 INFO - PID 21510 |
17:35:01 INFO - PID 21510 | [talos glvideo result] Mean tick time across 100 ticks: 6.9035 ms
17:35:01 INFO - PID 21510 | Cycle 1(1): loaded http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html)
17:35:03 INFO - PID 21510 | [talos glvideo result] Mean tick time across 100 ticks: 6.490550000000001 ms
17:35:03 INFO - PID 21510 | Cycle 1(2): loaded http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html)
17:35:04 INFO - PID 21510 | [talos glvideo result] Mean tick time across 100 ticks: 6.580349999999999 ms
17:35:04 INFO - PID 21510 | Cycle 1(3): loaded http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html)
17:35:05 INFO - PID 21510 | [talos glvideo result] Mean tick time across 100 ticks: 6.6739 ms
17:35:05 INFO - PID 21510 | Cycle 1(4): loaded http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html)
17:35:07 INFO - PID 21510 | [talos glvideo result] Mean tick time across 100 ticks: 6.567150000000001 ms
17:35:07 INFO - PID 21510 | Cycle 1(5): loaded http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:43328/tests/webgl/benchmarks/video/video_upload.html)
17:35:07 INFO - PID 21510 | __start_tp_report
17:35:07 INFO - PID 21510 | _x_x_mozilla_page_load
17:35:07 INFO - PID 21510 | _x_x_mozilla_page_load_details
17:35:07 INFO - PID 21510 | |i|pagename|runs|
17:35:07 INFO - PID 21510 | |0;Mean tick time across 100 ticks: ;6.9035;6.490550000000001;6.580349999999999;6.6739;6.567150000000001
17:35:07 INFO - PID 21510 | __end_tp_report
17:35:07 INFO - PID 21510 | __start_cc_report
17:35:07 INFO - PID 21510 | _x_x_mozilla_cycle_collect,309
17:35:07 INFO - PID 21510 | __end_cc_report
17:35:07 INFO - PID 21510 | __startTimestamp1512005707351__endTimestamp
17:35:07 INFO - PID 21510 |
17:35:07 INFO - PID 21510 | ------- Summary: start -------
17:35:07 INFO - PID 21510 | Number of tests: 1
17:35:07 INFO - PID 21510 |
17:35:07 INFO - PID 21510 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.64 Median:6.58 stddev:0.16 (2.4%) stddev-sans-first:0.08
17:35:07 INFO - PID 21510 | Values: 6.9 6.5 6.6 6.7 6.6
17:35:07 INFO - PID 21510 | -------- Summary: end --------
17:35:07 INFO - PID 21510 |
17:35:07 INFO - PID 21510 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
17:35:13 INFO - TEST-INFO | 21510: exit 0
17:35:13 INFO - TEST-OK | glvideo | took 23693ms
17:35:13 INFO - TEST-START | displaylist_mutate
17:35:13 INFO - Initialising browser for displaylist_mutate test...
17:35:13 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
17:35:13 INFO - Installing Add-ons
17:35:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43328/getInfo.html -profile /tmp/tmpLIIToK/profile
17:35:13 INFO - TEST-INFO | started process 21738 (/builds/slave/test/build/application/firefox/firefox http://localhost:43328/getInfo.html)
17:35:23 INFO - TEST-INFO | 21738: exit 0
17:35:23 INFO - Browser initialized.
17:35:23 INFO - Running cycle 1/1 for displaylist_mutate test...
17:35:23 INFO - Using env: {'DISPLAY': ':0',
17:35:23 INFO - 'HOME': '/home/cltbld',
17:35:23 INFO - 'JSGC_DISABLE_POISONING': '1',
17:35:23 INFO - 'LANG': 'en_US.UTF-8',
17:35:23 INFO - 'LANGUAGE': 'en_US:en',
17:35:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
17:35:23 INFO - 'LOGNAME': 'cltbld',
17:35:23 INFO - 'MAIL': '/var/mail/cltbld',
17:35:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
17:35:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
17:35:23 INFO - 'MOZ_CRASHREPORTER': '1',
17:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:35:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
17:35:23 INFO - 'MOZ_NO_REMOTE': '1',
17:35:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
17:35:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:35:23 INFO - 'NO_EM_RESTART': '1',
17:35:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:35:23 INFO - 'PWD': '/builds/slave/test',
17:35:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
17:35:23 INFO - 'RUST_BACKTRACE': 'full',
17:35:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
17:35:23 INFO - 'SHELL': '/bin/bash',
17:35:23 INFO - 'SHLVL': '1',
17:35:23 INFO - 'TERM': 'linux',
17:35:23 INFO - 'TMOUT': '86400',
17:35:23 INFO - 'USER': 'cltbld',
17:35:23 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469',
17:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:35:23 INFO - '_': '/tools/buildbot/bin/python'}
17:35:23 INFO - TEST-INFO | started process 22013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLIIToK/profile)
17:35:24 INFO - PID 22013 |
17:35:24 INFO - PID 22013 | (/builds/slave/test/build/application/firefox/firefox:22064): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
17:35:24 INFO - PID 22013 |
17:35:30 INFO - PID 22013 | Cycle 1(1): loaded http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html)
17:35:30 INFO - PID 22013 | Waiting for idle-callback
17:35:36 INFO - PID 22013 | Cycle 1(2): loaded http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html)
17:35:42 INFO - PID 22013 | Cycle 1(3): loaded http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html)
17:35:48 INFO - PID 22013 | Cycle 1(4): loaded http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html)
17:35:48 INFO - PID 22013 | Waiting for idle-callback
17:35:54 INFO - PID 22013 | Cycle 1(5): loaded http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:43328/tests/layout/benchmarks/displaylist_mutate.html)
17:35:54 INFO - PID 22013 | __start_tp_report
17:35:54 INFO - PID 22013 | _x_x_mozilla_page_load
17:35:54 INFO - PID 22013 | _x_x_mozilla_page_load_details
17:35:54 INFO - PID 22013 | |i|pagename|runs|
17:35:54 INFO - PID 22013 | |0;/displaylist_mutate.html;4946.5;4966.44;5069.6449999999995;5242.275000000001;4923.965
17:35:54 INFO - PID 22013 | __end_tp_report
17:35:54 INFO - PID 22013 | __start_cc_report
17:35:54 INFO - PID 22013 | _x_x_mozilla_cycle_collect,253
17:35:54 INFO - PID 22013 | __end_cc_report
17:35:54 INFO - PID 22013 | __startTimestamp1512005754399__endTimestamp
17:35:54 INFO - PID 22013 |
17:35:54 INFO - PID 22013 | ------- Summary: start -------
17:35:54 INFO - PID 22013 | Number of tests: 1
17:35:54 INFO - PID 22013 |
17:35:54 INFO - PID 22013 | [#0] /displaylist_mutate.html Cycles:5 Average:5029.77 Median:4966.44 stddev:131.23 (2.6%) stddev-sans-first:141.68
17:35:54 INFO - PID 22013 | Values: 4946.5 4966.4 5069.6 5242.3 4924.0
17:35:54 INFO - PID 22013 | -------- Summary: end --------
17:35:54 INFO - PID 22013 |
17:35:54 INFO - PID 22013 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
17:36:00 INFO - TEST-INFO | 22013: exit 0
17:36:00 INFO - TEST-OK | displaylist_mutate | took 47125ms
17:36:00 INFO - SUITE-END | took 641s
17:36:00 INFO - Completed test suite (00:10:41)
17:36:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.601630555249174, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6802155655095183, 1.6789199776161163, 1.6803275475923853, 1.6829977565900167, 1.6857921348314606, 1.6838355780022447, 1.6857893258426966, 1.6817909192825113, 1.685823033707865, 1.6907690140845069, 1.6893890765765767, 1.6826892877173305], "value": 1.6838355780022447, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6726128762541803, 1.6663530391340553, 1.6744225941422597, 1.6755025125628138, 1.6709607351712619, 1.6717000835421885, 1.668586321934946, 1.6687447873227688, 1.6744351464435148, 1.6703797996661103, 1.6671416666666665, 1.6738702928870293], "value": 1.6709607351712619, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7261910241657077, 1.7752485207100592, 1.783048128342246, 1.735485829959514, 1.7779739336492888, 1.7397507246376815, 1.7476616191030865, 1.779546263345196, 1.7755473372781065, 1.7715643447461629, 1.7505221703617266, 1.8108660229330118], "value": 1.7752485207100592, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7069795221843003, 1.7517775831873912, 1.7766651865008878, 1.7308347750865054, 1.733479202772964, 1.7143958868894593, 1.7241982758620686, 1.7427177700348426, 1.7189690721649478, 1.7468340611353712, 1.7502099737532806, 1.7668904593639576], "value": 1.7427177700348426, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.375163104611924, 4.156350415512466, 4.105198358413133, 4.060115020297701, 4.104876880984953, 4.127283356258597, 4.144109116022101, 4.173894297635603, 4.149854771784232, 4.054979729729729, 4.127159559834937, 4.202114845938374], "value": 4.127283356258597, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.168104166666668, 4.212010526315789, 3.914863013698628, 4.107577002053387, 3.9154305283757336, 4.09922131147541, 4.1687812499999986, 4.033921370967743, 4.192903563941303, 4.177181628392486, 3.9001851851851854, 4.042676767676768], "value": 4.09922131147541, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5346996466431087, 3.699475955610355, 3.5428748524203053, 3.5979076738609144, 3.4525834292289987, 3.6634981684981693, 3.624323671497585, 3.4649249422632793, 3.456993087557602, 3.6200603136308835, 3.453550057537402, 3.568121284185492], "value": 3.568121284185492, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4134982935153606, 3.5794991055456213, 3.515949033391917, 3.541725663716809, 3.4027891156462586, 3.6438615664845164, 3.449543103448277, 3.4138907849829367, 3.379628378378381, 3.6434972677595607, 3.3676094276094286, 3.4433734939759013], "value": 3.449543103448277, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.6608773584905645, 5.705684410646392, 5.609859813084115, 5.781907514450863, 5.704990494296579, 5.771173076923074, 5.629803001876175, 5.715171428571429, 5.726469465648852, 5.650216572504707, 5.545471349353054, 5.738001912045894], "value": 5.705684410646392, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.543365650969536, 5.63692957746478, 5.634971830985908, 5.5891620111731894, 5.495659340659346, 5.602829131652669, 5.587835195530725, 5.5869832402234625, 5.635042253521133, 5.465368852459019, 5.636450704225348, 5.6993019943019885], "value": 5.602829131652669, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.71085760517799, 10.135929054054056, 9.836360655737705, 10.102356902356908, 9.969019933554815, 9.937516556291381, 9.232400000000007, 10.000816666666664, 9.70935275080907, 9.901699669966996, 9.967707641196009, 9.616314102564106], "value": 9.937516556291381, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.094909090909095, 9.307116279069762, 9.620649038461544, 9.136187214611876, 9.664154589371979, 9.479099526066351, 9.345560747663537, 9.30730232558141, 9.904554455445533, 9.90257425742574, 9.855665024630545, 9.711213592233019], "value": 9.620649038461544, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.367765151515153, 11.238820224719099, 11.674143968871599, 11.3649053030303, 11.62870155038759, 11.279530075187969, 11.237734082397012, 11.32435849056603, 11.323528301886794, 11.326037735849063, 11.45391221374047, 11.40910646387833], "value": 11.326037735849063, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.992390109890138, 10.81389189189189, 10.816621621621605, 11.177346368715098, 11.050552486187854, 10.753655913978497, 10.695721925133682, 10.875054347826097, 10.813837837837825, 10.64156914893616, 10.86967391304346, 10.931612021857926], "value": 10.816621621621605, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.607121848739506, 12.554016736401689, 12.196585365853661, 12.609327731092424, 12.399276859504132, 12.557092050209192, 12.50477083333332, 12.39729338842977, 12.198109756097534, 12.295553278688516, 12.345987654320975, 12.399545454545464], "value": 12.399276859504132, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.495028735632154, 11.908749999999989, 11.908452380952397, 11.977365269461085, 12.198292682926855, 12.050210843373488, 11.766823529411768, 11.978952095808387, 11.976167664670683, 11.909375000000008, 11.769764705882356, 11.97739520958083], "value": 11.976167664670683, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.45438931297711, 11.542384615384604, 11.58409266409266, 11.676848249027254, 11.367481060606059, 11.677451361867718, 11.541711538461543, 11.630310077519361, 11.631550387596908, 11.768725490196084, 11.239962546816479, 11.497337164750931], "value": 11.58409266409266, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.174553072625672, 10.93333333333335, 11.367954545454557, 11.364971590909095, 10.699010695187146, 10.812702702702694, 11.052292817679579, 11.111861111111123, 11.050635359116027, 10.930437158469928, 10.816081081081073, 10.813189189189202], "value": 10.93333333333335, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.9035, 6.490550000000001, 6.580349999999999, 6.6739, 6.567150000000001], "value": 6.57375, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4946.5, 4966.44, 5069.6449999999995, 5242.275000000001, 4923.965], "value": 5018.0425, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
17:36:00 INFO - Return code: 0
17:36:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
17:36:00 INFO - # TBPL SUCCESS #
17:36:00 INFO - Running post-action listener: _package_coverage_data
17:36:00 INFO - Running post-action listener: _resource_record_post_action
17:36:00 INFO - [mozharness: 2017-11-30 01:36:00.630793Z] Finished run-tests step (success)
17:36:00 INFO - Running post-run listener: _resource_record_post_run
17:36:00 INFO - Total resource usage - Wall time: 661s; CPU: 36.0%; Read bytes: 8192; Write bytes: 349593600; Read time: 212; Write time: 227056
17:36:00 INFO - TinderboxPrint: CPU usage
35.7%
17:36:00 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 212
17:36:00 INFO - TinderboxPrint: I/O write bytes / time
349,593,600 / 227,056
17:36:00 INFO - TinderboxPrint: CPU idle
3,338.3 (64.3%)
17:36:00 INFO - TinderboxPrint: CPU system
305.6 (5.9%)
17:36:00 INFO - TinderboxPrint: CPU user
1,529.7 (29.5%)
17:36:00 INFO - TinderboxPrint: Swap in / out
0 / 0
17:36:00 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 7041024; Read time: 0; Write time: 3692
17:36:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
17:36:00 INFO - run-tests - Wall time: 643s; CPU: 36.0%; Read bytes: 8192; Write bytes: 342552576; Read time: 212; Write time: 223364
17:36:01 INFO - Running post-run listener: _upload_blobber_files
17:36:01 INFO - Blob upload gear active.
17:36:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
17:36:01 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
17:36:01 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']
17:36:01 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
17:36:01 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
17:36:01 INFO - SNIMissingWarning
17:36:01 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
17:36:01 INFO - InsecurePlatformWarning
17:36:01 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.)
17:36:01 INFO - SubjectAltNameWarning
17:36:01 INFO - (blobuploader) - INFO - Open directory for files ...
17:36:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
17:36:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:36:01 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:36:01 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
17:36:01 INFO - InsecurePlatformWarning
17:36:01 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.)
17:36:01 INFO - SubjectAltNameWarning
17:36:02 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
17:36:02 INFO - InsecurePlatformWarning
17:36:02 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
17:36:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:36:02 INFO - (blobuploader) - INFO - Done attempting.
17:36:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
17:36:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:36:02 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:36:02 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
17:36:02 INFO - InsecurePlatformWarning
17:36:02 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.)
17:36:02 INFO - SubjectAltNameWarning
17:36:07 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
17:36:07 INFO - InsecurePlatformWarning
17:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
17:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:36:07 INFO - (blobuploader) - INFO - Done attempting.
17:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
17:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:36:07 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
17:36:07 INFO - InsecurePlatformWarning
17:36:07 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.)
17:36:07 INFO - SubjectAltNameWarning
17:36:08 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
17:36:08 INFO - InsecurePlatformWarning
17:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
17:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:36:08 INFO - (blobuploader) - INFO - Done attempting.
17:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
17:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:36:08 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
17:36:08 INFO - InsecurePlatformWarning
17:36:08 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.)
17:36:08 INFO - SubjectAltNameWarning
17:36:09 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
17:36:09 INFO - InsecurePlatformWarning
17:36:09 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
17:36:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:36:09 INFO - (blobuploader) - INFO - Done attempting.
17:36:09 INFO - (blobuploader) - INFO - Iteration through files over.
17:36:09 INFO - Return code: 0
17:36:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
17:36:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
17:36:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fbda8e7cb14b119f709b348cbb11ec7ec268a3873e7a5907dbb73f2b4f17a8e0ac905b0d032e247bd7d1da77ae0171f2171db972fa9ae8d41b31df702d2fd99f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a22a392dadf0c2c8580fed2c3d5ac9a61b78e5798f1ffe4103fa4030ab10ec3e6b5d69f0d70aa08b9df5b915cb4b2746b7c380acfafe8eba68f2ddc85dbc268", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76862f2af4aa3c9f32a2ee5d5448363ef8b17f2509b1f27b632eee7abc59db274fc0a226e25ccd952e5442f166abd89a48c8aa70f0d4bcd380b1fe923634488d", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c2029dbd8d702ac8500c046360d715b7ad333d5aadbe6e6305ecef58e37684ee922260f84b678b565e202929041425645b6bf3af5c41a51c035ba5425b2e2dd"}
17:36:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
17:36:09 INFO - Writing to file /builds/slave/test/properties/blobber_files
17:36:09 INFO - Contents:
17:36:09 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fbda8e7cb14b119f709b348cbb11ec7ec268a3873e7a5907dbb73f2b4f17a8e0ac905b0d032e247bd7d1da77ae0171f2171db972fa9ae8d41b31df702d2fd99f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a22a392dadf0c2c8580fed2c3d5ac9a61b78e5798f1ffe4103fa4030ab10ec3e6b5d69f0d70aa08b9df5b915cb4b2746b7c380acfafe8eba68f2ddc85dbc268", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76862f2af4aa3c9f32a2ee5d5448363ef8b17f2509b1f27b632eee7abc59db274fc0a226e25ccd952e5442f166abd89a48c8aa70f0d4bcd380b1fe923634488d", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c2029dbd8d702ac8500c046360d715b7ad333d5aadbe6e6305ecef58e37684ee922260f84b678b565e202929041425645b6bf3af5c41a51c035ba5425b2e2dd"}
17:36:09 INFO - Running post-run listener: copy_logs_to_upload_dir
17:36:09 INFO - Copying logs to upload dir...
17:36:09 INFO - mkdir: /builds/slave/test/build/upload/logs
17:36:09 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=725.429621
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 5 secs) (at 2017-11-29 17:36:09.418705) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-29 17:36:09.422314) =========
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=31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469
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/fbda8e7cb14b119f709b348cbb11ec7ec268a3873e7a5907dbb73f2b4f17a8e0ac905b0d032e247bd7d1da77ae0171f2171db972fa9ae8d41b31df702d2fd99f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a22a392dadf0c2c8580fed2c3d5ac9a61b78e5798f1ffe4103fa4030ab10ec3e6b5d69f0d70aa08b9df5b915cb4b2746b7c380acfafe8eba68f2ddc85dbc268", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76862f2af4aa3c9f32a2ee5d5448363ef8b17f2509b1f27b632eee7abc59db274fc0a226e25ccd952e5442f166abd89a48c8aa70f0d4bcd380b1fe923634488d", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c2029dbd8d702ac8500c046360d715b7ad333d5aadbe6e6305ecef58e37684ee922260f84b678b565e202929041425645b6bf3af5c41a51c035ba5425b2e2dd"}
build_url:https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011419
build_url: 'https://queue.taskcluster.net/v1/task/ADCpvu8ORQqcdxu_pad5PA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fbda8e7cb14b119f709b348cbb11ec7ec268a3873e7a5907dbb73f2b4f17a8e0ac905b0d032e247bd7d1da77ae0171f2171db972fa9ae8d41b31df702d2fd99f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7a22a392dadf0c2c8580fed2c3d5ac9a61b78e5798f1ffe4103fa4030ab10ec3e6b5d69f0d70aa08b9df5b915cb4b2746b7c380acfafe8eba68f2ddc85dbc268", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76862f2af4aa3c9f32a2ee5d5448363ef8b17f2509b1f27b632eee7abc59db274fc0a226e25ccd952e5442f166abd89a48c8aa70f0d4bcd380b1fe923634488d", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c2029dbd8d702ac8500c046360d715b7ad333d5aadbe6e6305ecef58e37684ee922260f84b678b565e202929041425645b6bf3af5c41a51c035ba5425b2e2dd"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-29 17:36:09.452356) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-29 17:36:09.452691) =========
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=31acecf42b6a79ec49471d4a000001bb-1512004926.141170-574609469
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004461
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-29 17:36:09.537401) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-29 17:36:09.537744) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-29 17:36:09.538094) =========
========= Total master_lag: 0.21 =========