builder: mozilla-beta_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-015
starttime: 1512403168.4
results: success (0)
revision: f402c67ee9b03e2057afee139eea99456749b65e
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:28.400614) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:28.401049) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:28.401319) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:28.415840) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:28.416169) =========
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=20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-12-04 07:59:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 107M=0s
2017-12-04 07:59:28 (107 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.248868
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:28.683688) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:28.683995) =========
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=20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.016644
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:28.733739) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-04 07:59:28.734089) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f402c67ee9b03e2057afee139eea99456749b65e --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f402c67ee9b03e2057afee139eea99456749b65e --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=20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-12-04 07:59:28,782 truncating revision to first 12 chars
2017-12-04 07:59:28,782 Setting DEBUG logging.
2017-12-04 07:59:28,782 attempt 1/10
2017-12-04 07:59:28,782 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f402c67ee9b0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-12-04 07:59:29,601 unpacking tar archive at: mozilla-beta-f402c67ee9b0/testing/mozharness/
program finished with exit code 0
elapsedTime=1.406091
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-04 07:59:30.157955) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:30.158271) =========
script_repo_revision: f402c67ee9b03e2057afee139eea99456749b65e
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:30.158671) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:30.158941) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-04 07:59:30.173170) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 46 secs) (at 2017-12-04 07:59:30.173559) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
07:59:30 INFO - MultiFileLogger online at 20171204 07:59:30 in /builds/slave/test
07:59:30 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
07:59:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
07:59:30 INFO - {'append_to_log': False,
07:59:30 INFO - 'base_work_dir': '/builds/slave/test',
07:59:30 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO',
07:59:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
07:59:30 INFO - 'branch': 'Mozilla-Beta-Non-PGO',
07:59:30 INFO - 'buildbot_json_path': 'buildprops.json',
07:59:30 INFO - 'code_coverage': False,
07:59:30 INFO - 'config_files': ('talos/linux_config.py',),
07:59:30 INFO - 'default_actions': ('clobber',
07:59:30 INFO - 'read-buildbot-config',
07:59:30 INFO - 'download-and-extract',
07:59:30 INFO - 'populate-webroot',
07:59:30 INFO - 'create-virtualenv',
07:59:30 INFO - 'install',
07:59:30 INFO - 'setup-mitmproxy',
07:59:30 INFO - 'run-tests'),
07:59:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
07:59:30 INFO - 'disable_ccov_upload': False,
07:59:30 INFO - 'disable_stylo': False,
07:59:30 INFO - 'download_minidump_stackwalk': True,
07:59:30 INFO - 'download_symbols': 'ondemand',
07:59:30 INFO - 'e10s': False,
07:59:30 INFO - 'enable_stylo': False,
07:59:30 INFO - 'enable_webrender': False,
07:59:30 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
07:59:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
07:59:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
07:59:30 INFO - 'gecko_profile': False,
07:59:30 INFO - 'gecko_profile_interval': 0,
07:59:30 INFO - 'installer_path': 'installer.exe',
07:59:30 INFO - 'jsd_code_coverage': False,
07:59:30 INFO - 'log_level': 'info',
07:59:30 INFO - 'log_name': 'talos',
07:59:30 INFO - 'log_to_console': True,
07:59:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
07:59:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
07:59:30 INFO - 'opt_config_files': (),
07:59:30 INFO - 'pip_index': False,
07:59:30 INFO - 'suite': 'g4-e10s',
07:59:30 INFO - 'system_bits': '32',
07:59:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
07:59:30 INFO - 'title': 'talos-linux64-ix-015',
07:59:30 INFO - 'tooltool_cache': '/builds/tooltool_cache',
07:59:30 INFO - 'use_talos_json': True,
07:59:30 INFO - 'verify': 'False',
07:59:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
07:59:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
07:59:30 INFO - 'work_dir': 'build'}
07:59:30 INFO - [mozharness: 2017-12-04 15:59:30.368787Z] Running clobber step.
07:59:30 INFO - Running pre-action listener: _resource_record_pre_action
07:59:30 INFO - Running main action method: clobber
07:59:30 INFO - rmtree: /builds/slave/test/build
07:59:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
07:59:31 INFO - Running post-action listener: _resource_record_post_action
07:59:31 INFO - [mozharness: 2017-12-04 15:59:31.436881Z] Finished clobber step (success)
07:59:31 INFO - [mozharness: 2017-12-04 15:59:31.436977Z] Running read-buildbot-config step.
07:59:31 INFO - Running pre-action listener: _resource_record_pre_action
07:59:31 INFO - Running main action method: read_buildbot_config
07:59:31 INFO - Using buildbot properties:
07:59:31 INFO - {
07:59:31 INFO - "project": "",
07:59:31 INFO - "product": "firefox",
07:59:31 INFO - "who": "archaeopteryx@coole-files.de",
07:59:31 INFO - "installer_path": "public/build/target.tar.bz2",
07:59:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-g4-e10s",
07:59:31 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta",
07:59:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos g4-e10s",
07:59:31 INFO - "stage_platform": "linux64",
07:59:31 INFO - "basedir": "/builds/slave/test",
07:59:31 INFO - "buildnumber": 69,
07:59:31 INFO - "platform": "ubuntu64_hw",
07:59:31 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
07:59:31 INFO - "slavebuilddir": "test",
07:59:31 INFO - "taskId": "EpU6Bl93Q2uxnQV-fOVW5g",
07:59:31 INFO - "branch": "mozilla-beta",
07:59:31 INFO - "script_repo_revision": "production",
07:59:31 INFO - "revision": "f402c67ee9b03e2057afee139eea99456749b65e",
07:59:31 INFO - "slavename": "talos-linux64-ix-015",
07:59:31 INFO - "repo_path": "releases/mozilla-beta"
07:59:31 INFO - }
07:59:31 INFO - Finding installer, test and symbols from parent task.
07:59:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EpU6Bl93Q2uxnQV-fOVW5g'}, attempt #1
07:59:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EpU6Bl93Q2uxnQV-fOVW5g'}, attempt #1
07:59:32 INFO - Task dependencies: N73ZAk91SPeDiS7selHRAA
07:59:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA'}, attempt #1
07:59:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.tar.bz2
07:59:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.test_packages.json
07:59:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.crashreporter-symbols.zip
07:59:32 INFO - Running post-action listener: _resource_record_post_action
07:59:32 INFO - [mozharness: 2017-12-04 15:59:32.770401Z] Finished read-buildbot-config step (success)
07:59:32 INFO - [mozharness: 2017-12-04 15:59:32.770618Z] Running download-and-extract step.
07:59:32 INFO - Running pre-action listener: _resource_record_pre_action
07:59:32 INFO - Running main action method: download_and_extract
07:59:32 INFO - mkdir: /builds/slave/test/build/tests
07:59: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')]}
07:59:32 INFO - trying https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.test_packages.json
07:59:32 INFO - Downloading https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
07:59:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
07:59:33 INFO - Downloaded 989 bytes.
07:59:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
07:59:33 INFO - Using the following test package requirements:
07:59:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
07:59:33 INFO - u'common': [u'target.common.tests.zip'],
07:59:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
07:59:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
07:59:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
07:59:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
07:59:33 INFO - u'mozbase': [u'target.common.tests.zip'],
07:59:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
07:59:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
07:59:33 INFO - u'web-platform': [u'target.common.tests.zip',
07:59:33 INFO - u'target.web-platform.tests.tar.gz'],
07:59:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
07:59:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
07:59:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.common.tests.zip
07:59:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.common.tests.zip'}, attempt #1
07:59:33 INFO - Fetch https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.common.tests.zip into memory
07:59:34 INFO - Content-Length response header: 58311962
07:59:34 INFO - Bytes received: 58311962
07:59:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.talos.tests.zip
07:59:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
07:59:45 INFO - Fetch https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.talos.tests.zip into memory
07:59:45 INFO - Content-Length response header: 18772095
07:59:45 INFO - Bytes received: 18772095
07:59:46 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')]}
07:59:46 INFO - trying https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.tar.bz2
07:59:46 INFO - Downloading https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
07:59:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
07:59:47 INFO - Downloaded 54024247 bytes.
07:59:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.tar.bz2
07:59:47 INFO - mkdir: /builds/slave/test/properties
07:59:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
07:59:47 INFO - Writing to file /builds/slave/test/properties/build_url
07:59:47 INFO - Contents:
07:59:47 INFO - build_url:https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.tar.bz2
07:59:47 INFO - Running post-action listener: _resource_record_post_action
07:59:47 INFO - Running post-action listener: find_tests_for_verification
07:59:47 INFO - Running post-action listener: set_extra_try_arguments
07:59:47 INFO - [mozharness: 2017-12-04 15:59:47.611456Z] Finished download-and-extract step (success)
07:59:47 INFO - [mozharness: 2017-12-04 15:59:47.611550Z] Running populate-webroot step.
07:59:47 INFO - Running pre-action listener: _resource_record_pre_action
07:59:47 INFO - Running main action method: populate_webroot
07:59:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
07:59:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['tresize',
07:59:47 INFO - 'tcanvasmark']},
07:59:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
07:59:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['dromaeo_css',
07:59:47 INFO - 'kraken']},
07:59:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
07:59:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
07:59:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'tests': ['damp', 'tps']},
07:59:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['damp', 'tps']},
07:59:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
07:59:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['dromaeo_dom']},
07:59:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
07:59:47 INFO - 'glvideo',
07:59:47 INFO - 'displaylist_mutate']},
07:59:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['basic_compositor_video',
07:59:47 INFO - 'glvideo']},
07:59:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
07:59:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['ts_paint_webext',
07:59:47 INFO - 'tp5o_webext']},
07:59:47 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
07:59:47 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
07:59:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
07:59:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
07:59:47 INFO - 'talos_options': ['--mitmproxy',
07:59:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
07:59:47 INFO - '--firstNonBlankPaint'],
07:59:47 INFO - 'tests': ['tp6_google_heavy',
07:59:47 INFO - 'tp6_youtube_heavy',
07:59:47 INFO - 'tp6_amazon_heavy',
07:59:47 INFO - 'tp6_facebook_heavy']},
07:59:47 INFO - 'other-e10s': {'tests': ['a11yr',
07:59:47 INFO - 'ts_paint',
07:59:47 INFO - 'tpaint',
07:59:47 INFO - 'sessionrestore',
07:59:47 INFO - 'sessionrestore_many_windows',
07:59:47 INFO - 'sessionrestore_no_auto_restore',
07:59:47 INFO - 'tabpaint',
07:59:47 INFO - 'cpstartup']},
07:59:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['a11yr',
07:59:47 INFO - 'ts_paint',
07:59:47 INFO - 'tpaint',
07:59:47 INFO - 'sessionrestore',
07:59:47 INFO - 'sessionrestore_many_windows',
07:59:47 INFO - 'sessionrestore_no_auto_restore',
07:59:47 INFO - 'tabpaint',
07:59:47 INFO - 'cpstartup']},
07:59:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
07:59:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
07:59:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['perf_reftest_singletons']},
07:59:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['perf_reftest']},
07:59:47 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
07:59:47 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['speedometer']},
07:59:47 INFO - 'svgr-e10s': {'tests': ['tsvgx',
07:59:47 INFO - 'tsvgr_opacity',
07:59:47 INFO - 'tart',
07:59:47 INFO - 'tscrollx',
07:59:47 INFO - 'tsvg_static']},
07:59:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['tsvgx',
07:59:47 INFO - 'tsvgr_opacity',
07:59:47 INFO - 'tart',
07:59:47 INFO - 'tscrollx',
07:59:47 INFO - 'tsvg_static']},
07:59:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
07:59:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'talos_options': ['--disable-stylo'],
07:59:47 INFO - 'tests': ['tp5o']},
07:59:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
07:59:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
07:59:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
07:59:47 INFO - 'talos_options': ['--mitmproxy',
07:59:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
07:59:47 INFO - '--firstNonBlankPaint'],
07:59:47 INFO - 'tests': ['tp6_google',
07:59:47 INFO - 'tp6_youtube',
07:59:47 INFO - 'tp6_amazon',
07:59:47 INFO - 'tp6_facebook']},
07:59:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
07:59:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
07:59:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
07:59:47 INFO - 'talos_options': ['--disable-stylo',
07:59:47 INFO - '--mitmproxy',
07:59:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
07:59:47 INFO - '--firstNonBlankPaint'],
07:59:47 INFO - 'tests': ['tp6_google',
07:59:47 INFO - 'tp6_youtube',
07:59:47 INFO - 'tp6_amazon',
07:59:47 INFO - 'tp6_facebook']},
07:59:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
07:59:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
07:59:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
07:59:47 INFO - 'talos_options': ['--stylo-threads=1',
07:59:47 INFO - '--mitmproxy',
07:59:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
07:59:47 INFO - '--firstNonBlankPaint'],
07:59:47 INFO - 'tests': ['tp6_google',
07:59:47 INFO - 'tp6_youtube',
07:59:47 INFO - 'tp6_amazon',
07:59:47 INFO - 'tp6_facebook']},
07:59:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'talos_options': ['--xperf_path',
07:59:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:59:47 INFO - 'tests': ['tp5n']},
07:59:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:59:47 INFO - 'talos_options': ['--disable-stylo',
07:59:47 INFO - '--xperf_path',
07:59:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:59:47 INFO - 'tests': ['tp5n']}}}
07:59:47 INFO - Running post-action listener: _resource_record_post_action
07:59:47 INFO - [mozharness: 2017-12-04 15:59:47.630343Z] Finished populate-webroot step (success)
07:59:47 INFO - [mozharness: 2017-12-04 15:59:47.630436Z] Running create-virtualenv step.
07:59:47 INFO - Running pre-action listener: _resource_record_pre_action
07:59:47 INFO - Running main action method: create_virtualenv
07:59:47 INFO - Creating virtualenv /builds/slave/test/build/venv
07:59:47 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
07:59:47 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
07:59:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
07:59:47 INFO - Using real prefix '/usr'
07:59:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python
07:59:47 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
07:59:49 INFO - Installing setuptools, pip, wheel...done.
07:59:49 INFO - Return code: 0
07:59:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
07:59:49 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')]}
07:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:59:49 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')]}
07:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:59:49 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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
07:59:49 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
07:59:49 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
07:59:49 INFO - Using env: {'DISPLAY': ':0',
07:59:49 INFO - 'HOME': '/home/cltbld',
07:59:49 INFO - 'LANG': 'en_US.UTF-8',
07:59:49 INFO - 'LANGUAGE': 'en_US:en',
07:59:49 INFO - 'LOGNAME': 'cltbld',
07:59:49 INFO - 'MAIL': '/var/mail/cltbld',
07:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:59:49 INFO - 'MOZ_NO_REMOTE': '1',
07:59:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:59:49 INFO - 'NO_EM_RESTART': '1',
07:59:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:59:49 INFO - 'PWD': '/builds/slave/test',
07:59:49 INFO - 'SHELL': '/bin/bash',
07:59:49 INFO - 'SHLVL': '1',
07:59:49 INFO - 'TERM': 'linux',
07:59:49 INFO - 'TMOUT': '86400',
07:59:49 INFO - 'USER': 'cltbld',
07:59:49 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
07:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:59:49 INFO - '_': '/tools/buildbot/bin/python'}
07:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple
07:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
07:59:51 INFO - Return code: 0
07:59:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
07:59: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')]}
07:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:59: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')]}
07:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:59:51 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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
07:59:51 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
07:59:51 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
07:59:51 INFO - Using env: {'DISPLAY': ':0',
07:59:51 INFO - 'HOME': '/home/cltbld',
07:59:51 INFO - 'LANG': 'en_US.UTF-8',
07:59:51 INFO - 'LANGUAGE': 'en_US:en',
07:59:51 INFO - 'LOGNAME': 'cltbld',
07:59:51 INFO - 'MAIL': '/var/mail/cltbld',
07:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:59:51 INFO - 'MOZ_NO_REMOTE': '1',
07:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:59:51 INFO - 'NO_EM_RESTART': '1',
07:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:59:51 INFO - 'PWD': '/builds/slave/test',
07:59:51 INFO - 'SHELL': '/bin/bash',
07:59:51 INFO - 'SHLVL': '1',
07:59:51 INFO - 'TERM': 'linux',
07:59:51 INFO - 'TMOUT': '86400',
07:59:51 INFO - 'USER': 'cltbld',
07:59:51 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
07:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:59:51 INFO - '_': '/tools/buildbot/bin/python'}
07:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple
07:59:52 INFO - Collecting psutil>=3.1.1
07:59:53 INFO - Installing collected packages: psutil
07:59:53 INFO - Successfully installed psutil-3.1.1
07:59:53 INFO - Return code: 0
07:59:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
07:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:59:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
07:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
07:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
07:59:53 INFO - Using env: {'DISPLAY': ':0',
07:59:53 INFO - 'HOME': '/home/cltbld',
07:59:53 INFO - 'LANG': 'en_US.UTF-8',
07:59:53 INFO - 'LANGUAGE': 'en_US:en',
07:59:53 INFO - 'LOGNAME': 'cltbld',
07:59:53 INFO - 'MAIL': '/var/mail/cltbld',
07:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:59:53 INFO - 'MOZ_NO_REMOTE': '1',
07:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:59:53 INFO - 'NO_EM_RESTART': '1',
07:59:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:59:53 INFO - 'PWD': '/builds/slave/test',
07:59:53 INFO - 'SHELL': '/bin/bash',
07:59:53 INFO - 'SHLVL': '1',
07:59:53 INFO - 'TERM': 'linux',
07:59:53 INFO - 'TMOUT': '86400',
07:59:53 INFO - 'USER': 'cltbld',
07:59:53 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
07:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:59:53 INFO - '_': '/tools/buildbot/bin/python'}
07:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple
07:59:54 INFO - Collecting mozsystemmonitor==0.3
07:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
07:59:55 INFO - Installing collected packages: mozsystemmonitor
07:59:55 INFO - Successfully installed mozsystemmonitor-0.3
07:59:55 INFO - Return code: 0
07:59:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
07:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:59:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
07:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
07:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
07:59:55 INFO - Using env: {'DISPLAY': ':0',
07:59:55 INFO - 'HOME': '/home/cltbld',
07:59:55 INFO - 'LANG': 'en_US.UTF-8',
07:59:55 INFO - 'LANGUAGE': 'en_US:en',
07:59:55 INFO - 'LOGNAME': 'cltbld',
07:59:55 INFO - 'MAIL': '/var/mail/cltbld',
07:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:59:55 INFO - 'MOZ_NO_REMOTE': '1',
07:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:59:55 INFO - 'NO_EM_RESTART': '1',
07:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:59:55 INFO - 'PWD': '/builds/slave/test',
07:59:55 INFO - 'SHELL': '/bin/bash',
07:59:55 INFO - 'SHLVL': '1',
07:59:55 INFO - 'TERM': 'linux',
07:59:55 INFO - 'TMOUT': '86400',
07:59:55 INFO - 'USER': 'cltbld',
07:59:55 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
07:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:59:55 INFO - '_': '/tools/buildbot/bin/python'}
07:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple
07:59:55 INFO - Collecting jsonschema==2.5.1
07:59:56 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
07:59:56 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
07:59:57 INFO - Installing collected packages: functools32, jsonschema
07:59:58 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
07:59:58 INFO - Return code: 0
07:59:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
07:59: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')]}
07:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:59: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')]}
07:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:59: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', '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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
07:59: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', 'functools32==3.2.3-2'] in /builds/slave/test/build
07:59: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 functools32==3.2.3-2
07:59:58 INFO - Using env: {'DISPLAY': ':0',
07:59:58 INFO - 'HOME': '/home/cltbld',
07:59:58 INFO - 'LANG': 'en_US.UTF-8',
07:59:58 INFO - 'LANGUAGE': 'en_US:en',
07:59:58 INFO - 'LOGNAME': 'cltbld',
07:59:58 INFO - 'MAIL': '/var/mail/cltbld',
07:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:59:58 INFO - 'MOZ_NO_REMOTE': '1',
07:59:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:59:58 INFO - 'NO_EM_RESTART': '1',
07:59:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:59:58 INFO - 'PWD': '/builds/slave/test',
07:59:58 INFO - 'SHELL': '/bin/bash',
07:59:58 INFO - 'SHLVL': '1',
07:59:58 INFO - 'TERM': 'linux',
07:59:58 INFO - 'TMOUT': '86400',
07:59:58 INFO - 'USER': 'cltbld',
07:59:58 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
07:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:59:58 INFO - '_': '/tools/buildbot/bin/python'}
07:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple
07:59:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
07:59:58 INFO - Return code: 0
07:59:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
07:59: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')]}
07:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:59: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')]}
07:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:59: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', '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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
07:59: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', 'blobuploader==1.2.4'] in /builds/slave/test/build
07:59: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 blobuploader==1.2.4
07:59:58 INFO - Using env: {'DISPLAY': ':0',
07:59:58 INFO - 'HOME': '/home/cltbld',
07:59:58 INFO - 'LANG': 'en_US.UTF-8',
07:59:58 INFO - 'LANGUAGE': 'en_US:en',
07:59:58 INFO - 'LOGNAME': 'cltbld',
07:59:58 INFO - 'MAIL': '/var/mail/cltbld',
07:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:59:58 INFO - 'MOZ_NO_REMOTE': '1',
07:59:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:59:58 INFO - 'NO_EM_RESTART': '1',
07:59:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:59:58 INFO - 'PWD': '/builds/slave/test',
07:59:58 INFO - 'SHELL': '/bin/bash',
07:59:58 INFO - 'SHLVL': '1',
07:59:58 INFO - 'TERM': 'linux',
07:59:58 INFO - 'TMOUT': '86400',
07:59:58 INFO - 'USER': 'cltbld',
07:59:58 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
07:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:59:58 INFO - '_': '/tools/buildbot/bin/python'}
07:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple
07:59:58 INFO - Collecting blobuploader==1.2.4
07:59:59 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
08:00:01 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
08:00:02 INFO - Installing collected packages: requests, docopt, blobuploader
08:00:02 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
08:00:02 INFO - Return code: 0
08:00:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:00:02 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')]}
08:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:00:02 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')]}
08:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:00:02 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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
08:00:02 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
08:00:02 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
08:00:02 INFO - Using env: {'DISPLAY': ':0',
08:00:02 INFO - 'HOME': '/home/cltbld',
08:00:02 INFO - 'LANG': 'en_US.UTF-8',
08:00:02 INFO - 'LANGUAGE': 'en_US:en',
08:00:02 INFO - 'LOGNAME': 'cltbld',
08:00:02 INFO - 'MAIL': '/var/mail/cltbld',
08:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:00:02 INFO - 'MOZ_NO_REMOTE': '1',
08:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:00:02 INFO - 'NO_EM_RESTART': '1',
08:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:00:02 INFO - 'PWD': '/builds/slave/test',
08:00:02 INFO - 'SHELL': '/bin/bash',
08:00:02 INFO - 'SHLVL': '1',
08:00:02 INFO - 'TERM': 'linux',
08:00:02 INFO - 'TMOUT': '86400',
08:00:02 INFO - 'USER': 'cltbld',
08:00:02 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
08:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:00:02 INFO - '_': '/tools/buildbot/bin/python'}
08:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple
08:00:02 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:00:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:00:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:00:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:00:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:00:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:00:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:00:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:00:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:00:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:00:04 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:00:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:00:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:00:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
08:00:05 INFO - Running setup.py install for manifestparser: started
08:00:06 INFO - Running setup.py install for manifestparser: finished with status 'done'
08:00:06 INFO - Running setup.py install for mozcrash: started
08:00:06 INFO - Running setup.py install for mozcrash: finished with status 'done'
08:00:06 INFO - Running setup.py install for mozdebug: started
08:00:06 INFO - Running setup.py install for mozdebug: finished with status 'done'
08:00:06 INFO - Running setup.py install for mozdevice: started
08:00:06 INFO - Running setup.py install for mozdevice: finished with status 'done'
08:00:06 INFO - Running setup.py install for mozfile: started
08:00:07 INFO - Running setup.py install for mozfile: finished with status 'done'
08:00:07 INFO - Running setup.py install for mozhttpd: started
08:00:07 INFO - Running setup.py install for mozhttpd: finished with status 'done'
08:00:07 INFO - Running setup.py install for mozinfo: started
08:00:07 INFO - Running setup.py install for mozinfo: finished with status 'done'
08:00:07 INFO - Running setup.py install for mozInstall: started
08:00:07 INFO - Running setup.py install for mozInstall: finished with status 'done'
08:00:07 INFO - Running setup.py install for mozleak: started
08:00:07 INFO - Running setup.py install for mozleak: finished with status 'done'
08:00:07 INFO - Running setup.py install for mozlog: started
08:00:08 INFO - Running setup.py install for mozlog: finished with status 'done'
08:00:08 INFO - Running setup.py install for moznetwork: started
08:00:08 INFO - Running setup.py install for moznetwork: finished with status 'done'
08:00:08 INFO - Running setup.py install for mozprocess: started
08:00:08 INFO - Running setup.py install for mozprocess: finished with status 'done'
08:00:08 INFO - Running setup.py install for mozprofile: started
08:00:08 INFO - Running setup.py install for mozprofile: finished with status 'done'
08:00:08 INFO - Running setup.py install for mozrunner: started
08:00:09 INFO - Running setup.py install for mozrunner: finished with status 'done'
08:00:09 INFO - Running setup.py install for mozscreenshot: started
08:00:09 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
08:00:09 INFO - Running setup.py install for moztest: started
08:00:09 INFO - Running setup.py install for moztest: finished with status 'done'
08:00:09 INFO - Running setup.py install for mozversion: started
08:00:09 INFO - Running setup.py install for mozversion: finished with status 'done'
08:00:09 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
08:00:10 INFO - Return code: 0
08:00:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:00:10 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')]}
08:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:00:10 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')]}
08:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:00:10 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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
08:00:10 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
08:00:10 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
08:00:10 INFO - Using env: {'DISPLAY': ':0',
08:00:10 INFO - 'HOME': '/home/cltbld',
08:00:10 INFO - 'LANG': 'en_US.UTF-8',
08:00:10 INFO - 'LANGUAGE': 'en_US:en',
08:00:10 INFO - 'LOGNAME': 'cltbld',
08:00:10 INFO - 'MAIL': '/var/mail/cltbld',
08:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:00:10 INFO - 'MOZ_NO_REMOTE': '1',
08:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:00:10 INFO - 'NO_EM_RESTART': '1',
08:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:00:10 INFO - 'PWD': '/builds/slave/test',
08:00:10 INFO - 'SHELL': '/bin/bash',
08:00:10 INFO - 'SHLVL': '1',
08:00:10 INFO - 'TERM': 'linux',
08:00:10 INFO - 'TMOUT': '86400',
08:00:10 INFO - 'USER': 'cltbld',
08:00:10 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
08:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:00:10 INFO - '_': '/tools/buildbot/bin/python'}
08:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple
08:00:10 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:00:10 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))
08:00:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:00:10 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))
08:00:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:00:11 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))
08:00:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:00:11 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))
08:00:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:00:11 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))
08:00:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:00:11 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))
08:00:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:00:11 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))
08:00:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:00:12 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))
08:00:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:00:12 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))
08:00:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:00:12 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))
08:00:12 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:00:12 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))
08:00:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:00:12 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))
08:00:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:00:13 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))
08:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
08:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:00:13 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))
08:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:00:13 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))
08:00:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:00:13 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))
08:00:13 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
08:00:15 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
08:00:15 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))
08:00:15 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))
08:00:15 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))
08:00:15 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))
08:00:15 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))
08:00:15 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))
08:00:15 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
08:00:16 INFO - Installing collected packages: six, blessings
08:00:16 INFO - Successfully installed blessings-1.6 six-1.10.0
08:00:16 INFO - Return code: 0
08:00:16 INFO - Done creating virtualenv /builds/slave/test/build/venv.
08:00:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:00:16 INFO - Reading from file tmpfile_stdout
08:00:16 INFO - Reading from file tmpfile_stderr
08:00:16 INFO - Current package versions:
08:00:16 INFO - blessings == 1.6
08:00:16 INFO - blobuploader == 1.2.4
08:00:16 INFO - docopt == 0.6.1
08:00:16 INFO - functools32 == 3.2.3.post2
08:00:16 INFO - jsonschema == 2.5.1
08:00:16 INFO - manifestparser == 1.2
08:00:16 INFO - mozInstall == 1.14
08:00:16 INFO - mozcrash == 1.0
08:00:16 INFO - mozdebug == 0.1
08:00:16 INFO - mozdevice == 0.51
08:00:16 INFO - mozfile == 1.2
08:00:16 INFO - mozhttpd == 0.7
08:00:16 INFO - mozinfo == 0.10
08:00:16 INFO - mozleak == 0.1
08:00:16 INFO - mozlog == 3.5
08:00:16 INFO - moznetwork == 0.27
08:00:16 INFO - mozprocess == 0.25
08:00:16 INFO - mozprofile == 0.29
08:00:16 INFO - mozrunner == 6.13
08:00:16 INFO - mozscreenshot == 0.1
08:00:16 INFO - mozsystemmonitor == 0.3
08:00:16 INFO - moztest == 0.8
08:00:16 INFO - mozversion == 1.4
08:00:16 INFO - psutil == 3.1.1
08:00:16 INFO - requests == 1.2.3
08:00:16 INFO - six == 1.10.0
08:00:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:00:16 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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
08:00:16 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
08:00:16 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
08:00:16 INFO - Using env: {'DISPLAY': ':0',
08:00:16 INFO - 'HOME': '/home/cltbld',
08:00:16 INFO - 'LANG': 'en_US.UTF-8',
08:00:16 INFO - 'LANGUAGE': 'en_US:en',
08:00:16 INFO - 'LOGNAME': 'cltbld',
08:00:16 INFO - 'MAIL': '/var/mail/cltbld',
08:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:00:16 INFO - 'MOZ_NO_REMOTE': '1',
08:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:00:16 INFO - 'NO_EM_RESTART': '1',
08:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:00:16 INFO - 'PWD': '/builds/slave/test',
08:00:16 INFO - 'SHELL': '/bin/bash',
08:00:16 INFO - 'SHLVL': '1',
08:00:16 INFO - 'TERM': 'linux',
08:00:16 INFO - 'TMOUT': '86400',
08:00:16 INFO - 'USER': 'cltbld',
08:00:16 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
08:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:00:16 INFO - '_': '/tools/buildbot/bin/python'}
08:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple
08:00:17 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))
08:00:17 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))
08:00:17 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))
08:00:17 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))
08:00:17 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))
08:00:17 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))
08:00:17 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))
08:00:17 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))
08:00:17 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))
08:00:17 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
08:00:18 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:00:19 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
08:00:19 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))
08:00:19 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))
08:00:19 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))
08:00:19 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))
08:00:20 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
08:00:20 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:00:21 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
08:00:21 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:00:22 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
08:00:22 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:00:23 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
08:00:23 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
08:00:23 INFO - Found existing installation: requests 1.2.3
08:00:23 INFO - Uninstalling requests-1.2.3:
08:00:23 INFO - Successfully uninstalled requests-1.2.3
08:00:24 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
08:00:24 INFO - Return code: 0
08:00:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
08:00:24 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')]}
08:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:00:24 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')]}
08:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:00:24 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 0x1b622d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d3aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1edd930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, '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': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880', '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
08:00:24 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
08:00:24 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
08:00:24 INFO - Using env: {'DISPLAY': ':0',
08:00:24 INFO - 'HOME': '/home/cltbld',
08:00:24 INFO - 'LANG': 'en_US.UTF-8',
08:00:24 INFO - 'LANGUAGE': 'en_US:en',
08:00:24 INFO - 'LOGNAME': 'cltbld',
08:00:24 INFO - 'MAIL': '/var/mail/cltbld',
08:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:00:24 INFO - 'MOZ_NO_REMOTE': '1',
08:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:00:24 INFO - 'NO_EM_RESTART': '1',
08:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:00:24 INFO - 'PWD': '/builds/slave/test',
08:00:24 INFO - 'SHELL': '/bin/bash',
08:00:24 INFO - 'SHLVL': '1',
08:00:24 INFO - 'TERM': 'linux',
08:00:24 INFO - 'TMOUT': '86400',
08:00:24 INFO - 'USER': 'cltbld',
08:00:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
08:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:00:24 INFO - '_': '/tools/buildbot/bin/python'}
08:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple
08:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
08:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
08:00:24 INFO - Return code: 0
08:00:24 INFO - Running post-action listener: _resource_record_post_action
08:00:24 INFO - Running post-action listener: _start_resource_monitoring
08:00:24 INFO - Starting resource monitoring.
08:00:24 INFO - [mozharness: 2017-12-04 16:00:24.573778Z] Finished create-virtualenv step (success)
08:00:24 INFO - [mozharness: 2017-12-04 16:00:24.574249Z] Running install step.
08:00:24 INFO - Running pre-action listener: _resource_record_pre_action
08:00:24 INFO - Running main action method: install
08:00:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:00:25 INFO - Reading from file tmpfile_stdout
08:00:25 INFO - Reading from file tmpfile_stderr
08:00:25 INFO - Detecting whether we're running mozinstall >=1.0...
08:00:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
08:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
08:00:25 INFO - Reading from file tmpfile_stdout
08:00:25 INFO - Output received:
08:00:25 INFO - Usage: mozinstall [options] installer
08:00:25 INFO - Options:
08:00:25 INFO - -h, --help show this help message and exit
08:00:25 INFO - -d DEST, --destination=DEST
08:00:25 INFO - Directory to install application into. [default:
08:00:25 INFO - "/builds/slave/test"]
08:00:25 INFO - --app=APP Application being installed. [default: firefox]
08:00:25 INFO - mkdir: /builds/slave/test/build/application
08:00:25 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']
08:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
08:00:40 INFO - Reading from file tmpfile_stdout
08:00:40 INFO - Output received:
08:00:40 INFO - /builds/slave/test/build/application/firefox/firefox
08:00:40 INFO - Running post-action listener: _resource_record_post_action
08:00:40 INFO - [mozharness: 2017-12-04 16:00:40.410930Z] Finished install step (success)
08:00:40 INFO - [mozharness: 2017-12-04 16:00:40.411174Z] Running setup-mitmproxy step.
08:00:40 INFO - Running pre-action listener: _resource_record_pre_action
08:00:40 INFO - Running main action method: setup_mitmproxy
08:00:40 INFO - Skipping: mitmproxy is not required
08:00:40 INFO - Running post-action listener: _resource_record_post_action
08:00:40 INFO - [mozharness: 2017-12-04 16:00:40.412272Z] Finished setup-mitmproxy step (success)
08:00:40 INFO - [mozharness: 2017-12-04 16:00:40.412487Z] Running run-tests step.
08:00:40 INFO - Running pre-action listener: _resource_record_pre_action
08:00:40 INFO - Running pre-action listener: _set_gcov_prefix
08:00:40 INFO - Running main action method: run_tests
08:00:40 WARNING - Try message not found.
08:00:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
08:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
08:00:40 INFO - Python 2.7.3
08:00:40 INFO - Return code: 0
08:00:40 INFO - grabbing minidump binary from tooltool
08:00:40 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')]}
08:00:40 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 0x1edaad0>, '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 0x1edb9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1edbe60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
08:00:40 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
08:00:40 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
08:00:40 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
08:00:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
08:00:40 INFO - Return code: 0
08:00:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
08:00:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
08:00:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
08:00:40 INFO - ENV: RUST_BACKTRACE is now full
08:00:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
08:00:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
08:00:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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
08:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --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
08:00:40 INFO - Using env: {'DISPLAY': ':0',
08:00:40 INFO - 'HOME': '/home/cltbld',
08:00:40 INFO - 'LANG': 'en_US.UTF-8',
08:00:40 INFO - 'LANGUAGE': 'en_US:en',
08:00:40 INFO - 'LOGNAME': 'cltbld',
08:00:40 INFO - 'MAIL': '/var/mail/cltbld',
08:00:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:00:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:00:40 INFO - 'MOZ_NO_REMOTE': '1',
08:00:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:00:40 INFO - 'NO_EM_RESTART': '1',
08:00:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:00:40 INFO - 'PWD': '/builds/slave/test',
08:00:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:00:40 INFO - 'RUST_BACKTRACE': 'full',
08:00:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:00:40 INFO - 'SHELL': '/bin/bash',
08:00:40 INFO - 'SHLVL': '1',
08:00:40 INFO - 'TERM': 'linux',
08:00:40 INFO - 'TMOUT': '86400',
08:00:40 INFO - 'USER': 'cltbld',
08:00:40 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
08:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:00:40 INFO - '_': '/tools/buildbot/bin/python'}
08:00:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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
08:00:40 INFO - mozversion application_buildid: 20171204144037
08:00:40 INFO - mozversion application_changeset: f402c67ee9b03e2057afee139eea99456749b65e
08:00:40 INFO - mozversion application_display_name: Firefox
08:00:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
08:00:40 INFO - mozversion application_name: Firefox
08:00:40 INFO - mozversion application_remotingname: firefox
08:00:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
08:00:40 INFO - mozversion application_vendor: Mozilla
08:00:40 INFO - mozversion application_version: 58.0
08:00:40 INFO - mozversion platform_buildid: 20171204144037
08:00:40 INFO - mozversion platform_changeset: f402c67ee9b03e2057afee139eea99456749b65e
08:00:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
08:00:40 INFO - mozversion platform_version: 58.0
08:00:40 INFO - using testdate: 1512403240
08:00:40 INFO - actual date: 1512403240
08:00:40 INFO - starting webserver on 'localhost:45005'
08:00:40 INFO - SUITE-START | Running 3 tests
08:00:40 INFO - TEST-START | basic_compositor_video
08:00:40 INFO - Initialising browser for basic_compositor_video test...
08:00:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45005/getInfo.html -profile /tmp/tmpYxlo4H/profile
08:00:40 INFO - TEST-INFO | started process 20858 (/builds/slave/test/build/application/firefox/firefox http://localhost:45005/getInfo.html)
08:00:46 INFO - TEST-INFO | 20858: exit 0
08:00:46 INFO - Browser initialized.
08:00:46 INFO - Running cycle 1/1 for basic_compositor_video test...
08:00:46 INFO - Using env: {'DISPLAY': ':0',
08:00:46 INFO - 'HOME': '/home/cltbld',
08:00:46 INFO - 'JSGC_DISABLE_POISONING': '1',
08:00:46 INFO - 'LANG': 'en_US.UTF-8',
08:00:46 INFO - 'LANGUAGE': 'en_US:en',
08:00:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:00:46 INFO - 'LOGNAME': 'cltbld',
08:00:46 INFO - 'MAIL': '/var/mail/cltbld',
08:00:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:00:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:00:46 INFO - 'MOZ_CRASHREPORTER': '1',
08:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:00:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:00:46 INFO - 'MOZ_NO_REMOTE': '1',
08:00:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:00:46 INFO - 'NO_EM_RESTART': '1',
08:00:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:00:46 INFO - 'PWD': '/builds/slave/test',
08:00:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:00:46 INFO - 'RUST_BACKTRACE': 'full',
08:00:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:00:46 INFO - 'SHELL': '/bin/bash',
08:00:46 INFO - 'SHLVL': '1',
08:00:46 INFO - 'TERM': 'linux',
08:00:46 INFO - 'TMOUT': '86400',
08:00:46 INFO - 'USER': 'cltbld',
08:00:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
08:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:00:46 INFO - '_': '/tools/buildbot/bin/python'}
08:00:46 INFO - TEST-INFO | started process 21133 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYxlo4H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
08:00:47 INFO - PID 21133 |
08:00:47 INFO - PID 21133 | (/builds/slave/test/build/application/firefox/firefox:21183): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:00:47 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6845030881527234 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6660574521232303 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.713366647629926 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.697222222222222 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9073958333333323 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.000799999999999 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.440630733944954 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.367626262626267 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.895668841761824 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.586997206703912 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 10.49174825174825 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.713470873786418 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.114537037037021 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.473115183246101 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.24569387755102 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.979940119760487 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451087786259562 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.812486486486474 ms/frame
08:01:33 INFO - PID 21133 |
08:01:33 INFO - PID 21133 | Cycle 1(1): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:02:19 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800643896976484 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673499999999997 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7382242178447278 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7081169940222038 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9332699868938388 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.016395582329319 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.4291085714285705 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.339198664440735 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682083333333339 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.525317679558003 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 10.24013651877132 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 10.259256410256413 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.155501858736066 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.698850267379667 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.296311475409848 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83914201183429 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.539749999999996 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.049972375690597 ms/frame
08:02:19 INFO - PID 21133 |
08:02:19 INFO - PID 21133 | Cycle 1(2): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:03:05 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6913275084554678 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6701502504173624 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7403393271461713 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7469519650655023 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033494623655914 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.7810680529300558 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.4367411225658624 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.351415410385263 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.597779850746268 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.526837016574586 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 10.035200668896314 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 10.001500000000014 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.03038602941175 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.473743455497385 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.348888888888885 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.56453757225434 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.45276717557252 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.17578212290505 ms/frame
08:03:05 INFO - PID 21133 |
08:03:05 INFO - PID 21133 | Cycle 1(3): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:03:51 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6813305322128853 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6701878130217027 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.786929720071471 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7160591766723836 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0930627557980905 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.931237721021611 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.4140386803185425 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3396327212020074 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.59805970149254 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.602605042016799 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 10.136402027027026 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.092863636363637 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.19460820895522 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.643696808510635 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.502666666666665 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.911041666666668 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58492277992279 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.75456989247311 ms/frame
08:03:51 INFO - PID 21133 |
08:03:51 INFO - PID 21133 | Cycle 1(4): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:04:38 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682916432978127 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669878964941569 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7775622037914696 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7488505244755248 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.155927977839335 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.083602040816327 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.436958762886598 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3341833333333306 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683721590909093 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.514132231404951 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 9.869802631578942 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.757853658536588 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.950091240875915 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.419687500000009 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346810699588483 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.70190058479533 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.363920454545472 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.816108108108086 ms/frame
08:04:38 INFO - PID 21133 |
08:04:38 INFO - PID 21133 | Cycle 1(5): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:05:24 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6855337078651684 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6704048414023371 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7186053837342499 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.707308020477815 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.178481894150417 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.937253937007874 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.457044930875574 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.407172061328794 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.61829588014981 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.602184873949579 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 10.068070469798657 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.618437499999992 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.071918819188184 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.700561497326229 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.608004201680648 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.629273255813956 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49724137931034 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.875543478260862 ms/frame
08:05:24 INFO - PID 21133 |
08:05:24 INFO - PID 21133 | Cycle 1(6): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:06:10 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.677168809390721 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6715288220551376 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7294668587896254 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7456893542757428 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.032735215053766 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.16875 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.5634085510688807 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.444277108433735 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672674858223065 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.291190476190476 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 9.679419354838707 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.348107476635509 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.872300724637684 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.417682291666665 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.502604166666666 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.770470588235312 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451431297709929 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.112111111111112 ms/frame
08:06:10 INFO - PID 21133 |
08:06:10 INFO - PID 21133 | Cycle 1(7): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:06:56 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680781512605042 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6672833333333335 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7146057142857145 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7170085836909867 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.144060773480662 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.100256147540982 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.4770973348783323 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4374398625429556 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.639633458646617 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.362989276139412 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 10.23935153583617 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.665265700483106 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.951405109489043 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.64119680851063 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.555209205020919 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.768529411764714 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.541019230769233 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.753575268817208 ms/frame
08:06:56 INFO - PID 21133 |
08:06:56 INFO - PID 21133 | Cycle 1(8): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:07:42 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6876040494938136 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689199332777318 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7515995329830711 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.713783204798628 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.121146978021978 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.115504115226338 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.417511389521641 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.344999999999996 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5883985102420874 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5889245810055845 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 10.41987847222222 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.617524038461537 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.114351851851852 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.526815789473691 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.399070247933873 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907857142857168 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.280413533834574 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.869375000000021 ms/frame
08:07:42 INFO - PID 21133 |
08:07:42 INFO - PID 21133 | Cycle 1(9): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:08:28 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682135650224215 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683194328607174 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7069141069397042 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7096581196581198 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.027812080536914 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.14159420289855 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.5341224970553586 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4918760907504383 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.769576923076924 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5567916666666655 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 9.904537953795382 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.571985645933024 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.95096715328468 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.582804232804202 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.296926229508214 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.633837209302307 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.62897286821706 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.990219780219746 ms/frame
08:08:28 INFO - PID 21133 |
08:08:28 INFO - PID 21133 | Cycle 1(10): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:09:15 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6782578299776287 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669628547579299 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7570286885245903 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7174163090128747 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.044056603773585 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.142670807453416 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.425570776255707 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.361571428571432 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672362948960303 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.555888888888886 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 10.103468013468012 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 10.258820512820519 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.91212727272727 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.3663212435233 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.298360655737687 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.697777777777777 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.32456603773583 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.870434782608715 ms/frame
08:09:15 INFO - PID 21133 |
08:09:15 INFO - PID 21133 | Cycle 1(11): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:10:01 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_startup = 1.688519977490152 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685279399499584 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.776657785671995 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7704734513274347 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0392664872139985 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1413975155279505 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_startup = 3.444827784156144 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3453511705685566 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.576496282527879 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.57139275766017 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_startup = 9.55587579617835 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.480p.60fps.webm_scale_2_inclip = 9.856305418719202 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991556776556775 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.367901554404131 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.449585062240658 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.630087209302326 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.673929961089483 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11274999999999 ms/frame
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | Cycle 1(12): loaded http://localhost:45005/tests/video/video_playback.html (next: http://localhost:45005/tests/video/video_playback.html)
08:10:01 INFO - PID 21133 | __start_tp_report
08:10:01 INFO - PID 21133 | _x_x_mozilla_page_load
08:10:01 INFO - PID 21133 | _x_x_mozilla_page_load_details
08:10:01 INFO - PID 21133 | |i|pagename|runs|
08:10:01 INFO - PID 21133 | |0;240p.120fps.mp4_scale_1_startup;1.6845030881527234;1.6800643896976484;1.6913275084554678;1.6813305322128853;1.682916432978127;1.6855337078651684;1.677168809390721;1.680781512605042;1.6876040494938136;1.682135650224215;1.6782578299776287;1.688519977490152
08:10:01 INFO - PID 21133 | |1;240p.120fps.mp4_scale_1_inclip;1.6660574521232303;1.6673499999999997;1.6701502504173624;1.6701878130217027;1.669878964941569;1.6704048414023371;1.6715288220551376;1.6672833333333335;1.6689199332777318;1.6683194328607174;1.669628547579299;1.6685279399499584
08:10:01 INFO - PID 21133 | |2;240p.120fps.mp4_scale_1.1_startup;1.713366647629926;1.7382242178447278;1.7403393271461713;1.786929720071471;1.7775622037914696;1.7186053837342499;1.7294668587896254;1.7146057142857145;1.7515995329830711;1.7069141069397042;1.7570286885245903;1.776657785671995
08:10:01 INFO - PID 21133 | |3;240p.120fps.mp4_scale_1.1_inclip;1.697222222222222;1.7081169940222038;1.7469519650655023;1.7160591766723836;1.7488505244755248;1.707308020477815;1.7456893542757428;1.7170085836909867;1.713783204798628;1.7096581196581198;1.7174163090128747;1.7704734513274347
08:10:01 INFO - PID 21133 | |4;240p.120fps.mp4_scale_2_startup;3.9073958333333323;3.9332699868938388;4.033494623655914;4.0930627557980905;4.155927977839335;4.178481894150417;4.032735215053766;4.144060773480662;4.121146978021978;4.027812080536914;4.044056603773585;4.0392664872139985
08:10:01 INFO - PID 21133 | |5;240p.120fps.mp4_scale_2_inclip;4.000799999999999;4.016395582329319;3.7810680529300558;3.931237721021611;4.083602040816327;3.937253937007874;4.16875;4.100256147540982;4.115504115226338;4.14159420289855;4.142670807453416;4.1413975155279505
08:10:01 INFO - PID 21133 | |6;480p.60fps.webm_scale_1_startup;3.440630733944954;3.4291085714285705;3.4367411225658624;3.4140386803185425;3.436958762886598;3.457044930875574;3.5634085510688807;3.4770973348783323;3.417511389521641;3.5341224970553586;3.425570776255707;3.444827784156144
08:10:01 INFO - PID 21133 | |7;480p.60fps.webm_scale_1_inclip;3.367626262626267;3.339198664440735;3.351415410385263;3.3396327212020074;3.3341833333333306;3.407172061328794;3.444277108433735;3.4374398625429556;3.344999999999996;3.4918760907504383;3.361571428571432;3.3453511705685566
08:10:01 INFO - PID 21133 | |8;480p.60fps.webm_scale_1.1_startup;4.895668841761824;5.682083333333339;5.597779850746268;5.59805970149254;5.683721590909093;5.61829588014981;5.672674858223065;5.639633458646617;5.5883985102420874;5.769576923076924;5.672362948960303;5.576496282527879
08:10:01 INFO - PID 21133 | |9;480p.60fps.webm_scale_1.1_inclip;5.586997206703912;5.525317679558003;5.526837016574586;5.602605042016799;5.514132231404951;5.602184873949579;5.291190476190476;5.362989276139412;5.5889245810055845;5.5567916666666655;5.555888888888886;5.57139275766017
08:10:01 INFO - PID 21133 | |10;480p.60fps.webm_scale_2_startup;10.49174825174825;10.24013651877132;10.035200668896314;10.136402027027026;9.869802631578942;10.068070469798657;9.679419354838707;10.23935153583617;10.41987847222222;9.904537953795382;10.103468013468012;9.55587579617835
08:10:01 INFO - PID 21133 | |11;480p.60fps.webm_scale_2_inclip;9.713470873786418;10.259256410256413;10.001500000000014;9.092863636363637;9.757853658536588;9.618437499999992;9.348107476635509;9.665265700483106;9.617524038461537;9.571985645933024;10.258820512820519;9.856305418719202
08:10:01 INFO - PID 21133 | |12;1080p.60fps.mp4_scale_1_startup;11.114537037037021;11.155501858736066;11.03038602941175;11.19460820895522;10.950091240875915;11.071918819188184;10.872300724637684;10.951405109489043;11.114351851851852;10.95096715328468;10.91212727272727;10.991556776556775
08:10:01 INFO - PID 21133 | |13;1080p.60fps.mp4_scale_1_inclip;10.473115183246101;10.698850267379667;10.473743455497385;10.643696808510635;10.419687500000009;10.700561497326229;10.417682291666665;10.64119680851063;10.526815789473691;10.582804232804202;10.3663212435233;10.367901554404131
08:10:01 INFO - PID 21133 | |14;1080p.60fps.mp4_scale_1.1_startup;12.24569387755102;12.296311475409848;12.348888888888885;12.502666666666665;12.346810699588483;12.608004201680648;12.502604166666666;12.555209205020919;12.399070247933873;12.296926229508214;12.298360655737687;12.449585062240658
08:10:01 INFO - PID 21133 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.979940119760487;11.83914201183429;11.56453757225434;11.911041666666668;11.70190058479533;11.629273255813956;11.770470588235312;11.768529411764714;11.907857142857168;11.633837209302307;11.697777777777777;11.630087209302326
08:10:01 INFO - PID 21133 | |16;1080p.60fps.mp4_scale_2_startup;11.451087786259562;11.539749999999996;11.45276717557252;11.58492277992279;11.363920454545472;11.49724137931034;11.451431297709929;11.541019230769233;11.280413533834574;11.62897286821706;11.32456603773583;11.673929961089483
08:10:01 INFO - PID 21133 | |17;1080p.60fps.mp4_scale_2_inclip;10.812486486486474;11.049972375690597;11.17578212290505;10.75456989247311;10.816108108108086;10.875543478260862;11.112111111111112;10.753575268817208;10.869375000000021;10.990219780219746;10.870434782608715;11.11274999999999
08:10:01 INFO - PID 21133 | __end_tp_report
08:10:01 INFO - PID 21133 | __start_cc_report
08:10:01 INFO - PID 21133 | _x_x_mozilla_cycle_collect,553
08:10:01 INFO - PID 21133 | __end_cc_report
08:10:01 INFO - PID 21133 | __startTimestamp1512403801505__endTimestamp
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | ------- Summary: start -------
08:10:01 INFO - PID 21133 | Number of tests: 18
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
08:10:01 INFO - PID 21133 | 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
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
08:10:01 INFO - PID 21133 | 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
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.74 Median:1.75 stddev:0.03 (1.6%) stddev-sans-first:0.03
08:10:01 INFO - PID 21133 | Values: 1.7 1.7 1.7 1.8 1.8 1.7 1.7 1.7 1.8 1.7 1.8 1.8
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.72 Median:1.72 stddev:0.02 (1.3%) stddev-sans-first:0.02
08:10:01 INFO - PID 21133 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.8
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.06 Median:4.07 stddev:0.08 (2.1%) stddev-sans-first:0.07
08:10:01 INFO - PID 21133 | Values: 3.9 3.9 4.0 4.1 4.2 4.2 4.0 4.1 4.1 4.0 4.0 4.0
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.05 Median:4.11 stddev:0.12 (2.8%) stddev-sans-first:0.12
08:10:01 INFO - PID 21133 | Values: 4.0 4.0 3.8 3.9 4.1 3.9 4.2 4.1 4.1 4.1 4.1 4.1
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.46 Median:3.44 stddev:0.05 (1.4%) stddev-sans-first:0.05
08:10:01 INFO - PID 21133 | Values: 3.4 3.4 3.4 3.4 3.4 3.5 3.6 3.5 3.4 3.5 3.4 3.4
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.38 Median:3.36 stddev:0.05 (1.5%) stddev-sans-first:0.05
08:10:01 INFO - PID 21133 | Values: 3.4 3.3 3.4 3.3 3.3 3.4 3.4 3.4 3.3 3.5 3.4 3.3
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.58 Median:5.66 stddev:0.22 (3.9%) stddev-sans-first:0.06
08:10:01 INFO - PID 21133 | Values: 4.9 5.7 5.6 5.6 5.7 5.6 5.7 5.6 5.6 5.8 5.7 5.6
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.52 Median:5.56 stddev:0.10 (1.8%) stddev-sans-first:0.10
08:10:01 INFO - PID 21133 | Values: 5.6 5.5 5.5 5.6 5.5 5.6 5.3 5.4 5.6 5.6 5.6 5.6
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.06 Median:10.46 stddev:0.28 (2.7%) stddev-sans-first:0.25
08:10:01 INFO - PID 21133 | Values: 10.5 10.2 10.0 10.1 9.9 10.1 9.7 10.2 10.4 9.9 10.1 9.6
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.73 Median:9.62 stddev:0.34 (3.5%) stddev-sans-first:0.35
08:10:01 INFO - PID 21133 | Values: 9.7 10.3 10.0 9.1 9.8 9.6 9.3 9.7 9.6 9.6 10.3 9.9
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.03 Median:11.05 stddev:0.10 (0.9%) stddev-sans-first:0.10
08:10:01 INFO - PID 21133 | Values: 11.1 11.2 11.0 11.2 11.0 11.1 10.9 11.0 11.1 11.0 10.9 11.0
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.53 Median:10.55 stddev:0.12 (1.2%) stddev-sans-first:0.13
08:10:01 INFO - PID 21133 | Values: 10.5 10.7 10.5 10.6 10.4 10.7 10.4 10.6 10.5 10.6 10.4 10.4
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.40 Median:12.42 stddev:0.12 (0.9%) stddev-sans-first:0.11
08:10:01 INFO - PID 21133 | Values: 12.2 12.3 12.3 12.5 12.3 12.6 12.5 12.6 12.4 12.3 12.3 12.4
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.75 Median:11.77 stddev:0.13 (1.1%) stddev-sans-first:0.12
08:10:01 INFO - PID 21133 | Values: 12.0 11.8 11.6 11.9 11.7 11.6 11.8 11.8 11.9 11.6 11.7 11.6
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.48 Median:11.52 stddev:0.12 (1.0%) stddev-sans-first:0.13
08:10:01 INFO - PID 21133 | Values: 11.5 11.5 11.5 11.6 11.4 11.5 11.5 11.5 11.3 11.6 11.3 11.7
08:10:01 INFO - PID 21133 |
08:10:01 INFO - PID 21133 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.93 Median:10.93 stddev:0.15 (1.4%) stddev-sans-first:0.15
08:10:01 INFO - PID 21133 | Values: 10.8 11.0 11.2 10.8 10.8 10.9 11.1 10.8 10.9 11.0 10.9 11.1
08:10:01 INFO - PID 21133 | -------- Summary: end --------
08:10:01 INFO - PID 21133 |
08:10:02 INFO - TEST-INFO | 21133: exit 0
08:10:02 INFO - TEST-OK | basic_compositor_video | took 561212ms
08:10:02 INFO - TEST-START | glvideo
08:10:02 INFO - Initialising browser for glvideo test...
08:10:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45005/getInfo.html -profile /tmp/tmpMh1h24/profile
08:10:02 INFO - TEST-INFO | started process 22349 (/builds/slave/test/build/application/firefox/firefox http://localhost:45005/getInfo.html)
08:10:07 INFO - TEST-INFO | 22349: exit 0
08:10:08 INFO - Browser initialized.
08:10:08 INFO - Running cycle 1/1 for glvideo test...
08:10:08 INFO - Using env: {'DISPLAY': ':0',
08:10:08 INFO - 'HOME': '/home/cltbld',
08:10:08 INFO - 'JSGC_DISABLE_POISONING': '1',
08:10:08 INFO - 'LANG': 'en_US.UTF-8',
08:10:08 INFO - 'LANGUAGE': 'en_US:en',
08:10:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:10:08 INFO - 'LOGNAME': 'cltbld',
08:10:08 INFO - 'MAIL': '/var/mail/cltbld',
08:10:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:10:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:10:08 INFO - 'MOZ_CRASHREPORTER': '1',
08:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:10:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:10:08 INFO - 'MOZ_NO_REMOTE': '1',
08:10:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:10:08 INFO - 'NO_EM_RESTART': '1',
08:10:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:10:08 INFO - 'PWD': '/builds/slave/test',
08:10:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:10:08 INFO - 'RUST_BACKTRACE': 'full',
08:10:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:10:08 INFO - 'SHELL': '/bin/bash',
08:10:08 INFO - 'SHLVL': '1',
08:10:08 INFO - 'TERM': 'linux',
08:10:08 INFO - 'TMOUT': '86400',
08:10:08 INFO - 'USER': 'cltbld',
08:10:08 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
08:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:10:08 INFO - '_': '/tools/buildbot/bin/python'}
08:10:08 INFO - TEST-INFO | started process 22621 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMh1h24/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:10:08 INFO - PID 22621 |
08:10:08 INFO - PID 22621 | (/builds/slave/test/build/application/firefox/firefox:22671): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:10:08 INFO - PID 22621 |
08:10:10 INFO - PID 22621 | [talos glvideo result] Mean tick time across 100 ticks: 7.654649999999999 ms
08:10:10 INFO - PID 22621 | Cycle 1(1): loaded http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html)
08:10:11 INFO - PID 22621 | [talos glvideo result] Mean tick time across 100 ticks: 6.44565 ms
08:10:11 INFO - PID 22621 | Cycle 1(2): loaded http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html)
08:10:12 INFO - PID 22621 | [talos glvideo result] Mean tick time across 100 ticks: 6.485550000000001 ms
08:10:12 INFO - PID 22621 | Cycle 1(3): loaded http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html)
08:10:14 INFO - PID 22621 | [talos glvideo result] Mean tick time across 100 ticks: 6.696700000000001 ms
08:10:14 INFO - PID 22621 | Cycle 1(4): loaded http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html)
08:10:15 INFO - PID 22621 | [talos glvideo result] Mean tick time across 100 ticks: 6.5904 ms
08:10:15 INFO - PID 22621 | Cycle 1(5): loaded http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45005/tests/webgl/benchmarks/video/video_upload.html)
08:10:15 INFO - PID 22621 | __start_tp_report
08:10:15 INFO - PID 22621 | _x_x_mozilla_page_load
08:10:15 INFO - PID 22621 | _x_x_mozilla_page_load_details
08:10:15 INFO - PID 22621 | |i|pagename|runs|
08:10:15 INFO - PID 22621 | |0;Mean tick time across 100 ticks: ;7.654649999999999;6.44565;6.485550000000001;6.696700000000001;6.5904
08:10:15 INFO - PID 22621 | __end_tp_report
08:10:15 INFO - PID 22621 | __start_cc_report
08:10:15 INFO - PID 22621 | _x_x_mozilla_cycle_collect,250
08:10:15 INFO - PID 22621 | __end_cc_report
08:10:15 INFO - PID 22621 | __startTimestamp1512403815851__endTimestamp
08:10:15 INFO - PID 22621 |
08:10:15 INFO - PID 22621 | ------- Summary: start -------
08:10:15 INFO - PID 22621 | Number of tests: 1
08:10:15 INFO - PID 22621 |
08:10:15 INFO - PID 22621 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.77 Median:6.59 stddev:0.50 (7.6%) stddev-sans-first:0.11
08:10:15 INFO - PID 22621 | Values: 7.7 6.4 6.5 6.7 6.6
08:10:15 INFO - PID 22621 | -------- Summary: end --------
08:10:15 INFO - PID 22621 |
08:10:16 INFO - PID 22621 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:10:16 INFO - TEST-INFO | 22621: exit 0
08:10:16 INFO - TEST-OK | glvideo | took 14445ms
08:10:16 INFO - TEST-START | displaylist_mutate
08:10:16 INFO - Initialising browser for displaylist_mutate test...
08:10:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45005/getInfo.html -profile /tmp/tmp1Dytnw/profile
08:10:16 INFO - TEST-INFO | started process 22846 (/builds/slave/test/build/application/firefox/firefox http://localhost:45005/getInfo.html)
08:10:22 INFO - TEST-INFO | 22846: exit 0
08:10:22 INFO - Browser initialized.
08:10:22 INFO - Running cycle 1/1 for displaylist_mutate test...
08:10:22 INFO - Using env: {'DISPLAY': ':0',
08:10:22 INFO - 'HOME': '/home/cltbld',
08:10:22 INFO - 'JSGC_DISABLE_POISONING': '1',
08:10:22 INFO - 'LANG': 'en_US.UTF-8',
08:10:22 INFO - 'LANGUAGE': 'en_US:en',
08:10:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:10:22 INFO - 'LOGNAME': 'cltbld',
08:10:22 INFO - 'MAIL': '/var/mail/cltbld',
08:10:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:10:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:10:22 INFO - 'MOZ_CRASHREPORTER': '1',
08:10:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:10:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:10:22 INFO - 'MOZ_NO_REMOTE': '1',
08:10:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:10:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:10:22 INFO - 'NO_EM_RESTART': '1',
08:10:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:10:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:10:22 INFO - 'PWD': '/builds/slave/test',
08:10:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:10:22 INFO - 'RUST_BACKTRACE': 'full',
08:10:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:10:22 INFO - 'SHELL': '/bin/bash',
08:10:22 INFO - 'SHLVL': '1',
08:10:22 INFO - 'TERM': 'linux',
08:10:22 INFO - 'TMOUT': '86400',
08:10:22 INFO - 'USER': 'cltbld',
08:10:22 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880',
08:10:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:10:22 INFO - '_': '/tools/buildbot/bin/python'}
08:10:22 INFO - TEST-INFO | started process 23120 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1Dytnw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:10:22 INFO - PID 23120 |
08:10:22 INFO - PID 23120 | (/builds/slave/test/build/application/firefox/firefox:23171): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:10:22 INFO - PID 23120 |
08:10:31 INFO - PID 23120 | Cycle 1(1): loaded http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html)
08:10:40 INFO - PID 23120 | Cycle 1(2): loaded http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html)
08:10:48 INFO - PID 23120 | Cycle 1(3): loaded http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html)
08:10:57 INFO - PID 23120 | Cycle 1(4): loaded http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html)
08:11:06 INFO - PID 23120 | Cycle 1(5): loaded http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45005/tests/layout/benchmarks/displaylist_mutate.html)
08:11:06 INFO - PID 23120 | __start_tp_report
08:11:06 INFO - PID 23120 | _x_x_mozilla_page_load
08:11:06 INFO - PID 23120 | _x_x_mozilla_page_load_details
08:11:06 INFO - PID 23120 | |i|pagename|runs|
08:11:06 INFO - PID 23120 | |0;/displaylist_mutate.html;7794.015;7988.880000000001;8002.215;8055.385;8032.085
08:11:06 INFO - PID 23120 | __end_tp_report
08:11:06 INFO - PID 23120 | __start_cc_report
08:11:06 INFO - PID 23120 | _x_x_mozilla_cycle_collect,188
08:11:06 INFO - PID 23120 | __end_cc_report
08:11:06 INFO - PID 23120 | __startTimestamp1512403866560__endTimestamp
08:11:06 INFO - PID 23120 |
08:11:06 INFO - PID 23120 | ------- Summary: start -------
08:11:06 INFO - PID 23120 | Number of tests: 1
08:11:06 INFO - PID 23120 |
08:11:06 INFO - PID 23120 | [#0] /displaylist_mutate.html Cycles:5 Average:7974.52 Median:8002.22 stddev:104.17 (1.3%) stddev-sans-first:29.90
08:11:06 INFO - PID 23120 | Values: 7794.0 7988.9 8002.2 8055.4 8032.1
08:11:06 INFO - PID 23120 | -------- Summary: end --------
08:11:06 INFO - PID 23120 |
08:11:06 INFO - PID 23120 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:11:07 INFO - TEST-INFO | 23120: exit 0
08:11:07 INFO - TEST-OK | displaylist_mutate | took 50697ms
08:11:07 INFO - SUITE-END | took 626s
08:11:07 INFO - Completed test suite (00:10:26)
08:11:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.536930852101262, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6845030881527234, 1.6800643896976484, 1.6913275084554678, 1.6813305322128853, 1.682916432978127, 1.6855337078651684, 1.677168809390721, 1.680781512605042, 1.6876040494938136, 1.682135650224215, 1.6782578299776287, 1.688519977490152], "value": 1.682135650224215, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6660574521232303, 1.6673499999999997, 1.6701502504173624, 1.6701878130217027, 1.669878964941569, 1.6704048414023371, 1.6715288220551376, 1.6672833333333335, 1.6689199332777318, 1.6683194328607174, 1.669628547579299, 1.6685279399499584], "value": 1.669628547579299, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.713366647629926, 1.7382242178447278, 1.7403393271461713, 1.786929720071471, 1.7775622037914696, 1.7186053837342499, 1.7294668587896254, 1.7146057142857145, 1.7515995329830711, 1.7069141069397042, 1.7570286885245903, 1.776657785671995], "value": 1.7403393271461713, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.697222222222222, 1.7081169940222038, 1.7469519650655023, 1.7160591766723836, 1.7488505244755248, 1.707308020477815, 1.7456893542757428, 1.7170085836909867, 1.713783204798628, 1.7096581196581198, 1.7174163090128747, 1.7704734513274347], "value": 1.7170085836909867, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.9073958333333323, 3.9332699868938388, 4.033494623655914, 4.0930627557980905, 4.155927977839335, 4.178481894150417, 4.032735215053766, 4.144060773480662, 4.121146978021978, 4.027812080536914, 4.044056603773585, 4.0392664872139985], "value": 4.044056603773585, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.000799999999999, 4.016395582329319, 3.7810680529300558, 3.931237721021611, 4.083602040816327, 3.937253937007874, 4.16875, 4.100256147540982, 4.115504115226338, 4.14159420289855, 4.142670807453416, 4.1413975155279505], "value": 4.100256147540982, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.440630733944954, 3.4291085714285705, 3.4367411225658624, 3.4140386803185425, 3.436958762886598, 3.457044930875574, 3.5634085510688807, 3.4770973348783323, 3.417511389521641, 3.5341224970553586, 3.425570776255707, 3.444827784156144], "value": 3.436958762886598, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.367626262626267, 3.339198664440735, 3.351415410385263, 3.3396327212020074, 3.3341833333333306, 3.407172061328794, 3.444277108433735, 3.4374398625429556, 3.344999999999996, 3.4918760907504383, 3.361571428571432, 3.3453511705685566], "value": 3.351415410385263, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.895668841761824, 5.682083333333339, 5.597779850746268, 5.59805970149254, 5.683721590909093, 5.61829588014981, 5.672674858223065, 5.639633458646617, 5.5883985102420874, 5.769576923076924, 5.672362948960303, 5.576496282527879], "value": 5.639633458646617, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.586997206703912, 5.525317679558003, 5.526837016574586, 5.602605042016799, 5.514132231404951, 5.602184873949579, 5.291190476190476, 5.362989276139412, 5.5889245810055845, 5.5567916666666655, 5.555888888888886, 5.57139275766017], "value": 5.555888888888886, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.49174825174825, 10.24013651877132, 10.035200668896314, 10.136402027027026, 9.869802631578942, 10.068070469798657, 9.679419354838707, 10.23935153583617, 10.41987847222222, 9.904537953795382, 10.103468013468012, 9.55587579617835], "value": 10.068070469798657, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.713470873786418, 10.259256410256413, 10.001500000000014, 9.092863636363637, 9.757853658536588, 9.618437499999992, 9.348107476635509, 9.665265700483106, 9.617524038461537, 9.571985645933024, 10.258820512820519, 9.856305418719202], "value": 9.665265700483106, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.114537037037021, 11.155501858736066, 11.03038602941175, 11.19460820895522, 10.950091240875915, 11.071918819188184, 10.872300724637684, 10.951405109489043, 11.114351851851852, 10.95096715328468, 10.91212727272727, 10.991556776556775], "value": 10.991556776556775, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.473115183246101, 10.698850267379667, 10.473743455497385, 10.643696808510635, 10.419687500000009, 10.700561497326229, 10.417682291666665, 10.64119680851063, 10.526815789473691, 10.582804232804202, 10.3663212435233, 10.367901554404131], "value": 10.526815789473691, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.24569387755102, 12.296311475409848, 12.348888888888885, 12.502666666666665, 12.346810699588483, 12.608004201680648, 12.502604166666666, 12.555209205020919, 12.399070247933873, 12.296926229508214, 12.298360655737687, 12.449585062240658], "value": 12.399070247933873, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.979940119760487, 11.83914201183429, 11.56453757225434, 11.911041666666668, 11.70190058479533, 11.629273255813956, 11.770470588235312, 11.768529411764714, 11.907857142857168, 11.633837209302307, 11.697777777777777, 11.630087209302326], "value": 11.70190058479533, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.451087786259562, 11.539749999999996, 11.45276717557252, 11.58492277992279, 11.363920454545472, 11.49724137931034, 11.451431297709929, 11.541019230769233, 11.280413533834574, 11.62897286821706, 11.32456603773583, 11.673929961089483], "value": 11.49724137931034, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.812486486486474, 11.049972375690597, 11.17578212290505, 10.75456989247311, 10.816108108108086, 10.875543478260862, 11.112111111111112, 10.753575268817208, 10.869375000000021, 10.990219780219746, 10.870434782608715, 11.11274999999999], "value": 10.875543478260862, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.654649999999999, 6.44565, 6.485550000000001, 6.696700000000001, 6.5904], "value": 6.537975, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7794.015, 7988.880000000001, 8002.215, 8055.385, 8032.085], "value": 8017.15, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
08:11:07 INFO - Return code: 0
08:11:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
08:11:07 INFO - # TBPL SUCCESS #
08:11:07 INFO - Running post-action listener: _package_coverage_data
08:11:07 INFO - Running post-action listener: _resource_record_post_action
08:11:07 INFO - [mozharness: 2017-12-04 16:11:07.777190Z] Finished run-tests step (success)
08:11:07 INFO - Running post-run listener: _resource_record_post_run
08:11:07 INFO - Total resource usage - Wall time: 643s; CPU: 36.0%; Read bytes: 8945664; Write bytes: 301707264; Read time: 396; Write time: 272640
08:11:07 INFO - TinderboxPrint: CPU usage
36.4%
08:11:07 INFO - TinderboxPrint: I/O read bytes / time
8,945,664 / 396
08:11:07 INFO - TinderboxPrint: I/O write bytes / time
301,707,264 / 272,640
08:11:07 INFO - TinderboxPrint: CPU idle
3,207.0 (63.6%)
08:11:07 INFO - TinderboxPrint: CPU system
310.8 (6.2%)
08:11:07 INFO - TinderboxPrint: CPU user
1,507.5 (29.9%)
08:11:07 INFO - TinderboxPrint: Swap in / out
0 / 0
08:11:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 5472256; Read time: 0; Write time: 680
08:11:07 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
08:11:07 INFO - run-tests - Wall time: 627s; CPU: 37.0%; Read bytes: 8945664; Write bytes: 296235008; Read time: 396; Write time: 271960
08:11:08 INFO - Running post-run listener: _upload_blobber_files
08:11:08 INFO - Blob upload gear active.
08:11:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
08:11:08 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
08:11:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
08:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
08:11:08 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
08:11:08 INFO - SNIMissingWarning
08:11: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
08:11:08 INFO - InsecurePlatformWarning
08:11: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.)
08:11:08 INFO - SubjectAltNameWarning
08:11:08 INFO - (blobuploader) - INFO - Open directory for files ...
08:11:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
08:11:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:11:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:11: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
08:11:08 INFO - InsecurePlatformWarning
08:11: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.)
08:11:08 INFO - SubjectAltNameWarning
08:11:13 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
08:11:13 INFO - InsecurePlatformWarning
08:11:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
08:11:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:11:13 INFO - (blobuploader) - INFO - Done attempting.
08:11:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
08:11:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:11:13 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:11:13 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
08:11:13 INFO - InsecurePlatformWarning
08:11:13 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.)
08:11:13 INFO - SubjectAltNameWarning
08:11:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:11:14 INFO - InsecurePlatformWarning
08:11:14 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
08:11:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:11:14 INFO - (blobuploader) - INFO - Done attempting.
08:11:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
08:11:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:11:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:11:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:11:14 INFO - InsecurePlatformWarning
08:11:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
08:11:14 INFO - SubjectAltNameWarning
08:11:15 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
08:11:15 INFO - InsecurePlatformWarning
08:11:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
08:11:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:11:15 INFO - (blobuploader) - INFO - Done attempting.
08:11:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
08:11:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:11:15 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:11:15 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
08:11:15 INFO - InsecurePlatformWarning
08:11:15 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.)
08:11:15 INFO - SubjectAltNameWarning
08:11:16 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
08:11:16 INFO - InsecurePlatformWarning
08:11:16 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
08:11:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:11:16 INFO - (blobuploader) - INFO - Done attempting.
08:11:16 INFO - (blobuploader) - INFO - Iteration through files over.
08:11:16 INFO - Return code: 0
08:11:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
08:11:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
08:11:16 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/67502ffff474e9cd48c2ca74edf8af87ad2925134c5f3753b4bd242e94a06886ffb4a6e67ae65cd631f86940bce4e946eec127edf203a13d02ebc8cd75542f74", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a07302d4e294c15baa9baa00db5c12bf85be14d73f8540ed543b8b271a441a7de66870f73f3619f1642e2c31fd590c91bac54d2a1cb1a1ccdcd644c70a4949b2", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4f7efe42c0ab78f106bd46c2ce6bd33de4b46c7c8cc5532eb33f6da56729048e45c931ade6b55b268f3ba64d0a55e029bd53279a20178c03ea2eb4168099468e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bfe8ed9e28e3b89c7be0dcbad0f44b95f6035ce3c8cef916136aeb770b8b9a160e95d3f5ca6d6c9e49afe379663038d487bdef101924930f7d8afe74fa131f5e"}
08:11:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
08:11:16 INFO - Writing to file /builds/slave/test/properties/blobber_files
08:11:16 INFO - Contents:
08:11:16 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/67502ffff474e9cd48c2ca74edf8af87ad2925134c5f3753b4bd242e94a06886ffb4a6e67ae65cd631f86940bce4e946eec127edf203a13d02ebc8cd75542f74", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a07302d4e294c15baa9baa00db5c12bf85be14d73f8540ed543b8b271a441a7de66870f73f3619f1642e2c31fd590c91bac54d2a1cb1a1ccdcd644c70a4949b2", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4f7efe42c0ab78f106bd46c2ce6bd33de4b46c7c8cc5532eb33f6da56729048e45c931ade6b55b268f3ba64d0a55e029bd53279a20178c03ea2eb4168099468e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bfe8ed9e28e3b89c7be0dcbad0f44b95f6035ce3c8cef916136aeb770b8b9a160e95d3f5ca6d6c9e49afe379663038d487bdef101924930f7d8afe74fa131f5e"}
08:11:16 INFO - Running post-run listener: copy_logs_to_upload_dir
08:11:16 INFO - Copying logs to upload dir...
08:11:16 INFO - mkdir: /builds/slave/test/build/upload/logs
08:11:16 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=706.422665
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 46 secs) (at 2017-12-04 08:11:16.629499) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-04 08:11:16.632996) =========
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=20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/67502ffff474e9cd48c2ca74edf8af87ad2925134c5f3753b4bd242e94a06886ffb4a6e67ae65cd631f86940bce4e946eec127edf203a13d02ebc8cd75542f74", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a07302d4e294c15baa9baa00db5c12bf85be14d73f8540ed543b8b271a441a7de66870f73f3619f1642e2c31fd590c91bac54d2a1cb1a1ccdcd644c70a4949b2", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4f7efe42c0ab78f106bd46c2ce6bd33de4b46c7c8cc5532eb33f6da56729048e45c931ade6b55b268f3ba64d0a55e029bd53279a20178c03ea2eb4168099468e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bfe8ed9e28e3b89c7be0dcbad0f44b95f6035ce3c8cef916136aeb770b8b9a160e95d3f5ca6d6c9e49afe379663038d487bdef101924930f7d8afe74fa131f5e"}
build_url:https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011332
build_url: 'https://queue.taskcluster.net/v1/task/N73ZAk91SPeDiS7selHRAA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/67502ffff474e9cd48c2ca74edf8af87ad2925134c5f3753b4bd242e94a06886ffb4a6e67ae65cd631f86940bce4e946eec127edf203a13d02ebc8cd75542f74", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a07302d4e294c15baa9baa00db5c12bf85be14d73f8540ed543b8b271a441a7de66870f73f3619f1642e2c31fd590c91bac54d2a1cb1a1ccdcd644c70a4949b2", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4f7efe42c0ab78f106bd46c2ce6bd33de4b46c7c8cc5532eb33f6da56729048e45c931ade6b55b268f3ba64d0a55e029bd53279a20178c03ea2eb4168099468e", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/bfe8ed9e28e3b89c7be0dcbad0f44b95f6035ce3c8cef916136aeb770b8b9a160e95d3f5ca6d6c9e49afe379663038d487bdef101924930f7d8afe74fa131f5e"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-04 08:11:16.663600) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 08:11:16.664002) =========
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=20e666a3d4cfbb71505a330e000001b4-1512403167.496929-1280169880
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004796
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 08:11:16.746482) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-04 08:11:16.746799) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-04 08:11:16.747085) =========
========= Total master_lag: 0.20 =========