builder: mozilla-beta_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-034
starttime: 1510544981.11
results: success (0)
revision: 6e1fec5da81ae656d2f4fdd2dd7d7aca23eccaa3
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:41.105572) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:41.111501) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:41.111799) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:41.162710) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:41.162973) =========
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=d6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-12 19:49:41-- 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% 100M=0s
2017-11-12 19:49:41 (100 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.148626
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:41.332834) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:41.333135) =========
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=d6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.023030
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:41.388932) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-12 19:49:41.389229) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6e1fec5da81ae656d2f4fdd2dd7d7aca23eccaa3 --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 6e1fec5da81ae656d2f4fdd2dd7d7aca23eccaa3 --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=d6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-12 19:49:41,584 truncating revision to first 12 chars
2017-11-12 19:49:41,584 Setting DEBUG logging.
2017-11-12 19:49:41,584 attempt 1/10
2017-11-12 19:49:41,584 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6e1fec5da81a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-12 19:49:42,750 unpacking tar archive at: mozilla-beta-6e1fec5da81a/testing/mozharness/
program finished with exit code 0
elapsedTime=1.747714
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-12 19:49:43.154395) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:43.157719) =========
script_repo_revision: 6e1fec5da81ae656d2f4fdd2dd7d7aca23eccaa3
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:43.158102) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:43.158367) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-12 19:49:43.173439) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 45 secs) (at 2017-11-12 19:49:43.173849) =========
/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=d6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
19:49:43 INFO - MultiFileLogger online at 20171112 19:49:43 in /builds/slave/test
19:49:43 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
19:49:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
19:49:43 INFO - {'append_to_log': False,
19:49:43 INFO - 'base_work_dir': '/builds/slave/test',
19:49:43 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO',
19:49:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
19:49:43 INFO - 'branch': 'Mozilla-Beta-Non-PGO',
19:49:43 INFO - 'buildbot_json_path': 'buildprops.json',
19:49:43 INFO - 'code_coverage': False,
19:49:43 INFO - 'config_files': ('talos/linux_config.py',),
19:49:43 INFO - 'default_actions': ('clobber',
19:49:43 INFO - 'read-buildbot-config',
19:49:43 INFO - 'download-and-extract',
19:49:43 INFO - 'populate-webroot',
19:49:43 INFO - 'create-virtualenv',
19:49:43 INFO - 'install',
19:49:43 INFO - 'setup-mitmproxy',
19:49:43 INFO - 'run-tests'),
19:49:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
19:49:43 INFO - 'disable_ccov_upload': False,
19:49:43 INFO - 'disable_stylo': False,
19:49:43 INFO - 'download_minidump_stackwalk': True,
19:49:43 INFO - 'download_symbols': 'ondemand',
19:49:43 INFO - 'e10s': False,
19:49:43 INFO - 'enable_stylo': False,
19:49:43 INFO - 'enable_webrender': False,
19:49:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
19:49:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
19:49:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
19:49:43 INFO - 'gecko_profile': False,
19:49:43 INFO - 'gecko_profile_interval': 0,
19:49:43 INFO - 'installer_path': 'installer.exe',
19:49:43 INFO - 'jsd_code_coverage': False,
19:49:43 INFO - 'log_level': 'info',
19:49:43 INFO - 'log_name': 'talos',
19:49:43 INFO - 'log_to_console': True,
19:49:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
19:49:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
19:49:43 INFO - 'opt_config_files': (),
19:49:43 INFO - 'pip_index': False,
19:49:43 INFO - 'suite': 'g4-e10s',
19:49:43 INFO - 'system_bits': '32',
19:49:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
19:49:43 INFO - 'title': 'talos-linux64-ix-034',
19:49:43 INFO - 'tooltool_cache': '/builds/tooltool_cache',
19:49:43 INFO - 'use_talos_json': True,
19:49:43 INFO - 'verify': 'False',
19:49:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
19:49:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
19:49:43 INFO - 'work_dir': 'build'}
19:49:43 INFO - [mozharness: 2017-11-13 03:49:43.510210Z] Running clobber step.
19:49:43 INFO - Running pre-action listener: _resource_record_pre_action
19:49:43 INFO - Running main action method: clobber
19:49:43 INFO - rmtree: /builds/slave/test/build
19:49:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
19:49:44 INFO - Running post-action listener: _resource_record_post_action
19:49:44 INFO - [mozharness: 2017-11-13 03:49:44.622845Z] Finished clobber step (success)
19:49:44 INFO - [mozharness: 2017-11-13 03:49:44.622942Z] Running read-buildbot-config step.
19:49:44 INFO - Running pre-action listener: _resource_record_pre_action
19:49:44 INFO - Running main action method: read_buildbot_config
19:49:44 INFO - Using buildbot properties:
19:49:44 INFO - {
19:49:44 INFO - "project": "",
19:49:44 INFO - "product": "firefox",
19:49:44 INFO - "who": "ryanvm@gmail.com",
19:49:44 INFO - "installer_path": "public/build/target.tar.bz2",
19:49:44 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-g4-e10s",
19:49:44 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta",
19:49:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos g4-e10s",
19:49:44 INFO - "stage_platform": "linux64",
19:49:44 INFO - "basedir": "/builds/slave/test",
19:49:44 INFO - "buildnumber": 256,
19:49:44 INFO - "platform": "ubuntu64_hw",
19:49:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
19:49:44 INFO - "slavebuilddir": "test",
19:49:44 INFO - "taskId": "ShlZvQJqSjG53V0BPWu8MA",
19:49:44 INFO - "branch": "mozilla-beta",
19:49:44 INFO - "script_repo_revision": "production",
19:49:44 INFO - "revision": "6e1fec5da81ae656d2f4fdd2dd7d7aca23eccaa3",
19:49:44 INFO - "slavename": "talos-linux64-ix-034",
19:49:44 INFO - "repo_path": "releases/mozilla-beta"
19:49:44 INFO - }
19:49:44 INFO - Finding installer, test and symbols from parent task.
19:49:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ShlZvQJqSjG53V0BPWu8MA'}, attempt #1
19:49:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ShlZvQJqSjG53V0BPWu8MA'}, attempt #1
19:49:45 INFO - Task dependencies: PM6VPIBjQKqwvy8y3JYefQ
19:49:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ'}, attempt #1
19:49:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.tar.bz2
19:49:45 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.test_packages.json
19:49:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.crashreporter-symbols.zip
19:49:45 INFO - Running post-action listener: _resource_record_post_action
19:49:45 INFO - [mozharness: 2017-11-13 03:49:45.957771Z] Finished read-buildbot-config step (success)
19:49:45 INFO - [mozharness: 2017-11-13 03:49:45.957988Z] Running download-and-extract step.
19:49:45 INFO - Running pre-action listener: _resource_record_pre_action
19:49:45 INFO - Running main action method: download_and_extract
19:49:45 INFO - mkdir: /builds/slave/test/build/tests
19:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:49:45 INFO - trying https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.test_packages.json
19:49:45 INFO - Downloading https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
19:49:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
19:49:46 INFO - Downloaded 989 bytes.
19:49:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
19:49:46 INFO - Using the following test package requirements:
19:49:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
19:49:46 INFO - u'common': [u'target.common.tests.zip'],
19:49:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
19:49:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
19:49:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
19:49:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
19:49:46 INFO - u'mozbase': [u'target.common.tests.zip'],
19:49:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
19:49:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
19:49:46 INFO - u'web-platform': [u'target.common.tests.zip',
19:49:46 INFO - u'target.web-platform.tests.tar.gz'],
19:49:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
19:49:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
19:49:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.common.tests.zip
19:49:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
19:49:46 INFO - Fetch https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.common.tests.zip into memory
19:49:47 INFO - Content-Length response header: 58328358
19:49:47 INFO - Bytes received: 58328358
19:49:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.talos.tests.zip
19:49:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
19:49:58 INFO - Fetch https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.talos.tests.zip into memory
19:49:59 INFO - Content-Length response header: 18772073
19:49:59 INFO - Bytes received: 18772073
19:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:00 INFO - trying https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.tar.bz2
19:50:00 INFO - Downloading https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
19:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
19:50:02 INFO - Downloaded 54024643 bytes.
19:50:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.tar.bz2
19:50:02 INFO - mkdir: /builds/slave/test/properties
19:50:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
19:50:02 INFO - Writing to file /builds/slave/test/properties/build_url
19:50:02 INFO - Contents:
19:50:02 INFO - build_url:https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.tar.bz2
19:50:02 INFO - Running post-action listener: _resource_record_post_action
19:50:02 INFO - Running post-action listener: find_tests_for_verification
19:50:02 INFO - Running post-action listener: set_extra_try_arguments
19:50:02 INFO - [mozharness: 2017-11-13 03:50:02.062340Z] Finished download-and-extract step (success)
19:50:02 INFO - [mozharness: 2017-11-13 03:50:02.062567Z] Running populate-webroot step.
19:50:02 INFO - Running pre-action listener: _resource_record_pre_action
19:50:02 INFO - Running main action method: populate_webroot
19:50:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
19:50:02 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['tresize',
19:50:02 INFO - 'tcanvasmark']},
19:50:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
19:50:02 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['dromaeo_css',
19:50:02 INFO - 'kraken']},
19:50:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
19:50:02 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
19:50:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'tests': ['damp', 'tps']},
19:50:02 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['damp', 'tps']},
19:50:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
19:50:02 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['dromaeo_dom']},
19:50:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
19:50:02 INFO - 'glvideo',
19:50:02 INFO - 'displaylist_mutate']},
19:50:02 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['basic_compositor_video',
19:50:02 INFO - 'glvideo']},
19:50:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
19:50:02 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['ts_paint_webext',
19:50:02 INFO - 'tp5o_webext']},
19:50:02 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
19:50:02 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
19:50:02 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
19:50:02 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
19:50:02 INFO - 'talos_options': ['--mitmproxy',
19:50:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
19:50:02 INFO - '--firstNonBlankPaint'],
19:50:02 INFO - 'tests': ['tp6_google_heavy',
19:50:02 INFO - 'tp6_youtube_heavy',
19:50:02 INFO - 'tp6_amazon_heavy',
19:50:02 INFO - 'tp6_facebook_heavy']},
19:50:02 INFO - 'other-e10s': {'tests': ['a11yr',
19:50:02 INFO - 'ts_paint',
19:50:02 INFO - 'tpaint',
19:50:02 INFO - 'sessionrestore',
19:50:02 INFO - 'sessionrestore_many_windows',
19:50:02 INFO - 'sessionrestore_no_auto_restore',
19:50:02 INFO - 'tabpaint',
19:50:02 INFO - 'cpstartup']},
19:50:02 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['a11yr',
19:50:02 INFO - 'ts_paint',
19:50:02 INFO - 'tpaint',
19:50:02 INFO - 'sessionrestore',
19:50:02 INFO - 'sessionrestore_many_windows',
19:50:02 INFO - 'sessionrestore_no_auto_restore',
19:50:02 INFO - 'tabpaint',
19:50:02 INFO - 'cpstartup']},
19:50:02 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
19:50:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
19:50:02 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['perf_reftest_singletons']},
19:50:02 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['perf_reftest']},
19:50:02 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
19:50:02 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['speedometer']},
19:50:02 INFO - 'svgr-e10s': {'tests': ['tsvgx',
19:50:02 INFO - 'tsvgr_opacity',
19:50:02 INFO - 'tart',
19:50:02 INFO - 'tscrollx',
19:50:02 INFO - 'tsvg_static']},
19:50:02 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['tsvgx',
19:50:02 INFO - 'tsvgr_opacity',
19:50:02 INFO - 'tart',
19:50:02 INFO - 'tscrollx',
19:50:02 INFO - 'tsvg_static']},
19:50:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
19:50:02 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'talos_options': ['--disable-stylo'],
19:50:02 INFO - 'tests': ['tp5o']},
19:50:02 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
19:50:02 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
19:50:02 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
19:50:02 INFO - 'talos_options': ['--mitmproxy',
19:50:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
19:50:02 INFO - '--firstNonBlankPaint'],
19:50:02 INFO - 'tests': ['tp6_google',
19:50:02 INFO - 'tp6_youtube',
19:50:02 INFO - 'tp6_amazon',
19:50:02 INFO - 'tp6_facebook']},
19:50:02 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
19:50:02 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
19:50:02 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
19:50:02 INFO - 'talos_options': ['--disable-stylo',
19:50:02 INFO - '--mitmproxy',
19:50:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
19:50:02 INFO - '--firstNonBlankPaint'],
19:50:02 INFO - 'tests': ['tp6_google',
19:50:02 INFO - 'tp6_youtube',
19:50:02 INFO - 'tp6_amazon',
19:50:02 INFO - 'tp6_facebook']},
19:50:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
19:50:02 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
19:50:02 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
19:50:02 INFO - 'talos_options': ['--stylo-threads=1',
19:50:02 INFO - '--mitmproxy',
19:50:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
19:50:02 INFO - '--firstNonBlankPaint'],
19:50:02 INFO - 'tests': ['tp6_google',
19:50:02 INFO - 'tp6_youtube',
19:50:02 INFO - 'tp6_amazon',
19:50:02 INFO - 'tp6_facebook']},
19:50:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'talos_options': ['--xperf_path',
19:50:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
19:50:02 INFO - 'tests': ['tp5n']},
19:50:02 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
19:50:02 INFO - 'talos_options': ['--disable-stylo',
19:50:02 INFO - '--xperf_path',
19:50:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
19:50:02 INFO - 'tests': ['tp5n']}}}
19:50:02 INFO - Running post-action listener: _resource_record_post_action
19:50:02 INFO - [mozharness: 2017-11-13 03:50:02.084487Z] Finished populate-webroot step (success)
19:50:02 INFO - [mozharness: 2017-11-13 03:50:02.084581Z] Running create-virtualenv step.
19:50:02 INFO - Running pre-action listener: _resource_record_pre_action
19:50:02 INFO - Running main action method: create_virtualenv
19:50:02 INFO - Creating virtualenv /builds/slave/test/build/venv
19:50:02 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
19:50:02 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
19:50:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
19:50:02 INFO - Using real prefix '/usr'
19:50:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python
19:50:02 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
19:50:04 INFO - Installing setuptools, pip, wheel...done.
19:50:04 INFO - Return code: 0
19:50:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
19:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:04 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:04 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
19:50:04 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
19:50:04 INFO - Using env: {'DISPLAY': ':0',
19:50:04 INFO - 'HOME': '/home/cltbld',
19:50:04 INFO - 'LANG': 'en_US.UTF-8',
19:50:04 INFO - 'LANGUAGE': 'en_US:en',
19:50:04 INFO - 'LOGNAME': 'cltbld',
19:50:04 INFO - 'MAIL': '/var/mail/cltbld',
19:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:04 INFO - 'MOZ_NO_REMOTE': '1',
19:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:04 INFO - 'NO_EM_RESTART': '1',
19:50:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:04 INFO - 'PWD': '/builds/slave/test',
19:50:04 INFO - 'SHELL': '/bin/bash',
19:50:04 INFO - 'SHLVL': '1',
19:50:04 INFO - 'TERM': 'linux',
19:50:04 INFO - 'TMOUT': '86400',
19:50:04 INFO - 'USER': 'cltbld',
19:50:04 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:04 INFO - '_': '/tools/buildbot/bin/python'}
19:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
19:50:04 INFO - Return code: 0
19:50:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
19:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:04 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:04 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
19:50:04 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
19:50:04 INFO - Using env: {'DISPLAY': ':0',
19:50:04 INFO - 'HOME': '/home/cltbld',
19:50:04 INFO - 'LANG': 'en_US.UTF-8',
19:50:04 INFO - 'LANGUAGE': 'en_US:en',
19:50:04 INFO - 'LOGNAME': 'cltbld',
19:50:04 INFO - 'MAIL': '/var/mail/cltbld',
19:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:04 INFO - 'MOZ_NO_REMOTE': '1',
19:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:04 INFO - 'NO_EM_RESTART': '1',
19:50:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:04 INFO - 'PWD': '/builds/slave/test',
19:50:04 INFO - 'SHELL': '/bin/bash',
19:50:04 INFO - 'SHLVL': '1',
19:50:04 INFO - 'TERM': 'linux',
19:50:04 INFO - 'TMOUT': '86400',
19:50:04 INFO - 'USER': 'cltbld',
19:50:04 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:04 INFO - '_': '/tools/buildbot/bin/python'}
19:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:05 INFO - Collecting psutil>=3.1.1
19:50:06 INFO - Installing collected packages: psutil
19:50:06 INFO - Successfully installed psutil-3.1.1
19:50:06 INFO - Return code: 0
19:50:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
19:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:06 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:06 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
19:50:06 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
19:50:06 INFO - Using env: {'DISPLAY': ':0',
19:50:06 INFO - 'HOME': '/home/cltbld',
19:50:06 INFO - 'LANG': 'en_US.UTF-8',
19:50:06 INFO - 'LANGUAGE': 'en_US:en',
19:50:06 INFO - 'LOGNAME': 'cltbld',
19:50:06 INFO - 'MAIL': '/var/mail/cltbld',
19:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:06 INFO - 'MOZ_NO_REMOTE': '1',
19:50:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:06 INFO - 'NO_EM_RESTART': '1',
19:50:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:06 INFO - 'PWD': '/builds/slave/test',
19:50:06 INFO - 'SHELL': '/bin/bash',
19:50:06 INFO - 'SHLVL': '1',
19:50:06 INFO - 'TERM': 'linux',
19:50:06 INFO - 'TMOUT': '86400',
19:50:06 INFO - 'USER': 'cltbld',
19:50:06 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:06 INFO - '_': '/tools/buildbot/bin/python'}
19:50:06 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:06 INFO - Collecting mozsystemmonitor==0.3
19:50:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
19:50:07 INFO - Installing collected packages: mozsystemmonitor
19:50:07 INFO - Successfully installed mozsystemmonitor-0.3
19:50:07 INFO - Return code: 0
19:50:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
19:50:07 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')]}
19:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:07 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')]}
19:50:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:07 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:07 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
19:50:07 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
19:50:07 INFO - Using env: {'DISPLAY': ':0',
19:50:07 INFO - 'HOME': '/home/cltbld',
19:50:07 INFO - 'LANG': 'en_US.UTF-8',
19:50:07 INFO - 'LANGUAGE': 'en_US:en',
19:50:07 INFO - 'LOGNAME': 'cltbld',
19:50:07 INFO - 'MAIL': '/var/mail/cltbld',
19:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:07 INFO - 'MOZ_NO_REMOTE': '1',
19:50:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:07 INFO - 'NO_EM_RESTART': '1',
19:50:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:07 INFO - 'PWD': '/builds/slave/test',
19:50:07 INFO - 'SHELL': '/bin/bash',
19:50:07 INFO - 'SHLVL': '1',
19:50:07 INFO - 'TERM': 'linux',
19:50:07 INFO - 'TMOUT': '86400',
19:50:07 INFO - 'USER': 'cltbld',
19:50:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:07 INFO - '_': '/tools/buildbot/bin/python'}
19:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:08 INFO - Collecting jsonschema==2.5.1
19:50:09 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
19:50:09 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
19:50:10 INFO - Installing collected packages: functools32, jsonschema
19:50:10 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
19:50:10 INFO - Return code: 0
19:50:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
19:50: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')]}
19:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50: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')]}
19:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:10 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:10 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
19:50:10 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
19:50:10 INFO - Using env: {'DISPLAY': ':0',
19:50:10 INFO - 'HOME': '/home/cltbld',
19:50:10 INFO - 'LANG': 'en_US.UTF-8',
19:50:10 INFO - 'LANGUAGE': 'en_US:en',
19:50:10 INFO - 'LOGNAME': 'cltbld',
19:50:10 INFO - 'MAIL': '/var/mail/cltbld',
19:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:10 INFO - 'MOZ_NO_REMOTE': '1',
19:50:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:10 INFO - 'NO_EM_RESTART': '1',
19:50:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:10 INFO - 'PWD': '/builds/slave/test',
19:50:10 INFO - 'SHELL': '/bin/bash',
19:50:10 INFO - 'SHLVL': '1',
19:50:10 INFO - 'TERM': 'linux',
19:50:10 INFO - 'TMOUT': '86400',
19:50:10 INFO - 'USER': 'cltbld',
19:50:10 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:10 INFO - '_': '/tools/buildbot/bin/python'}
19:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
19:50:11 INFO - Return code: 0
19:50:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
19:50:11 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')]}
19:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:11 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')]}
19:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:11 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:11 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
19:50:11 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
19:50:11 INFO - Using env: {'DISPLAY': ':0',
19:50:11 INFO - 'HOME': '/home/cltbld',
19:50:11 INFO - 'LANG': 'en_US.UTF-8',
19:50:11 INFO - 'LANGUAGE': 'en_US:en',
19:50:11 INFO - 'LOGNAME': 'cltbld',
19:50:11 INFO - 'MAIL': '/var/mail/cltbld',
19:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:11 INFO - 'MOZ_NO_REMOTE': '1',
19:50:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:11 INFO - 'NO_EM_RESTART': '1',
19:50:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:11 INFO - 'PWD': '/builds/slave/test',
19:50:11 INFO - 'SHELL': '/bin/bash',
19:50:11 INFO - 'SHLVL': '1',
19:50:11 INFO - 'TERM': 'linux',
19:50:11 INFO - 'TMOUT': '86400',
19:50:11 INFO - 'USER': 'cltbld',
19:50:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:11 INFO - '_': '/tools/buildbot/bin/python'}
19:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:11 INFO - Collecting blobuploader==1.2.4
19:50:12 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
19:50:13 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
19:50:14 INFO - Installing collected packages: requests, docopt, blobuploader
19:50:15 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
19:50:15 INFO - Return code: 0
19:50:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv
19:50:15 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')]}
19:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:15 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')]}
19:50:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:15 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:15 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
19:50:15 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
19:50:15 INFO - Using env: {'DISPLAY': ':0',
19:50:15 INFO - 'HOME': '/home/cltbld',
19:50:15 INFO - 'LANG': 'en_US.UTF-8',
19:50:15 INFO - 'LANGUAGE': 'en_US:en',
19:50:15 INFO - 'LOGNAME': 'cltbld',
19:50:15 INFO - 'MAIL': '/var/mail/cltbld',
19:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:15 INFO - 'MOZ_NO_REMOTE': '1',
19:50:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:15 INFO - 'NO_EM_RESTART': '1',
19:50:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:15 INFO - 'PWD': '/builds/slave/test',
19:50:15 INFO - 'SHELL': '/bin/bash',
19:50:15 INFO - 'SHLVL': '1',
19:50:15 INFO - 'TERM': 'linux',
19:50:15 INFO - 'TMOUT': '86400',
19:50:15 INFO - 'USER': 'cltbld',
19:50:15 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:15 INFO - '_': '/tools/buildbot/bin/python'}
19:50:15 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:15 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
19:50:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
19:50:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
19:50:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
19:50:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
19:50:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
19:50:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
19:50:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
19:50:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
19:50:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
19:50:17 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
19:50:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
19:50:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
19:50:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
19:50:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
19:50:18 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
19:50:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
19:50:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
19:50:18 INFO - Running setup.py install for manifestparser: started
19:50:19 INFO - Running setup.py install for manifestparser: finished with status 'done'
19:50:19 INFO - Running setup.py install for mozcrash: started
19:50:19 INFO - Running setup.py install for mozcrash: finished with status 'done'
19:50:19 INFO - Running setup.py install for mozdebug: started
19:50:19 INFO - Running setup.py install for mozdebug: finished with status 'done'
19:50:19 INFO - Running setup.py install for mozdevice: started
19:50:19 INFO - Running setup.py install for mozdevice: finished with status 'done'
19:50:19 INFO - Running setup.py install for mozfile: started
19:50:19 INFO - Running setup.py install for mozfile: finished with status 'done'
19:50:19 INFO - Running setup.py install for mozhttpd: started
19:50:20 INFO - Running setup.py install for mozhttpd: finished with status 'done'
19:50:20 INFO - Running setup.py install for mozinfo: started
19:50:20 INFO - Running setup.py install for mozinfo: finished with status 'done'
19:50:20 INFO - Running setup.py install for mozInstall: started
19:50:20 INFO - Running setup.py install for mozInstall: finished with status 'done'
19:50:20 INFO - Running setup.py install for mozleak: started
19:50:20 INFO - Running setup.py install for mozleak: finished with status 'done'
19:50:20 INFO - Running setup.py install for mozlog: started
19:50:21 INFO - Running setup.py install for mozlog: finished with status 'done'
19:50:21 INFO - Running setup.py install for moznetwork: started
19:50:21 INFO - Running setup.py install for moznetwork: finished with status 'done'
19:50:21 INFO - Running setup.py install for mozprocess: started
19:50:21 INFO - Running setup.py install for mozprocess: finished with status 'done'
19:50:21 INFO - Running setup.py install for mozprofile: started
19:50:21 INFO - Running setup.py install for mozprofile: finished with status 'done'
19:50:21 INFO - Running setup.py install for mozrunner: started
19:50:21 INFO - Running setup.py install for mozrunner: finished with status 'done'
19:50:21 INFO - Running setup.py install for mozscreenshot: started
19:50:22 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
19:50:22 INFO - Running setup.py install for moztest: started
19:50:22 INFO - Running setup.py install for moztest: finished with status 'done'
19:50:22 INFO - Running setup.py install for mozversion: started
19:50:22 INFO - Running setup.py install for mozversion: finished with status 'done'
19:50:22 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
19:50:22 INFO - Return code: 0
19:50:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv
19:50:22 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')]}
19:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:22 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')]}
19:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:22 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:22 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
19:50:22 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
19:50:22 INFO - Using env: {'DISPLAY': ':0',
19:50:22 INFO - 'HOME': '/home/cltbld',
19:50:22 INFO - 'LANG': 'en_US.UTF-8',
19:50:22 INFO - 'LANGUAGE': 'en_US:en',
19:50:22 INFO - 'LOGNAME': 'cltbld',
19:50:22 INFO - 'MAIL': '/var/mail/cltbld',
19:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:22 INFO - 'MOZ_NO_REMOTE': '1',
19:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:22 INFO - 'NO_EM_RESTART': '1',
19:50:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:22 INFO - 'PWD': '/builds/slave/test',
19:50:22 INFO - 'SHELL': '/bin/bash',
19:50:22 INFO - 'SHLVL': '1',
19:50:22 INFO - 'TERM': 'linux',
19:50:22 INFO - 'TMOUT': '86400',
19:50:22 INFO - 'USER': 'cltbld',
19:50:22 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:22 INFO - '_': '/tools/buildbot/bin/python'}
19:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:23 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
19:50:23 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))
19:50:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
19:50:23 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))
19:50:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
19:50:24 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))
19:50:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
19:50:24 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))
19:50:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
19:50:24 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))
19:50:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
19:50:24 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))
19:50:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
19:50:24 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))
19:50:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
19:50:25 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))
19:50:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
19:50:25 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))
19:50:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
19:50:25 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))
19:50:25 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
19:50:25 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))
19:50:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
19:50:25 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))
19:50:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
19:50:26 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))
19:50:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
19:50:26 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))
19:50:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
19:50:26 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))
19:50:26 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
19:50:26 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))
19:50:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
19:50:26 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))
19:50:26 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
19:50:27 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
19:50:27 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))
19:50:27 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))
19:50:27 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))
19:50:27 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))
19:50:27 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))
19:50:27 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))
19:50:27 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
19:50:29 INFO - Installing collected packages: six, blessings
19:50:29 INFO - Successfully installed blessings-1.6 six-1.10.0
19:50:29 INFO - Return code: 0
19:50:29 INFO - Done creating virtualenv /builds/slave/test/build/venv.
19:50:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
19:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
19:50:29 INFO - Reading from file tmpfile_stdout
19:50:29 INFO - Reading from file tmpfile_stderr
19:50:29 INFO - Current package versions:
19:50:29 INFO - blessings == 1.6
19:50:29 INFO - blobuploader == 1.2.4
19:50:29 INFO - docopt == 0.6.1
19:50:29 INFO - functools32 == 3.2.3.post2
19:50:29 INFO - jsonschema == 2.5.1
19:50:29 INFO - manifestparser == 1.2
19:50:29 INFO - mozInstall == 1.14
19:50:29 INFO - mozcrash == 1.0
19:50:29 INFO - mozdebug == 0.1
19:50:29 INFO - mozdevice == 0.51
19:50:29 INFO - mozfile == 1.2
19:50:29 INFO - mozhttpd == 0.7
19:50:29 INFO - mozinfo == 0.10
19:50:29 INFO - mozleak == 0.1
19:50:29 INFO - mozlog == 3.5
19:50:29 INFO - moznetwork == 0.27
19:50:29 INFO - mozprocess == 0.25
19:50:29 INFO - mozprofile == 0.29
19:50:29 INFO - mozrunner == 6.13
19:50:29 INFO - mozscreenshot == 0.1
19:50:29 INFO - mozsystemmonitor == 0.3
19:50:29 INFO - moztest == 0.8
19:50:29 INFO - mozversion == 1.4
19:50:29 INFO - psutil == 3.1.1
19:50:29 INFO - requests == 1.2.3
19:50:29 INFO - six == 1.10.0
19:50:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv
19:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:29 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:29 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
19:50:29 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
19:50:29 INFO - Using env: {'DISPLAY': ':0',
19:50:29 INFO - 'HOME': '/home/cltbld',
19:50:29 INFO - 'LANG': 'en_US.UTF-8',
19:50:29 INFO - 'LANGUAGE': 'en_US:en',
19:50:29 INFO - 'LOGNAME': 'cltbld',
19:50:29 INFO - 'MAIL': '/var/mail/cltbld',
19:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:29 INFO - 'MOZ_NO_REMOTE': '1',
19:50:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:29 INFO - 'NO_EM_RESTART': '1',
19:50:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:29 INFO - 'PWD': '/builds/slave/test',
19:50:29 INFO - 'SHELL': '/bin/bash',
19:50:29 INFO - 'SHLVL': '1',
19:50:29 INFO - 'TERM': 'linux',
19:50:29 INFO - 'TMOUT': '86400',
19:50:29 INFO - 'USER': 'cltbld',
19:50:29 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:29 INFO - '_': '/tools/buildbot/bin/python'}
19:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:30 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))
19:50:30 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))
19:50:30 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))
19:50:30 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))
19:50:30 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))
19:50:30 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))
19:50:30 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))
19:50:30 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))
19:50:30 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))
19:50:30 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
19:50:31 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
19:50:32 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
19:50:32 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))
19:50:32 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))
19:50:32 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))
19:50:32 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))
19:50:33 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
19:50:33 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
19:50:34 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
19:50:34 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
19:50:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
19:50:35 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
19:50:36 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
19:50:36 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
19:50:37 INFO - Found existing installation: requests 1.2.3
19:50:37 INFO - Uninstalling requests-1.2.3:
19:50:37 INFO - Successfully uninstalled requests-1.2.3
19:50:37 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
19:50:37 INFO - Return code: 0
19:50:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
19:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
19:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
19:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
19:50:37 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 0x24852d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279', '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
19:50:37 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
19:50:37 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
19:50:37 INFO - Using env: {'DISPLAY': ':0',
19:50:37 INFO - 'HOME': '/home/cltbld',
19:50:37 INFO - 'LANG': 'en_US.UTF-8',
19:50:37 INFO - 'LANGUAGE': 'en_US:en',
19:50:37 INFO - 'LOGNAME': 'cltbld',
19:50:37 INFO - 'MAIL': '/var/mail/cltbld',
19:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:37 INFO - 'MOZ_NO_REMOTE': '1',
19:50:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:37 INFO - 'NO_EM_RESTART': '1',
19:50:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:37 INFO - 'PWD': '/builds/slave/test',
19:50:37 INFO - 'SHELL': '/bin/bash',
19:50:37 INFO - 'SHLVL': '1',
19:50:37 INFO - 'TERM': 'linux',
19:50:37 INFO - 'TMOUT': '86400',
19:50:37 INFO - 'USER': 'cltbld',
19:50:37 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:37 INFO - '_': '/tools/buildbot/bin/python'}
19:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple
19:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
19:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
19:50:37 INFO - Return code: 0
19:50:37 INFO - Running post-action listener: _resource_record_post_action
19:50:37 INFO - Running post-action listener: _start_resource_monitoring
19:50:37 INFO - Starting resource monitoring.
19:50:37 INFO - [mozharness: 2017-11-13 03:50:37.868665Z] Finished create-virtualenv step (success)
19:50:37 INFO - [mozharness: 2017-11-13 03:50:37.869184Z] Running install step.
19:50:37 INFO - Running pre-action listener: _resource_record_pre_action
19:50:37 INFO - Running main action method: install
19:50:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
19:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
19:50:38 INFO - Reading from file tmpfile_stdout
19:50:38 INFO - Reading from file tmpfile_stderr
19:50:38 INFO - Detecting whether we're running mozinstall >=1.0...
19:50:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
19:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
19:50:38 INFO - Reading from file tmpfile_stdout
19:50:38 INFO - Output received:
19:50:38 INFO - Usage: mozinstall [options] installer
19:50:38 INFO - Options:
19:50:38 INFO - -h, --help show this help message and exit
19:50:38 INFO - -d DEST, --destination=DEST
19:50:38 INFO - Directory to install application into. [default:
19:50:38 INFO - "/builds/slave/test"]
19:50:38 INFO - --app=APP Application being installed. [default: firefox]
19:50:38 INFO - mkdir: /builds/slave/test/build/application
19:50:38 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']
19:50:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
19:50:53 INFO - Reading from file tmpfile_stdout
19:50:53 INFO - Output received:
19:50:53 INFO - /builds/slave/test/build/application/firefox/firefox
19:50:53 INFO - Running post-action listener: _resource_record_post_action
19:50:53 INFO - [mozharness: 2017-11-13 03:50:53.559039Z] Finished install step (success)
19:50:53 INFO - [mozharness: 2017-11-13 03:50:53.559185Z] Running setup-mitmproxy step.
19:50:53 INFO - Running pre-action listener: _resource_record_pre_action
19:50:53 INFO - Running main action method: setup_mitmproxy
19:50:53 INFO - Skipping: mitmproxy is not required
19:50:53 INFO - Running post-action listener: _resource_record_post_action
19:50:53 INFO - [mozharness: 2017-11-13 03:50:53.560103Z] Finished setup-mitmproxy step (success)
19:50:53 INFO - [mozharness: 2017-11-13 03:50:53.560409Z] Running run-tests step.
19:50:53 INFO - Running pre-action listener: _resource_record_pre_action
19:50:53 INFO - Running pre-action listener: _set_gcov_prefix
19:50:53 INFO - Running main action method: run_tests
19:50:53 WARNING - Try message not found.
19:50:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
19:50:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
19:50:53 INFO - Python 2.7.3
19:50:53 INFO - Return code: 0
19:50:53 INFO - grabbing minidump binary from tooltool
19:50: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')]}
19:50:53 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 0x27fdad0>, '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 0x27fe9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27fee60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
19:50:53 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
19:50:53 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
19:50:53 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
19:50:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
19:50:53 INFO - Return code: 0
19:50:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
19:50:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
19:50:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
19:50:53 INFO - ENV: RUST_BACKTRACE is now full
19:50:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
19:50:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
19:50:53 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/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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
19:50:53 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/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-034 --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
19:50:53 INFO - Using env: {'DISPLAY': ':0',
19:50:53 INFO - 'HOME': '/home/cltbld',
19:50:53 INFO - 'LANG': 'en_US.UTF-8',
19:50:53 INFO - 'LANGUAGE': 'en_US:en',
19:50:53 INFO - 'LOGNAME': 'cltbld',
19:50:53 INFO - 'MAIL': '/var/mail/cltbld',
19:50:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
19:50:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
19:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:53 INFO - 'MOZ_NO_REMOTE': '1',
19:50:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
19:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:53 INFO - 'NO_EM_RESTART': '1',
19:50:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:53 INFO - 'PWD': '/builds/slave/test',
19:50:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
19:50:53 INFO - 'RUST_BACKTRACE': 'full',
19:50:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
19:50:53 INFO - 'SHELL': '/bin/bash',
19:50:53 INFO - 'SHLVL': '1',
19:50:53 INFO - 'TERM': 'linux',
19:50:53 INFO - 'TMOUT': '86400',
19:50:53 INFO - 'USER': 'cltbld',
19:50:53 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:53 INFO - '_': '/tools/buildbot/bin/python'}
19:50:53 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/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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
19:50:53 INFO - mozversion application_buildid: 20171113022804
19:50:53 INFO - mozversion application_changeset: 6e1fec5da81ae656d2f4fdd2dd7d7aca23eccaa3
19:50:53 INFO - mozversion application_display_name: Firefox
19:50:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
19:50:53 INFO - mozversion application_name: Firefox
19:50:53 INFO - mozversion application_remotingname: firefox
19:50:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
19:50:53 INFO - mozversion application_vendor: Mozilla
19:50:53 INFO - mozversion application_version: 58.0
19:50:53 INFO - mozversion platform_buildid: 20171113022804
19:50:53 INFO - mozversion platform_changeset: 6e1fec5da81ae656d2f4fdd2dd7d7aca23eccaa3
19:50:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
19:50:53 INFO - mozversion platform_version: 58.0
19:50:53 INFO - using testdate: 1510545053
19:50:53 INFO - actual date: 1510545053
19:50:53 INFO - starting webserver on 'localhost:49308'
19:50:53 INFO - SUITE-START | Running 3 tests
19:50:53 INFO - TEST-START | basic_compositor_video
19:50:53 INFO - Initialising browser for basic_compositor_video test...
19:50:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49308/getInfo.html -profile /tmp/tmpuEbb8T/profile
19:50:53 INFO - TEST-INFO | started process 12372 (/builds/slave/test/build/application/firefox/firefox http://localhost:49308/getInfo.html)
19:50:59 INFO - TEST-INFO | 12372: exit 0
19:50:59 INFO - Browser initialized.
19:50:59 INFO - Running cycle 1/1 for basic_compositor_video test...
19:50:59 INFO - Using env: {'DISPLAY': ':0',
19:50:59 INFO - 'HOME': '/home/cltbld',
19:50:59 INFO - 'JSGC_DISABLE_POISONING': '1',
19:50:59 INFO - 'LANG': 'en_US.UTF-8',
19:50:59 INFO - 'LANGUAGE': 'en_US:en',
19:50:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
19:50:59 INFO - 'LOGNAME': 'cltbld',
19:50:59 INFO - 'MAIL': '/var/mail/cltbld',
19:50:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
19:50:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
19:50:59 INFO - 'MOZ_CRASHREPORTER': '1',
19:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
19:50:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
19:50:59 INFO - 'MOZ_NO_REMOTE': '1',
19:50:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
19:50:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
19:50:59 INFO - 'NO_EM_RESTART': '1',
19:50:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
19:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
19:50:59 INFO - 'PWD': '/builds/slave/test',
19:50:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
19:50:59 INFO - 'RUST_BACKTRACE': 'full',
19:50:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
19:50:59 INFO - 'SHELL': '/bin/bash',
19:50:59 INFO - 'SHLVL': '1',
19:50:59 INFO - 'TERM': 'linux',
19:50:59 INFO - 'TMOUT': '86400',
19:50:59 INFO - 'USER': 'cltbld',
19:50:59 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
19:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
19:50:59 INFO - '_': '/tools/buildbot/bin/python'}
19:50:59 INFO - TEST-INFO | started process 12647 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuEbb8T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
19:51:00 INFO - PID 12647 |
19:51:00 INFO - PID 12647 | (/builds/slave/test/build/application/firefox/firefox:12696): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
19:51:00 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.68127731092437 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.66871976647206 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.716850686498856 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7183247422680414 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.005994659546061 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.167708333333334 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.641595873786409 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5535612788632305 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.129282051282047 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.437323369565221 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 10.419288194444448 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 9.617163461538457 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.91 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.47206806282723 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.71209745762712 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.980089820359298 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.406996197718634 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.933087431694014 ms/frame
19:51:46 INFO - PID 12647 |
19:51:46 INFO - PID 12647 | Cycle 1(1): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:52:32 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822141255605383 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6698956594323868 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7622166764533174 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7476244541484713 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.082714285714286 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.7956166982922217 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.489482558139537 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.454645941278062 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6289681050656695 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.451403269754761 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 10.38425605536332 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 9.303162790697677 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.95259124087592 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.586666666666654 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.449917012448134 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.769205882352939 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.409961977186311 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05215469613261 ms/frame
19:52:32 INFO - PID 12647 |
19:52:32 INFO - PID 12647 | Cycle 1(2): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:53:18 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.690738028169014 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683194328607174 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7361545138888888 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7406701479547437 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0602029769959405 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.10828542094456 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.6063461538461534 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5152636203866456 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6506873822975505 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603711484593842 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 10.20756802721089 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 10.000499999999994 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.94987226277372 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.527052631578945 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.605651260504189 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.56118497109826 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.677276264591459 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.929672131147527 ms/frame
19:53:18 INFO - PID 12647 |
19:53:18 INFO - PID 12647 | Cycle 1(3): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:54:04 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6826584408300616 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6708980785296579 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7206249999999996 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7379105125977419 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8373849104859348 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.000360000000001 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.4734895833333304 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4013010204081637 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.736912045889099 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.407972972972975 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 9.838590163934427 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 9.662077294685988 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.032628676470589 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.474712041884807 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500416666666661 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.27088957055214 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.862450592885393 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.930300546448063 ms/frame
19:54:04 INFO - PID 12647 |
19:54:04 INFO - PID 12647 | Cycle 1(4): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:54:50 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.692208121827411 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6671624999999999 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.757788517867604 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7232472006890616 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093212824010915 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1425258799171845 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.4131001137656427 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3514321608040207 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.705617870722431 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.391873315363877 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 10.419947916666665 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 9.757024390243895 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.989285714285703 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.582222222222226 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.450082987551841 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054186746987968 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.677256809338523 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111305555555575 ms/frame
19:54:50 INFO - PID 12647 |
19:54:50 INFO - PID 12647 | Cycle 1(5): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:55:37 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6779781879194633 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6674875 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7241379310344827 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6941320914479259 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.184205020920503 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0913190184049055 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.492753201396975 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.460519031141869 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693358633776094 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.333493333333337 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 9.587763578274764 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 9.38927230046947 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.950602189781009 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.526684210526314 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.501083333333343 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.121333333333315 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540865384615385 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.240000000000007 ms/frame
19:55:37 INFO - PID 12647 |
19:55:37 INFO - PID 12647 | Cycle 1(6): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:56:23 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6761787709497207 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6672041666666664 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.746824796274738 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7338864818024262 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.08191156462585 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8769573643410857 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.421328392246293 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3616638655462223 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.726545801526716 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.604621848739492 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 10.00125 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 9.662801932367154 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.793723021582743 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.42255208333332 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.45170124481328 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90494047619047 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.40982889733839 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.993049450549453 ms/frame
19:56:23 INFO - PID 12647 |
19:56:23 INFO - PID 12647 | Cycle 1(7): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:57:09 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6809411764705882 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6703464106844739 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7590357561547485 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7485183566433569 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.185062761506277 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.065376016260164 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.4655138568129313 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4200341880341902 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.694611005692602 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.527417127071821 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 9.901633663366336 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 10.105378787878777 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.19725746268656 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.582275132275148 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503520833333308 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05093373493977 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.585212355212354 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.052044198895018 ms/frame
19:57:09 INFO - PID 12647 |
19:57:09 INFO - PID 12647 | Cycle 1(8): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:57:55 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6779250559284118 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684070058381981 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7554008191925106 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7322207792207793 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.032607526881721 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.018293172690764 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.413265073947668 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.373541315345697 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.736711281070751 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.48010958904109 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 10.206258503401358 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 10.051758793969846 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.196660447761188 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.69524064171124 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39787190082646 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.76764705882353 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.767470588235302 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.992499999999971 ms/frame
19:57:55 INFO - PID 12647 |
19:57:55 INFO - PID 12647 | Cycle 1(9): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:58:41 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.679012311135982 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.667275 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.721632243258749 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6925211505922169 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1047332421340625 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.10782340862423 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.496678321678321 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4434939759036114 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.566716141001853 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.510482093663913 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 10.206802721088433 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 9.574234449760763 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.949945255474452 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.474659685863854 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.248346938775516 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.76570588235293 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.454293893129776 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932622950819663 ms/frame
19:58:41 INFO - PID 12647 |
19:58:41 INFO - PID 12647 | Cycle 1(10): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
19:59:27 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681992713004484 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6668875000000003 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7154459691252142 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7029446808510647 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8083185279187814 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.168260416666666 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.4370389461626596 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.350536013400336 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.597602611940303 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.4975274725274685 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 10.528771929824565 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 9.85224137931035 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.029430147058815 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.259615384615385 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.451493775933631 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.348117283950598 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.453473282442767 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.755940860215032 ms/frame
19:59:27 INFO - PID 12647 |
19:59:27 INFO - PID 12647 | Cycle 1(11): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
20:00:14 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6839001122334456 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6661781848459614 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7628260869565218 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7369835069444446 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093396998635745 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.08231632653061 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_startup = 3.4364833906071013 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3904576271186424 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693614800759013 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.58758379888268 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_startup = 9.711925566343044 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.480p.60fps.webm_scale_2_inclip = 10.051080402010054 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.990146520146538 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.58441798941796 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346090534979417 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.91092261904764 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.631375968992264 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.29957627118645 ms/frame
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | Cycle 1(12): loaded http://localhost:49308/tests/video/video_playback.html (next: http://localhost:49308/tests/video/video_playback.html)
20:00:14 INFO - PID 12647 | __start_tp_report
20:00:14 INFO - PID 12647 | _x_x_mozilla_page_load
20:00:14 INFO - PID 12647 | _x_x_mozilla_page_load_details
20:00:14 INFO - PID 12647 | |i|pagename|runs|
20:00:14 INFO - PID 12647 | |0;240p.120fps.mp4_scale_1_startup;1.68127731092437;1.6822141255605383;1.690738028169014;1.6826584408300616;1.692208121827411;1.6779781879194633;1.6761787709497207;1.6809411764705882;1.6779250559284118;1.679012311135982;1.681992713004484;1.6839001122334456
20:00:14 INFO - PID 12647 | |1;240p.120fps.mp4_scale_1_inclip;1.66871976647206;1.6698956594323868;1.6683194328607174;1.6708980785296579;1.6671624999999999;1.6674875;1.6672041666666664;1.6703464106844739;1.6684070058381981;1.667275;1.6668875000000003;1.6661781848459614
20:00:14 INFO - PID 12647 | |2;240p.120fps.mp4_scale_1.1_startup;1.716850686498856;1.7622166764533174;1.7361545138888888;1.7206249999999996;1.757788517867604;1.7241379310344827;1.746824796274738;1.7590357561547485;1.7554008191925106;1.721632243258749;1.7154459691252142;1.7628260869565218
20:00:14 INFO - PID 12647 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7183247422680414;1.7476244541484713;1.7406701479547437;1.7379105125977419;1.7232472006890616;1.6941320914479259;1.7338864818024262;1.7485183566433569;1.7322207792207793;1.6925211505922169;1.7029446808510647;1.7369835069444446
20:00:14 INFO - PID 12647 | |4;240p.120fps.mp4_scale_2_startup;4.005994659546061;4.082714285714286;4.0602029769959405;3.8373849104859348;4.093212824010915;4.184205020920503;4.08191156462585;4.185062761506277;4.032607526881721;4.1047332421340625;3.8083185279187814;4.093396998635745
20:00:14 INFO - PID 12647 | |5;240p.120fps.mp4_scale_2_inclip;4.167708333333334;3.7956166982922217;4.10828542094456;4.000360000000001;4.1425258799171845;4.0913190184049055;3.8769573643410857;4.065376016260164;4.018293172690764;4.10782340862423;4.168260416666666;4.08231632653061
20:00:14 INFO - PID 12647 | |6;480p.60fps.webm_scale_1_startup;3.641595873786409;3.489482558139537;3.6063461538461534;3.4734895833333304;3.4131001137656427;3.492753201396975;3.421328392246293;3.4655138568129313;3.413265073947668;3.496678321678321;3.4370389461626596;3.4364833906071013
20:00:14 INFO - PID 12647 | |7;480p.60fps.webm_scale_1_inclip;3.5535612788632305;3.454645941278062;3.5152636203866456;3.4013010204081637;3.3514321608040207;3.460519031141869;3.3616638655462223;3.4200341880341902;3.373541315345697;3.4434939759036114;3.350536013400336;3.3904576271186424
20:00:14 INFO - PID 12647 | |8;480p.60fps.webm_scale_1.1_startup;5.129282051282047;5.6289681050656695;5.6506873822975505;5.736912045889099;5.705617870722431;5.693358633776094;5.726545801526716;5.694611005692602;5.736711281070751;5.566716141001853;5.597602611940303;5.693614800759013
20:00:14 INFO - PID 12647 | |9;480p.60fps.webm_scale_1.1_inclip;5.437323369565221;5.451403269754761;5.603711484593842;5.407972972972975;5.391873315363877;5.333493333333337;5.604621848739492;5.527417127071821;5.48010958904109;5.510482093663913;5.4975274725274685;5.58758379888268
20:00:14 INFO - PID 12647 | |10;480p.60fps.webm_scale_2_startup;10.419288194444448;10.38425605536332;10.20756802721089;9.838590163934427;10.419947916666665;9.587763578274764;10.00125;9.901633663366336;10.206258503401358;10.206802721088433;10.528771929824565;9.711925566343044
20:00:14 INFO - PID 12647 | |11;480p.60fps.webm_scale_2_inclip;9.617163461538457;9.303162790697677;10.000499999999994;9.662077294685988;9.757024390243895;9.38927230046947;9.662801932367154;10.105378787878777;10.051758793969846;9.574234449760763;9.85224137931035;10.051080402010054
20:00:14 INFO - PID 12647 | |12;1080p.60fps.mp4_scale_1_startup;10.91;10.95259124087592;10.94987226277372;11.032628676470589;10.989285714285703;10.950602189781009;10.793723021582743;11.19725746268656;11.196660447761188;10.949945255474452;11.029430147058815;10.990146520146538
20:00:14 INFO - PID 12647 | |13;1080p.60fps.mp4_scale_1_inclip;10.47206806282723;10.586666666666654;10.527052631578945;10.474712041884807;10.582222222222226;10.526684210526314;10.42255208333332;10.582275132275148;10.69524064171124;10.474659685863854;10.259615384615385;10.58441798941796
20:00:14 INFO - PID 12647 | |14;1080p.60fps.mp4_scale_1.1_startup;12.71209745762712;12.449917012448134;12.605651260504189;12.500416666666661;12.450082987551841;12.501083333333343;12.45170124481328;12.503520833333308;12.39787190082646;12.248346938775516;12.451493775933631;12.346090534979417
20:00:14 INFO - PID 12647 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.980089820359298;11.769205882352939;11.56118497109826;12.27088957055214;12.054186746987968;12.121333333333315;11.90494047619047;12.05093373493977;11.76764705882353;11.76570588235293;12.348117283950598;11.91092261904764
20:00:14 INFO - PID 12647 | |16;1080p.60fps.mp4_scale_2_startup;11.406996197718634;11.409961977186311;11.677276264591459;11.862450592885393;11.677256809338523;11.540865384615385;11.40982889733839;11.585212355212354;11.767470588235302;11.454293893129776;11.453473282442767;11.631375968992264
20:00:14 INFO - PID 12647 | |17;1080p.60fps.mp4_scale_2_inclip;10.933087431694014;11.05215469613261;10.929672131147527;10.930300546448063;11.111305555555575;11.240000000000007;10.993049450549453;11.052044198895018;10.992499999999971;10.932622950819663;10.755940860215032;11.29957627118645
20:00:14 INFO - PID 12647 | __end_tp_report
20:00:14 INFO - PID 12647 | __start_cc_report
20:00:14 INFO - PID 12647 | _x_x_mozilla_cycle_collect,556
20:00:14 INFO - PID 12647 | __end_cc_report
20:00:14 INFO - PID 12647 | __startTimestamp1510545614363__endTimestamp
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | ------- Summary: start -------
20:00:14 INFO - PID 12647 | Number of tests: 18
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.01
20:00:14 INFO - PID 12647 | 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
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#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
20:00:14 INFO - PID 12647 | 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
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.74 Median:1.75 stddev:0.02 (1.1%) stddev-sans-first:0.02
20:00:14 INFO - PID 12647 | Values: 1.7 1.8 1.7 1.7 1.8 1.7 1.7 1.8 1.8 1.7 1.7 1.8
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.73 Median:1.74 stddev:0.02 (1.1%) stddev-sans-first:0.02
20:00:14 INFO - PID 12647 | 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
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.05 Median:4.09 stddev:0.12 (2.9%) stddev-sans-first:0.12
20:00:14 INFO - PID 12647 | Values: 4.0 4.1 4.1 3.8 4.1 4.2 4.1 4.2 4.0 4.1 3.8 4.1
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.05 Median:4.10 stddev:0.11 (2.8%) stddev-sans-first:0.11
20:00:14 INFO - PID 12647 | Values: 4.2 3.8 4.1 4.0 4.1 4.1 3.9 4.1 4.0 4.1 4.2 4.1
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.48 Median:3.48 stddev:0.07 (2.1%) stddev-sans-first:0.06
20:00:14 INFO - PID 12647 | Values: 3.6 3.5 3.6 3.5 3.4 3.5 3.4 3.5 3.4 3.5 3.4 3.4
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.42 Median:3.43 stddev:0.07 (1.9%) stddev-sans-first:0.05
20:00:14 INFO - PID 12647 | Values: 3.6 3.5 3.5 3.4 3.4 3.5 3.4 3.4 3.4 3.4 3.4 3.4
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.63 Median:5.69 stddev:0.17 (2.9%) stddev-sans-first:0.06
20:00:14 INFO - PID 12647 | Values: 5.1 5.6 5.7 5.7 5.7 5.7 5.7 5.7 5.7 5.6 5.6 5.7
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.49 Median:5.50 stddev:0.09 (1.6%) stddev-sans-first:0.09
20:00:14 INFO - PID 12647 | Values: 5.4 5.5 5.6 5.4 5.4 5.3 5.6 5.5 5.5 5.5 5.5 5.6
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.12 Median:10.47 stddev:0.31 (2.9%) stddev-sans-first:0.31
20:00:14 INFO - PID 12647 | Values: 10.4 10.4 10.2 9.8 10.4 9.6 10.0 9.9 10.2 10.2 10.5 9.7
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.75 Median:9.60 stddev:0.27 (2.8%) stddev-sans-first:0.27
20:00:14 INFO - PID 12647 | Values: 9.6 9.3 10.0 9.7 9.8 9.4 9.7 10.1 10.1 9.6 9.9 10.1
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.00 Median:10.99 stddev:0.11 (1.0%) stddev-sans-first:0.11
20:00:14 INFO - PID 12647 | Values: 10.9 11.0 10.9 11.0 11.0 11.0 10.8 11.2 11.2 10.9 11.0 11.0
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.52 Median:10.55 stddev:0.11 (1.0%) stddev-sans-first:0.11
20:00:14 INFO - PID 12647 | Values: 10.5 10.6 10.5 10.5 10.6 10.5 10.4 10.6 10.7 10.5 10.3 10.6
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.47 Median:12.48 stddev:0.12 (0.9%) stddev-sans-first:0.09
20:00:14 INFO - PID 12647 | Values: 12.7 12.4 12.6 12.5 12.5 12.5 12.5 12.5 12.4 12.2 12.5 12.3
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.96 Median:12.02 stddev:0.23 (1.9%) stddev-sans-first:0.24
20:00:14 INFO - PID 12647 | Values: 12.0 11.8 11.6 12.3 12.1 12.1 11.9 12.1 11.8 11.8 12.3 11.9
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.57 Median:11.61 stddev:0.15 (1.3%) stddev-sans-first:0.15
20:00:14 INFO - PID 12647 | Values: 11.4 11.4 11.7 11.9 11.7 11.5 11.4 11.6 11.8 11.5 11.5 11.6
20:00:14 INFO - PID 12647 |
20:00:14 INFO - PID 12647 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.02 Median:11.02 stddev:0.15 (1.3%) stddev-sans-first:0.15
20:00:14 INFO - PID 12647 | Values: 10.9 11.1 10.9 10.9 11.1 11.2 11.0 11.1 11.0 10.9 10.8 11.3
20:00:14 INFO - PID 12647 | -------- Summary: end --------
20:00:14 INFO - PID 12647 |
20:00:14 INFO - TEST-INFO | 12647: exit 0
20:00:14 INFO - TEST-OK | basic_compositor_video | took 560919ms
20:00:14 INFO - TEST-START | glvideo
20:00:14 INFO - Initialising browser for glvideo test...
20:00:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49308/getInfo.html -profile /tmp/tmpw15Y70/profile
20:00:14 INFO - TEST-INFO | started process 13881 (/builds/slave/test/build/application/firefox/firefox http://localhost:49308/getInfo.html)
20:00:19 INFO - TEST-INFO | 13881: exit 0
20:00:20 INFO - Browser initialized.
20:00:20 INFO - Running cycle 1/1 for glvideo test...
20:00:20 INFO - Using env: {'DISPLAY': ':0',
20:00:20 INFO - 'HOME': '/home/cltbld',
20:00:20 INFO - 'JSGC_DISABLE_POISONING': '1',
20:00:20 INFO - 'LANG': 'en_US.UTF-8',
20:00:20 INFO - 'LANGUAGE': 'en_US:en',
20:00:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
20:00:20 INFO - 'LOGNAME': 'cltbld',
20:00:20 INFO - 'MAIL': '/var/mail/cltbld',
20:00:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
20:00:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
20:00:20 INFO - 'MOZ_CRASHREPORTER': '1',
20:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:00:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
20:00:20 INFO - 'MOZ_NO_REMOTE': '1',
20:00:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
20:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:00:20 INFO - 'NO_EM_RESTART': '1',
20:00:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:00:20 INFO - 'PWD': '/builds/slave/test',
20:00:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
20:00:20 INFO - 'RUST_BACKTRACE': 'full',
20:00:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
20:00:20 INFO - 'SHELL': '/bin/bash',
20:00:20 INFO - 'SHLVL': '1',
20:00:20 INFO - 'TERM': 'linux',
20:00:20 INFO - 'TMOUT': '86400',
20:00:20 INFO - 'USER': 'cltbld',
20:00:20 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
20:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:00:20 INFO - '_': '/tools/buildbot/bin/python'}
20:00:20 INFO - TEST-INFO | started process 14153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw15Y70/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
20:00:20 INFO - PID 14153 |
20:00:20 INFO - PID 14153 | (/builds/slave/test/build/application/firefox/firefox:14202): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
20:00:20 INFO - PID 14153 |
20:00:22 INFO - PID 14153 | [talos glvideo result] Mean tick time across 100 ticks: 7.048100000000001 ms
20:00:22 INFO - PID 14153 | Cycle 1(1): loaded http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html)
20:00:23 INFO - PID 14153 | [talos glvideo result] Mean tick time across 100 ticks: 6.515100000000001 ms
20:00:23 INFO - PID 14153 | Cycle 1(2): loaded http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html)
20:00:24 INFO - PID 14153 | [talos glvideo result] Mean tick time across 100 ticks: 6.55045 ms
20:00:24 INFO - PID 14153 | Cycle 1(3): loaded http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html)
20:00:26 INFO - PID 14153 | [talos glvideo result] Mean tick time across 100 ticks: 6.5306 ms
20:00:26 INFO - PID 14153 | Cycle 1(4): loaded http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html)
20:00:27 INFO - PID 14153 | [talos glvideo result] Mean tick time across 100 ticks: 6.6084 ms
20:00:27 INFO - PID 14153 | Cycle 1(5): loaded http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49308/tests/webgl/benchmarks/video/video_upload.html)
20:00:27 INFO - PID 14153 | __start_tp_report
20:00:27 INFO - PID 14153 | _x_x_mozilla_page_load
20:00:27 INFO - PID 14153 | _x_x_mozilla_page_load_details
20:00:27 INFO - PID 14153 | |i|pagename|runs|
20:00:27 INFO - PID 14153 | |0;Mean tick time across 100 ticks: ;7.048100000000001;6.515100000000001;6.55045;6.5306;6.6084
20:00:27 INFO - PID 14153 | __end_tp_report
20:00:27 INFO - PID 14153 | __start_cc_report
20:00:27 INFO - PID 14153 | _x_x_mozilla_cycle_collect,266
20:00:27 INFO - PID 14153 | __end_cc_report
20:00:27 INFO - PID 14153 | __startTimestamp1510545627831__endTimestamp
20:00:27 INFO - PID 14153 |
20:00:27 INFO - PID 14153 | ------- Summary: start -------
20:00:27 INFO - PID 14153 | Number of tests: 1
20:00:27 INFO - PID 14153 |
20:00:27 INFO - PID 14153 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.65 Median:6.55 stddev:0.23 (3.4%) stddev-sans-first:0.04
20:00:27 INFO - PID 14153 | Values: 7.0 6.5 6.6 6.5 6.6
20:00:27 INFO - PID 14153 | -------- Summary: end --------
20:00:27 INFO - PID 14153 |
20:00:28 INFO - PID 14153 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
20:00:28 INFO - TEST-INFO | 14153: exit 0
20:00:28 INFO - TEST-OK | glvideo | took 13594ms
20:00:28 INFO - TEST-START | displaylist_mutate
20:00:28 INFO - Initialising browser for displaylist_mutate test...
20:00:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49308/getInfo.html -profile /tmp/tmpjbL5tn/profile
20:00:28 INFO - TEST-INFO | started process 14373 (/builds/slave/test/build/application/firefox/firefox http://localhost:49308/getInfo.html)
20:00:33 INFO - TEST-INFO | 14373: exit 0
20:00:33 INFO - Browser initialized.
20:00:33 INFO - Running cycle 1/1 for displaylist_mutate test...
20:00:33 INFO - Using env: {'DISPLAY': ':0',
20:00:33 INFO - 'HOME': '/home/cltbld',
20:00:33 INFO - 'JSGC_DISABLE_POISONING': '1',
20:00:33 INFO - 'LANG': 'en_US.UTF-8',
20:00:33 INFO - 'LANGUAGE': 'en_US:en',
20:00:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
20:00:33 INFO - 'LOGNAME': 'cltbld',
20:00:33 INFO - 'MAIL': '/var/mail/cltbld',
20:00:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
20:00:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
20:00:33 INFO - 'MOZ_CRASHREPORTER': '1',
20:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:00:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
20:00:33 INFO - 'MOZ_NO_REMOTE': '1',
20:00:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
20:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:00:33 INFO - 'NO_EM_RESTART': '1',
20:00:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:00:33 INFO - 'PWD': '/builds/slave/test',
20:00:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
20:00:33 INFO - 'RUST_BACKTRACE': 'full',
20:00:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
20:00:33 INFO - 'SHELL': '/bin/bash',
20:00:33 INFO - 'SHLVL': '1',
20:00:33 INFO - 'TERM': 'linux',
20:00:33 INFO - 'TMOUT': '86400',
20:00:33 INFO - 'USER': 'cltbld',
20:00:33 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279',
20:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:00:33 INFO - '_': '/tools/buildbot/bin/python'}
20:00:33 INFO - TEST-INFO | started process 14649 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjbL5tn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
20:00:34 INFO - PID 14649 |
20:00:34 INFO - PID 14649 | (/builds/slave/test/build/application/firefox/firefox:14698): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
20:00:34 INFO - PID 14649 |
20:00:43 INFO - PID 14649 | Cycle 1(1): loaded http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html)
20:00:52 INFO - PID 14649 | Cycle 1(2): loaded http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html)
20:01:00 INFO - PID 14649 | Cycle 1(3): loaded http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html)
20:01:09 INFO - PID 14649 | Cycle 1(4): loaded http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html)
20:01:18 INFO - PID 14649 | Cycle 1(5): loaded http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49308/tests/layout/benchmarks/displaylist_mutate.html)
20:01:18 INFO - PID 14649 | __start_tp_report
20:01:18 INFO - PID 14649 | _x_x_mozilla_page_load
20:01:18 INFO - PID 14649 | _x_x_mozilla_page_load_details
20:01:18 INFO - PID 14649 | |i|pagename|runs|
20:01:18 INFO - PID 14649 | |0;/displaylist_mutate.html;8136.41;8095.6900000000005;8173.06;8200.82;8147.495000000001
20:01:18 INFO - PID 14649 | __end_tp_report
20:01:18 INFO - PID 14649 | __start_cc_report
20:01:18 INFO - PID 14649 | _x_x_mozilla_cycle_collect,202
20:01:18 INFO - PID 14649 | __end_cc_report
20:01:18 INFO - PID 14649 | __startTimestamp1510545678987__endTimestamp
20:01:18 INFO - PID 14649 |
20:01:18 INFO - PID 14649 | ------- Summary: start -------
20:01:18 INFO - PID 14649 | Number of tests: 1
20:01:18 INFO - PID 14649 |
20:01:18 INFO - PID 14649 | [#0] /displaylist_mutate.html Cycles:5 Average:8150.69 Median:8147.50 stddev:39.54 (0.5%) stddev-sans-first:44.71
20:01:18 INFO - PID 14649 | Values: 8136.4 8095.7 8173.1 8200.8 8147.5
20:01:18 INFO - PID 14649 | -------- Summary: end --------
20:01:18 INFO - PID 14649 |
20:01:19 INFO - PID 14649 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
20:01:19 INFO - TEST-INFO | 14649: exit 0
20:01:19 INFO - TEST-OK | displaylist_mutate | took 51165ms
20:01:19 INFO - SUITE-END | took 625s
20:01:19 INFO - Completed test suite (00:10:26)
20:01:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.568483894524852, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.68127731092437, 1.6822141255605383, 1.690738028169014, 1.6826584408300616, 1.692208121827411, 1.6779781879194633, 1.6761787709497207, 1.6809411764705882, 1.6779250559284118, 1.679012311135982, 1.681992713004484, 1.6839001122334456], "value": 1.681992713004484, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.66871976647206, 1.6698956594323868, 1.6683194328607174, 1.6708980785296579, 1.6671624999999999, 1.6674875, 1.6672041666666664, 1.6703464106844739, 1.6684070058381981, 1.667275, 1.6668875000000003, 1.6661781848459614], "value": 1.6674875, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.716850686498856, 1.7622166764533174, 1.7361545138888888, 1.7206249999999996, 1.757788517867604, 1.7241379310344827, 1.746824796274738, 1.7590357561547485, 1.7554008191925106, 1.721632243258749, 1.7154459691252142, 1.7628260869565218], "value": 1.746824796274738, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7183247422680414, 1.7476244541484713, 1.7406701479547437, 1.7379105125977419, 1.7232472006890616, 1.6941320914479259, 1.7338864818024262, 1.7485183566433569, 1.7322207792207793, 1.6925211505922169, 1.7029446808510647, 1.7369835069444446], "value": 1.7338864818024262, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.005994659546061, 4.082714285714286, 4.0602029769959405, 3.8373849104859348, 4.093212824010915, 4.184205020920503, 4.08191156462585, 4.185062761506277, 4.032607526881721, 4.1047332421340625, 3.8083185279187814, 4.093396998635745], "value": 4.082714285714286, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.167708333333334, 3.7956166982922217, 4.10828542094456, 4.000360000000001, 4.1425258799171845, 4.0913190184049055, 3.8769573643410857, 4.065376016260164, 4.018293172690764, 4.10782340862423, 4.168260416666666, 4.08231632653061], "value": 4.08231632653061, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.641595873786409, 3.489482558139537, 3.6063461538461534, 3.4734895833333304, 3.4131001137656427, 3.492753201396975, 3.421328392246293, 3.4655138568129313, 3.413265073947668, 3.496678321678321, 3.4370389461626596, 3.4364833906071013], "value": 3.4655138568129313, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5535612788632305, 3.454645941278062, 3.5152636203866456, 3.4013010204081637, 3.3514321608040207, 3.460519031141869, 3.3616638655462223, 3.4200341880341902, 3.373541315345697, 3.4434939759036114, 3.350536013400336, 3.3904576271186424], "value": 3.4013010204081637, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.129282051282047, 5.6289681050656695, 5.6506873822975505, 5.736912045889099, 5.705617870722431, 5.693358633776094, 5.726545801526716, 5.694611005692602, 5.736711281070751, 5.566716141001853, 5.597602611940303, 5.693614800759013], "value": 5.693614800759013, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.437323369565221, 5.451403269754761, 5.603711484593842, 5.407972972972975, 5.391873315363877, 5.333493333333337, 5.604621848739492, 5.527417127071821, 5.48010958904109, 5.510482093663913, 5.4975274725274685, 5.58758379888268], "value": 5.4975274725274685, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.419288194444448, 10.38425605536332, 10.20756802721089, 9.838590163934427, 10.419947916666665, 9.587763578274764, 10.00125, 9.901633663366336, 10.206258503401358, 10.206802721088433, 10.528771929824565, 9.711925566343044], "value": 10.206258503401358, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.617163461538457, 9.303162790697677, 10.000499999999994, 9.662077294685988, 9.757024390243895, 9.38927230046947, 9.662801932367154, 10.105378787878777, 10.051758793969846, 9.574234449760763, 9.85224137931035, 10.051080402010054], "value": 9.757024390243895, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.91, 10.95259124087592, 10.94987226277372, 11.032628676470589, 10.989285714285703, 10.950602189781009, 10.793723021582743, 11.19725746268656, 11.196660447761188, 10.949945255474452, 11.029430147058815, 10.990146520146538], "value": 10.989285714285703, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.47206806282723, 10.586666666666654, 10.527052631578945, 10.474712041884807, 10.582222222222226, 10.526684210526314, 10.42255208333332, 10.582275132275148, 10.69524064171124, 10.474659685863854, 10.259615384615385, 10.58441798941796], "value": 10.527052631578945, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.71209745762712, 12.449917012448134, 12.605651260504189, 12.500416666666661, 12.450082987551841, 12.501083333333343, 12.45170124481328, 12.503520833333308, 12.39787190082646, 12.248346938775516, 12.451493775933631, 12.346090534979417], "value": 12.451493775933631, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.980089820359298, 11.769205882352939, 11.56118497109826, 12.27088957055214, 12.054186746987968, 12.121333333333315, 11.90494047619047, 12.05093373493977, 11.76764705882353, 11.76570588235293, 12.348117283950598, 11.91092261904764], "value": 11.91092261904764, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.406996197718634, 11.409961977186311, 11.677276264591459, 11.862450592885393, 11.677256809338523, 11.540865384615385, 11.40982889733839, 11.585212355212354, 11.767470588235302, 11.454293893129776, 11.453473282442767, 11.631375968992264], "value": 11.585212355212354, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.933087431694014, 11.05215469613261, 10.929672131147527, 10.930300546448063, 11.111305555555575, 11.240000000000007, 10.993049450549453, 11.052044198895018, 10.992499999999971, 10.932622950819663, 10.755940860215032, 11.29957627118645], "value": 10.993049450549453, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.048100000000001, 6.515100000000001, 6.55045, 6.5306, 6.6084], "value": 6.540525, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8136.41, 8095.6900000000005, 8173.06, 8200.82, 8147.495000000001], "value": 8160.2775, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
20:01:20 INFO - Return code: 0
20:01:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
20:01:20 INFO - # TBPL SUCCESS #
20:01:20 INFO - Running post-action listener: _package_coverage_data
20:01:20 INFO - Running post-action listener: _resource_record_post_action
20:01:20 INFO - [mozharness: 2017-11-13 04:01:20.106437Z] Finished run-tests step (success)
20:01:20 INFO - Running post-run listener: _resource_record_post_run
20:01:20 INFO - Total resource usage - Wall time: 642s; CPU: 37.0%; Read bytes: 4096; Write bytes: 296603648; Read time: 20; Write time: 141464
20:01:20 INFO - TinderboxPrint: CPU usage
36.5%
20:01:20 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20
20:01:20 INFO - TinderboxPrint: I/O write bytes / time
296,603,648 / 141,464
20:01:20 INFO - TinderboxPrint: CPU idle
3,199.9 (63.5%)
20:01:20 INFO - TinderboxPrint: CPU system
311.1 (6.2%)
20:01:20 INFO - TinderboxPrint: CPU user
1,512.5 (30.0%)
20:01:20 INFO - TinderboxPrint: Swap in / out
0 / 0
20:01:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 5488640; Read time: 0; Write time: 528
20:01:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
20:01:20 INFO - run-tests - Wall time: 627s; CPU: 37.0%; Read bytes: 4096; Write bytes: 291115008; Read time: 20; Write time: 140936
20:01:20 INFO - Running post-run listener: _upload_blobber_files
20:01:20 INFO - Blob upload gear active.
20:01:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
20:01:20 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
20:01:20 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']
20:01:20 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
20:01:20 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
20:01:20 INFO - SNIMissingWarning
20:01:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
20:01:20 INFO - InsecurePlatformWarning
20:01:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
20:01:20 INFO - SubjectAltNameWarning
20:01:20 INFO - (blobuploader) - INFO - Open directory for files ...
20:01:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
20:01:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:01:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:01:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
20:01:20 INFO - InsecurePlatformWarning
20:01:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
20:01:21 INFO - SubjectAltNameWarning
20:01:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
20:01:21 INFO - InsecurePlatformWarning
20:01:21 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
20:01:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:01:21 INFO - (blobuploader) - INFO - Done attempting.
20:01:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
20:01:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:01:21 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:01:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
20:01:21 INFO - InsecurePlatformWarning
20:01:22 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.)
20:01:22 INFO - SubjectAltNameWarning
20:01:22 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
20:01:22 INFO - InsecurePlatformWarning
20:01:22 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
20:01:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:01:22 INFO - (blobuploader) - INFO - Done attempting.
20:01:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
20:01:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:01:22 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:01:22 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
20:01:22 INFO - InsecurePlatformWarning
20:01:22 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.)
20:01:22 INFO - SubjectAltNameWarning
20:01:23 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
20:01:23 INFO - InsecurePlatformWarning
20:01:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
20:01:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:01:23 INFO - (blobuploader) - INFO - Done attempting.
20:01:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
20:01:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:01:23 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:01:23 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
20:01:23 INFO - InsecurePlatformWarning
20:01:23 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.)
20:01:23 INFO - SubjectAltNameWarning
20:01:28 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
20:01:28 INFO - InsecurePlatformWarning
20:01:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
20:01:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:01:28 INFO - (blobuploader) - INFO - Done attempting.
20:01:28 INFO - (blobuploader) - INFO - Iteration through files over.
20:01:28 INFO - Return code: 0
20:01:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
20:01:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
20:01:28 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1269ec18decb43e7a22f129233d52a3e1491b48bfd1985355f9d8b052fb79a26e7a59124ccaf6a13c784a01ad32a4933f269269a3d617ca878e5dedf3c300934", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3a970f358c39323ff8ea234180950b6bb17b91fc394bded7c998d0c71b315bdc46bb0bc97acd42dd5294cea0714c20089a962979e2ed6fa09a7748aa1912fdee", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a82defc2b747bac62640592c491974e286f143b55b23020979d73469a1c0d63772864fb9ec288b10df98235212cf0a267b588120c9e4b41249939f86f3e387bc", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cb8fe8d1925f055fc34420849fa318fa369245d41cc2489e684aa19fabf2a19f9c31a390da7416f04a1a289dff1cc934dde83fc0a1dcbee336188761d2d3dc0f"}
20:01:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
20:01:28 INFO - Writing to file /builds/slave/test/properties/blobber_files
20:01:28 INFO - Contents:
20:01:28 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1269ec18decb43e7a22f129233d52a3e1491b48bfd1985355f9d8b052fb79a26e7a59124ccaf6a13c784a01ad32a4933f269269a3d617ca878e5dedf3c300934", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3a970f358c39323ff8ea234180950b6bb17b91fc394bded7c998d0c71b315bdc46bb0bc97acd42dd5294cea0714c20089a962979e2ed6fa09a7748aa1912fdee", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a82defc2b747bac62640592c491974e286f143b55b23020979d73469a1c0d63772864fb9ec288b10df98235212cf0a267b588120c9e4b41249939f86f3e387bc", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cb8fe8d1925f055fc34420849fa318fa369245d41cc2489e684aa19fabf2a19f9c31a390da7416f04a1a289dff1cc934dde83fc0a1dcbee336188761d2d3dc0f"}
20:01:28 INFO - Running post-run listener: copy_logs_to_upload_dir
20:01:28 INFO - Copying logs to upload dir...
20:01:28 INFO - mkdir: /builds/slave/test/build/upload/logs
20:01:28 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=705.273507
========= master_lag: 0.14 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 45 secs) (at 2017-11-12 20:01:28.584658) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-12 20:01:28.592131) =========
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=d6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279
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/1269ec18decb43e7a22f129233d52a3e1491b48bfd1985355f9d8b052fb79a26e7a59124ccaf6a13c784a01ad32a4933f269269a3d617ca878e5dedf3c300934", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3a970f358c39323ff8ea234180950b6bb17b91fc394bded7c998d0c71b315bdc46bb0bc97acd42dd5294cea0714c20089a962979e2ed6fa09a7748aa1912fdee", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a82defc2b747bac62640592c491974e286f143b55b23020979d73469a1c0d63772864fb9ec288b10df98235212cf0a267b588120c9e4b41249939f86f3e387bc", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cb8fe8d1925f055fc34420849fa318fa369245d41cc2489e684aa19fabf2a19f9c31a390da7416f04a1a289dff1cc934dde83fc0a1dcbee336188761d2d3dc0f"}
build_url:https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011337
build_url: 'https://queue.taskcluster.net/v1/task/PM6VPIBjQKqwvy8y3JYefQ/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/1269ec18decb43e7a22f129233d52a3e1491b48bfd1985355f9d8b052fb79a26e7a59124ccaf6a13c784a01ad32a4933f269269a3d617ca878e5dedf3c300934", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/3a970f358c39323ff8ea234180950b6bb17b91fc394bded7c998d0c71b315bdc46bb0bc97acd42dd5294cea0714c20089a962979e2ed6fa09a7748aa1912fdee", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a82defc2b747bac62640592c491974e286f143b55b23020979d73469a1c0d63772864fb9ec288b10df98235212cf0a267b588120c9e4b41249939f86f3e387bc", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/cb8fe8d1925f055fc34420849fa318fa369245d41cc2489e684aa19fabf2a19f9c31a390da7416f04a1a289dff1cc934dde83fc0a1dcbee336188761d2d3dc0f"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-12 20:01:28.625311) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 20:01:28.625754) =========
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=d6bbd613908ccac0c6c9cb47000001f7-1510544646.289929-611894279
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004493
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 20:01:28.707955) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-12 20:01:28.708315) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-12 20:01:28.711763) =========
========= Total master_lag: 0.31 =========