builder: mozilla-central_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-074
starttime: 1510397561.7
results: success (0)
revision: 57eb0baf17ce8678767abfbf17bdb0acfd8909ad
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:41.697914) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:41.699051) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:41.699409) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:41.924910) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:41.925166) =========
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=23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-11 02:52:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 35.8M=0s
2017-11-11 02:52:42 (35.8 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.095856
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:42.079143) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:42.082545) =========
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=23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.019971
========= master_lag: 0.05 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:42.154765) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:42.155239) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 57eb0baf17ce8678767abfbf17bdb0acfd8909ad --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 57eb0baf17ce8678767abfbf17bdb0acfd8909ad --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=23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-11 02:52:42,364 truncating revision to first 12 chars
2017-11-11 02:52:42,364 Setting DEBUG logging.
2017-11-11 02:52:42,364 attempt 1/10
2017-11-11 02:52:42,364 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/57eb0baf17ce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-11 02:52:42,793 unpacking tar archive at: mozilla-central-57eb0baf17ce/testing/mozharness/
program finished with exit code 0
elapsedTime=0.960292
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:43.143506) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:43.144035) =========
script_repo_revision: 57eb0baf17ce8678767abfbf17bdb0acfd8909ad
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:43.147690) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:43.148021) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-11 02:52:43.165610) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 51 secs) (at 2017-11-11 02:52:43.166114) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
02:52:43 INFO - MultiFileLogger online at 20171111 02:52:43 in /builds/slave/test
02:52:43 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
02:52:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
02:52:43 INFO - {'append_to_log': False,
02:52:43 INFO - 'base_work_dir': '/builds/slave/test',
02:52:43 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
02:52:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
02:52:43 INFO - 'branch': 'Firefox-Non-PGO',
02:52:43 INFO - 'buildbot_json_path': 'buildprops.json',
02:52:43 INFO - 'code_coverage': False,
02:52:43 INFO - 'config_files': ('talos/linux_config.py',),
02:52:43 INFO - 'default_actions': ('clobber',
02:52:43 INFO - 'read-buildbot-config',
02:52:43 INFO - 'download-and-extract',
02:52:43 INFO - 'populate-webroot',
02:52:43 INFO - 'create-virtualenv',
02:52:43 INFO - 'install',
02:52:43 INFO - 'setup-mitmproxy',
02:52:43 INFO - 'run-tests'),
02:52:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:52:43 INFO - 'disable_ccov_upload': False,
02:52:43 INFO - 'disable_stylo': False,
02:52:43 INFO - 'download_minidump_stackwalk': True,
02:52:43 INFO - 'download_symbols': 'ondemand',
02:52:43 INFO - 'e10s': False,
02:52:43 INFO - 'enable_stylo': False,
02:52:43 INFO - 'enable_webrender': False,
02:52:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
02:52:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:52:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:52:43 INFO - 'gecko_profile': False,
02:52:43 INFO - 'gecko_profile_interval': 0,
02:52:43 INFO - 'installer_path': 'installer.exe',
02:52:43 INFO - 'jsd_code_coverage': False,
02:52:43 INFO - 'log_level': 'info',
02:52:43 INFO - 'log_name': 'talos',
02:52:43 INFO - 'log_to_console': True,
02:52:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
02:52:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
02:52:43 INFO - 'opt_config_files': (),
02:52:43 INFO - 'pip_index': False,
02:52:43 INFO - 'suite': 'g4-e10s',
02:52:43 INFO - 'system_bits': '32',
02:52:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:52:43 INFO - 'title': 'talos-linux64-ix-074',
02:52:43 INFO - 'tooltool_cache': '/builds/tooltool_cache',
02:52:43 INFO - 'use_talos_json': True,
02:52:43 INFO - 'verify': 'False',
02:52:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
02:52:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:52:43 INFO - 'work_dir': 'build'}
02:52:43 INFO - [mozharness: 2017-11-11 10:52:43.506615Z] Running clobber step.
02:52:43 INFO - Running pre-action listener: _resource_record_pre_action
02:52:43 INFO - Running main action method: clobber
02:52:43 INFO - rmtree: /builds/slave/test/build
02:52:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
02:52:44 INFO - Running post-action listener: _resource_record_post_action
02:52:44 INFO - [mozharness: 2017-11-11 10:52:44.603258Z] Finished clobber step (success)
02:52:44 INFO - [mozharness: 2017-11-11 10:52:44.603355Z] Running read-buildbot-config step.
02:52:44 INFO - Running pre-action listener: _resource_record_pre_action
02:52:44 INFO - Running main action method: read_buildbot_config
02:52:44 INFO - Using buildbot properties:
02:52:44 INFO - {
02:52:44 INFO - "project": "",
02:52:44 INFO - "product": "firefox",
02:52:44 INFO - "who": "apavel@mozilla.com",
02:52:44 INFO - "installer_path": "public/build/target.tar.bz2",
02:52:44 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
02:52:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
02:52:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-e10s",
02:52:44 INFO - "stage_platform": "linux64",
02:52:44 INFO - "basedir": "/builds/slave/test",
02:52:44 INFO - "buildnumber": 812,
02:52:44 INFO - "platform": "ubuntu64_hw",
02:52:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
02:52:44 INFO - "slavebuilddir": "test",
02:52:44 INFO - "taskId": "RCnfzLDMTxGtPSH7S1a-2Q",
02:52:44 INFO - "branch": "mozilla-central",
02:52:44 INFO - "script_repo_revision": "production",
02:52:44 INFO - "revision": "57eb0baf17ce8678767abfbf17bdb0acfd8909ad",
02:52:44 INFO - "slavename": "talos-linux64-ix-074",
02:52:44 INFO - "repo_path": "mozilla-central"
02:52:44 INFO - }
02:52:44 INFO - Finding installer, test and symbols from parent task.
02:52:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RCnfzLDMTxGtPSH7S1a-2Q'}, attempt #1
02:52:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RCnfzLDMTxGtPSH7S1a-2Q'}, attempt #1
02:52:45 INFO - Task dependencies: VYKuN5MRTg6oye4xoai1Tw
02:52:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw'}, attempt #1
02:52:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.tar.bz2
02:52:45 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.test_packages.json
02:52:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.crashreporter-symbols.zip
02:52:45 INFO - Running post-action listener: _resource_record_post_action
02:52:45 INFO - [mozharness: 2017-11-11 10:52:45.944793Z] Finished read-buildbot-config step (success)
02:52:45 INFO - [mozharness: 2017-11-11 10:52:45.945012Z] Running download-and-extract step.
02:52:45 INFO - Running pre-action listener: _resource_record_pre_action
02:52:45 INFO - Running main action method: download_and_extract
02:52:45 INFO - mkdir: /builds/slave/test/build/tests
02:52: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')]}
02:52:45 INFO - trying https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.test_packages.json
02:52:45 INFO - Downloading https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
02:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
02:52:46 INFO - Downloaded 989 bytes.
02:52:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
02:52:46 INFO - Using the following test package requirements:
02:52:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
02:52:46 INFO - u'common': [u'target.common.tests.zip'],
02:52:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
02:52:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
02:52:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
02:52:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
02:52:46 INFO - u'mozbase': [u'target.common.tests.zip'],
02:52:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
02:52:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
02:52:46 INFO - u'web-platform': [u'target.common.tests.zip',
02:52:46 INFO - u'target.web-platform.tests.tar.gz'],
02:52:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
02:52:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
02:52:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.common.tests.zip
02:52:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.common.tests.zip'}, attempt #1
02:52:46 INFO - Fetch https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.common.tests.zip into memory
02:52:47 INFO - Content-Length response header: 57772345
02:52:47 INFO - Bytes received: 57772345
02:52:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.talos.tests.zip
02:52:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
02:52:58 INFO - Fetch https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.talos.tests.zip into memory
02:52:59 INFO - Content-Length response header: 18772073
02:52:59 INFO - Bytes received: 18772073
02:52:59 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')]}
02:52:59 INFO - trying https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.tar.bz2
02:52:59 INFO - Downloading https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
02:52:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
02:53:01 INFO - Downloaded 62407507 bytes.
02:53:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.tar.bz2
02:53:01 INFO - mkdir: /builds/slave/test/properties
02:53:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
02:53:01 INFO - Writing to file /builds/slave/test/properties/build_url
02:53:01 INFO - Contents:
02:53:01 INFO - build_url:https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.tar.bz2
02:53:01 INFO - Running post-action listener: _resource_record_post_action
02:53:01 INFO - Running post-action listener: find_tests_for_verification
02:53:01 INFO - Running post-action listener: set_extra_try_arguments
02:53:01 INFO - [mozharness: 2017-11-11 10:53:01.254491Z] Finished download-and-extract step (success)
02:53:01 INFO - [mozharness: 2017-11-11 10:53:01.254697Z] Running populate-webroot step.
02:53:01 INFO - Running pre-action listener: _resource_record_pre_action
02:53:01 INFO - Running main action method: populate_webroot
02:53:01 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
02:53:01 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['tresize',
02:53:01 INFO - 'tcanvasmark']},
02:53:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
02:53:01 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['dromaeo_css',
02:53:01 INFO - 'kraken']},
02:53:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:53:01 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:53:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'tests': ['damp', 'tps']},
02:53:01 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['damp', 'tps']},
02:53:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
02:53:01 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['dromaeo_dom']},
02:53:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
02:53:01 INFO - 'glvideo',
02:53:01 INFO - 'displaylist_mutate']},
02:53:01 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['basic_compositor_video',
02:53:01 INFO - 'glvideo']},
02:53:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
02:53:01 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['ts_paint_webext',
02:53:01 INFO - 'tp5o_webext']},
02:53:01 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
02:53:01 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:53:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:53:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:53:01 INFO - 'talos_options': ['--mitmproxy',
02:53:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:53:01 INFO - '--firstNonBlankPaint'],
02:53:01 INFO - 'tests': ['tp6_google_heavy',
02:53:01 INFO - 'tp6_youtube_heavy',
02:53:01 INFO - 'tp6_amazon_heavy',
02:53:01 INFO - 'tp6_facebook_heavy']},
02:53:01 INFO - 'other-e10s': {'tests': ['a11yr',
02:53:01 INFO - 'ts_paint',
02:53:01 INFO - 'tpaint',
02:53:01 INFO - 'sessionrestore',
02:53:01 INFO - 'sessionrestore_many_windows',
02:53:01 INFO - 'sessionrestore_no_auto_restore',
02:53:01 INFO - 'tabpaint',
02:53:01 INFO - 'cpstartup']},
02:53:01 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['a11yr',
02:53:01 INFO - 'ts_paint',
02:53:01 INFO - 'tpaint',
02:53:01 INFO - 'sessionrestore',
02:53:01 INFO - 'sessionrestore_many_windows',
02:53:01 INFO - 'sessionrestore_no_auto_restore',
02:53:01 INFO - 'tabpaint',
02:53:01 INFO - 'cpstartup']},
02:53:01 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
02:53:01 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
02:53:01 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['perf_reftest_singletons']},
02:53:01 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['perf_reftest']},
02:53:01 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
02:53:01 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['speedometer']},
02:53:01 INFO - 'svgr-e10s': {'tests': ['tsvgx',
02:53:01 INFO - 'tsvgr_opacity',
02:53:01 INFO - 'tart',
02:53:01 INFO - 'tscrollx',
02:53:01 INFO - 'tsvg_static']},
02:53:01 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['tsvgx',
02:53:01 INFO - 'tsvgr_opacity',
02:53:01 INFO - 'tart',
02:53:01 INFO - 'tscrollx',
02:53:01 INFO - 'tsvg_static']},
02:53:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
02:53:01 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'talos_options': ['--disable-stylo'],
02:53:01 INFO - 'tests': ['tp5o']},
02:53:01 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:53:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:53:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:53:01 INFO - 'talos_options': ['--mitmproxy',
02:53:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:53:01 INFO - '--firstNonBlankPaint'],
02:53:01 INFO - 'tests': ['tp6_google',
02:53:01 INFO - 'tp6_youtube',
02:53:01 INFO - 'tp6_amazon',
02:53:01 INFO - 'tp6_facebook']},
02:53:01 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:53:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:53:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:53:01 INFO - 'talos_options': ['--disable-stylo',
02:53:01 INFO - '--mitmproxy',
02:53:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:53:01 INFO - '--firstNonBlankPaint'],
02:53:01 INFO - 'tests': ['tp6_google',
02:53:01 INFO - 'tp6_youtube',
02:53:01 INFO - 'tp6_amazon',
02:53:01 INFO - 'tp6_facebook']},
02:53:01 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:53:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:53:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:53:01 INFO - 'talos_options': ['--stylo-threads=1',
02:53:01 INFO - '--mitmproxy',
02:53:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:53:01 INFO - '--firstNonBlankPaint'],
02:53:01 INFO - 'tests': ['tp6_google',
02:53:01 INFO - 'tp6_youtube',
02:53:01 INFO - 'tp6_amazon',
02:53:01 INFO - 'tp6_facebook']},
02:53:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'talos_options': ['--xperf_path',
02:53:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:53:01 INFO - 'tests': ['tp5n']},
02:53:01 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:53:01 INFO - 'talos_options': ['--disable-stylo',
02:53:01 INFO - '--xperf_path',
02:53:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:53:01 INFO - 'tests': ['tp5n']}}}
02:53:01 INFO - Running post-action listener: _resource_record_post_action
02:53:01 INFO - [mozharness: 2017-11-11 10:53:01.279728Z] Finished populate-webroot step (success)
02:53:01 INFO - [mozharness: 2017-11-11 10:53:01.279822Z] Running create-virtualenv step.
02:53:01 INFO - Running pre-action listener: _resource_record_pre_action
02:53:01 INFO - Running main action method: create_virtualenv
02:53:01 INFO - Creating virtualenv /builds/slave/test/build/venv
02:53:01 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
02:53:01 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
02:53:01 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
02:53:01 INFO - Using real prefix '/usr'
02:53:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python
02:53:01 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
02:53:03 INFO - Installing setuptools, pip, wheel...done.
02:53:03 INFO - Return code: 0
02:53:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
02:53:03 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')]}
02:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53:03 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')]}
02:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53:03 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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53:03 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
02:53:03 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
02:53:03 INFO - Using env: {'DISPLAY': ':0',
02:53:03 INFO - 'HOME': '/home/cltbld',
02:53:03 INFO - 'LANG': 'en_US.UTF-8',
02:53:03 INFO - 'LANGUAGE': 'en_US:en',
02:53:03 INFO - 'LOGNAME': 'cltbld',
02:53:03 INFO - 'MAIL': '/var/mail/cltbld',
02:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:03 INFO - 'MOZ_NO_REMOTE': '1',
02:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:03 INFO - 'NO_EM_RESTART': '1',
02:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:03 INFO - 'PWD': '/builds/slave/test',
02:53:03 INFO - 'SHELL': '/bin/bash',
02:53:03 INFO - 'SHLVL': '1',
02:53:03 INFO - 'TERM': 'linux',
02:53:03 INFO - 'TMOUT': '86400',
02:53:03 INFO - 'USER': 'cltbld',
02:53:03 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:03 INFO - '_': '/tools/buildbot/bin/python'}
02:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
02:53:03 INFO - Return code: 0
02:53:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
02:53:03 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')]}
02:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53:03 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')]}
02:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53:03 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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53:03 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
02:53:03 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
02:53:03 INFO - Using env: {'DISPLAY': ':0',
02:53:03 INFO - 'HOME': '/home/cltbld',
02:53:03 INFO - 'LANG': 'en_US.UTF-8',
02:53:03 INFO - 'LANGUAGE': 'en_US:en',
02:53:03 INFO - 'LOGNAME': 'cltbld',
02:53:03 INFO - 'MAIL': '/var/mail/cltbld',
02:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:03 INFO - 'MOZ_NO_REMOTE': '1',
02:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:03 INFO - 'NO_EM_RESTART': '1',
02:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:03 INFO - 'PWD': '/builds/slave/test',
02:53:03 INFO - 'SHELL': '/bin/bash',
02:53:03 INFO - 'SHLVL': '1',
02:53:03 INFO - 'TERM': 'linux',
02:53:03 INFO - 'TMOUT': '86400',
02:53:03 INFO - 'USER': 'cltbld',
02:53:03 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:03 INFO - '_': '/tools/buildbot/bin/python'}
02:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:04 INFO - Collecting psutil>=3.1.1
02:53:05 INFO - Installing collected packages: psutil
02:53:05 INFO - Successfully installed psutil-3.1.1
02:53:05 INFO - Return code: 0
02:53:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
02:53:05 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')]}
02:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53:05 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')]}
02:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53:05 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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53:05 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
02:53:05 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
02:53:05 INFO - Using env: {'DISPLAY': ':0',
02:53:05 INFO - 'HOME': '/home/cltbld',
02:53:05 INFO - 'LANG': 'en_US.UTF-8',
02:53:05 INFO - 'LANGUAGE': 'en_US:en',
02:53:05 INFO - 'LOGNAME': 'cltbld',
02:53:05 INFO - 'MAIL': '/var/mail/cltbld',
02:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:05 INFO - 'MOZ_NO_REMOTE': '1',
02:53:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:05 INFO - 'NO_EM_RESTART': '1',
02:53:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:05 INFO - 'PWD': '/builds/slave/test',
02:53:05 INFO - 'SHELL': '/bin/bash',
02:53:05 INFO - 'SHLVL': '1',
02:53:05 INFO - 'TERM': 'linux',
02:53:05 INFO - 'TMOUT': '86400',
02:53:05 INFO - 'USER': 'cltbld',
02:53:05 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:05 INFO - '_': '/tools/buildbot/bin/python'}
02:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:05 INFO - Collecting mozsystemmonitor==0.3
02:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
02:53:06 INFO - Installing collected packages: mozsystemmonitor
02:53:06 INFO - Successfully installed mozsystemmonitor-0.3
02:53:07 INFO - Return code: 0
02:53:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
02:53: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')]}
02:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53: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')]}
02:53:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53: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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53: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
02:53: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
02:53:07 INFO - Using env: {'DISPLAY': ':0',
02:53:07 INFO - 'HOME': '/home/cltbld',
02:53:07 INFO - 'LANG': 'en_US.UTF-8',
02:53:07 INFO - 'LANGUAGE': 'en_US:en',
02:53:07 INFO - 'LOGNAME': 'cltbld',
02:53:07 INFO - 'MAIL': '/var/mail/cltbld',
02:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:07 INFO - 'MOZ_NO_REMOTE': '1',
02:53:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:07 INFO - 'NO_EM_RESTART': '1',
02:53:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:07 INFO - 'PWD': '/builds/slave/test',
02:53:07 INFO - 'SHELL': '/bin/bash',
02:53:07 INFO - 'SHLVL': '1',
02:53:07 INFO - 'TERM': 'linux',
02:53:07 INFO - 'TMOUT': '86400',
02:53:07 INFO - 'USER': 'cltbld',
02:53:07 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:07 INFO - '_': '/tools/buildbot/bin/python'}
02:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:07 INFO - Collecting jsonschema==2.5.1
02:53:08 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
02:53:08 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
02:53:09 INFO - Installing collected packages: functools32, jsonschema
02:53:09 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
02:53:09 INFO - Return code: 0
02:53:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
02:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53:09 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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53:09 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
02:53:09 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
02:53:09 INFO - Using env: {'DISPLAY': ':0',
02:53:09 INFO - 'HOME': '/home/cltbld',
02:53:09 INFO - 'LANG': 'en_US.UTF-8',
02:53:09 INFO - 'LANGUAGE': 'en_US:en',
02:53:09 INFO - 'LOGNAME': 'cltbld',
02:53:09 INFO - 'MAIL': '/var/mail/cltbld',
02:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:09 INFO - 'MOZ_NO_REMOTE': '1',
02:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:09 INFO - 'NO_EM_RESTART': '1',
02:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:09 INFO - 'PWD': '/builds/slave/test',
02:53:09 INFO - 'SHELL': '/bin/bash',
02:53:09 INFO - 'SHLVL': '1',
02:53:09 INFO - 'TERM': 'linux',
02:53:09 INFO - 'TMOUT': '86400',
02:53:09 INFO - 'USER': 'cltbld',
02:53:09 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:09 INFO - '_': '/tools/buildbot/bin/python'}
02:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
02:53:10 INFO - Return code: 0
02:53:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
02:53: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')]}
02:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53: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')]}
02:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53: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', '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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53: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', 'blobuploader==1.2.4'] in /builds/slave/test/build
02:53: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 blobuploader==1.2.4
02:53:10 INFO - Using env: {'DISPLAY': ':0',
02:53:10 INFO - 'HOME': '/home/cltbld',
02:53:10 INFO - 'LANG': 'en_US.UTF-8',
02:53:10 INFO - 'LANGUAGE': 'en_US:en',
02:53:10 INFO - 'LOGNAME': 'cltbld',
02:53:10 INFO - 'MAIL': '/var/mail/cltbld',
02:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:10 INFO - 'MOZ_NO_REMOTE': '1',
02:53:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:10 INFO - 'NO_EM_RESTART': '1',
02:53:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:10 INFO - 'PWD': '/builds/slave/test',
02:53:10 INFO - 'SHELL': '/bin/bash',
02:53:10 INFO - 'SHLVL': '1',
02:53:10 INFO - 'TERM': 'linux',
02:53:10 INFO - 'TMOUT': '86400',
02:53:10 INFO - 'USER': 'cltbld',
02:53:10 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:10 INFO - '_': '/tools/buildbot/bin/python'}
02:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:10 INFO - Collecting blobuploader==1.2.4
02:53:11 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
02:53:12 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
02:53:13 INFO - Installing collected packages: requests, docopt, blobuploader
02:53:13 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
02:53:13 INFO - Return code: 0
02:53:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:53:13 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')]}
02:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53:13 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')]}
02:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53:13 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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53:13 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
02:53:13 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
02:53:13 INFO - Using env: {'DISPLAY': ':0',
02:53:13 INFO - 'HOME': '/home/cltbld',
02:53:13 INFO - 'LANG': 'en_US.UTF-8',
02:53:13 INFO - 'LANGUAGE': 'en_US:en',
02:53:13 INFO - 'LOGNAME': 'cltbld',
02:53:13 INFO - 'MAIL': '/var/mail/cltbld',
02:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:13 INFO - 'MOZ_NO_REMOTE': '1',
02:53:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:13 INFO - 'NO_EM_RESTART': '1',
02:53:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:13 INFO - 'PWD': '/builds/slave/test',
02:53:13 INFO - 'SHELL': '/bin/bash',
02:53:13 INFO - 'SHLVL': '1',
02:53:13 INFO - 'TERM': 'linux',
02:53:13 INFO - 'TMOUT': '86400',
02:53:13 INFO - 'USER': 'cltbld',
02:53:13 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:13 INFO - '_': '/tools/buildbot/bin/python'}
02:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:14 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:53:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:53:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:53:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:53:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:53:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:53:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:53:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:53:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:53:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:53:16 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:53:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:53:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:53:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:53:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:53:17 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:53:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:53:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:53:17 INFO - Running setup.py install for manifestparser: started
02:53:17 INFO - Running setup.py install for manifestparser: finished with status 'done'
02:53:17 INFO - Running setup.py install for mozcrash: started
02:53:18 INFO - Running setup.py install for mozcrash: finished with status 'done'
02:53:18 INFO - Running setup.py install for mozdebug: started
02:53:18 INFO - Running setup.py install for mozdebug: finished with status 'done'
02:53:18 INFO - Running setup.py install for mozdevice: started
02:53:18 INFO - Running setup.py install for mozdevice: finished with status 'done'
02:53:18 INFO - Running setup.py install for mozfile: started
02:53:18 INFO - Running setup.py install for mozfile: finished with status 'done'
02:53:18 INFO - Running setup.py install for mozhttpd: started
02:53:18 INFO - Running setup.py install for mozhttpd: finished with status 'done'
02:53:18 INFO - Running setup.py install for mozinfo: started
02:53:19 INFO - Running setup.py install for mozinfo: finished with status 'done'
02:53:19 INFO - Running setup.py install for mozInstall: started
02:53:19 INFO - Running setup.py install for mozInstall: finished with status 'done'
02:53:19 INFO - Running setup.py install for mozleak: started
02:53:19 INFO - Running setup.py install for mozleak: finished with status 'done'
02:53:19 INFO - Running setup.py install for mozlog: started
02:53:19 INFO - Running setup.py install for mozlog: finished with status 'done'
02:53:19 INFO - Running setup.py install for moznetwork: started
02:53:20 INFO - Running setup.py install for moznetwork: finished with status 'done'
02:53:20 INFO - Running setup.py install for mozprocess: started
02:53:20 INFO - Running setup.py install for mozprocess: finished with status 'done'
02:53:20 INFO - Running setup.py install for mozprofile: started
02:53:20 INFO - Running setup.py install for mozprofile: finished with status 'done'
02:53:20 INFO - Running setup.py install for mozrunner: started
02:53:20 INFO - Running setup.py install for mozrunner: finished with status 'done'
02:53:20 INFO - Running setup.py install for mozscreenshot: started
02:53:20 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
02:53:20 INFO - Running setup.py install for moztest: started
02:53:21 INFO - Running setup.py install for moztest: finished with status 'done'
02:53:21 INFO - Running setup.py install for mozversion: started
02:53:21 INFO - Running setup.py install for mozversion: finished with status 'done'
02:53:21 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
02:53:21 INFO - Return code: 0
02:53:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:53:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:53:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53:21 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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53:21 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
02:53:21 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
02:53:21 INFO - Using env: {'DISPLAY': ':0',
02:53:21 INFO - 'HOME': '/home/cltbld',
02:53:21 INFO - 'LANG': 'en_US.UTF-8',
02:53:21 INFO - 'LANGUAGE': 'en_US:en',
02:53:21 INFO - 'LOGNAME': 'cltbld',
02:53:21 INFO - 'MAIL': '/var/mail/cltbld',
02:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:21 INFO - 'MOZ_NO_REMOTE': '1',
02:53:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:21 INFO - 'NO_EM_RESTART': '1',
02:53:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:21 INFO - 'PWD': '/builds/slave/test',
02:53:21 INFO - 'SHELL': '/bin/bash',
02:53:21 INFO - 'SHLVL': '1',
02:53:21 INFO - 'TERM': 'linux',
02:53:21 INFO - 'TMOUT': '86400',
02:53:21 INFO - 'USER': 'cltbld',
02:53:21 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:21 INFO - '_': '/tools/buildbot/bin/python'}
02:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:22 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:53:22 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))
02:53:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:53:22 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))
02:53:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:53:22 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))
02:53:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:53:22 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))
02:53:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:53:23 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))
02:53:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:53:23 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))
02:53:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:53:23 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))
02:53:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:53:23 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))
02:53:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:53:23 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))
02:53:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:53:24 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))
02:53:24 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:53:24 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))
02:53:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:53:24 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))
02:53:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:53:24 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))
02:53:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:53:25 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))
02:53:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:53:25 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))
02:53:25 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:53:25 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))
02:53:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:53:25 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))
02:53:25 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
02:53:26 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
02:53:26 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))
02:53:26 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))
02:53:26 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))
02:53:26 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))
02:53:26 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))
02:53:26 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))
02:53:26 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:53:27 INFO - Installing collected packages: six, blessings
02:53:27 INFO - Successfully installed blessings-1.6 six-1.10.0
02:53:27 INFO - Return code: 0
02:53:27 INFO - Done creating virtualenv /builds/slave/test/build/venv.
02:53:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:53:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:53:28 INFO - Reading from file tmpfile_stdout
02:53:28 INFO - Reading from file tmpfile_stderr
02:53:28 INFO - Current package versions:
02:53:28 INFO - blessings == 1.6
02:53:28 INFO - blobuploader == 1.2.4
02:53:28 INFO - docopt == 0.6.1
02:53:28 INFO - functools32 == 3.2.3.post2
02:53:28 INFO - jsonschema == 2.5.1
02:53:28 INFO - manifestparser == 1.2
02:53:28 INFO - mozInstall == 1.14
02:53:28 INFO - mozcrash == 1.0
02:53:28 INFO - mozdebug == 0.1
02:53:28 INFO - mozdevice == 0.51
02:53:28 INFO - mozfile == 1.2
02:53:28 INFO - mozhttpd == 0.7
02:53:28 INFO - mozinfo == 0.10
02:53:28 INFO - mozleak == 0.1
02:53:28 INFO - mozlog == 3.5
02:53:28 INFO - moznetwork == 0.27
02:53:28 INFO - mozprocess == 0.25
02:53:28 INFO - mozprofile == 0.29
02:53:28 INFO - mozrunner == 6.13
02:53:28 INFO - mozscreenshot == 0.1
02:53:28 INFO - mozsystemmonitor == 0.3
02:53:28 INFO - moztest == 0.8
02:53:28 INFO - mozversion == 1.4
02:53:28 INFO - psutil == 3.1.1
02:53:28 INFO - requests == 1.2.3
02:53:28 INFO - six == 1.10.0
02:53:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:53:28 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')]}
02:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53:28 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')]}
02:53:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53:28 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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53:28 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
02:53:28 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
02:53:28 INFO - Using env: {'DISPLAY': ':0',
02:53:28 INFO - 'HOME': '/home/cltbld',
02:53:28 INFO - 'LANG': 'en_US.UTF-8',
02:53:28 INFO - 'LANGUAGE': 'en_US:en',
02:53:28 INFO - 'LOGNAME': 'cltbld',
02:53:28 INFO - 'MAIL': '/var/mail/cltbld',
02:53:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:28 INFO - 'MOZ_NO_REMOTE': '1',
02:53:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:28 INFO - 'NO_EM_RESTART': '1',
02:53:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:28 INFO - 'PWD': '/builds/slave/test',
02:53:28 INFO - 'SHELL': '/bin/bash',
02:53:28 INFO - 'SHLVL': '1',
02:53:28 INFO - 'TERM': 'linux',
02:53:28 INFO - 'TMOUT': '86400',
02:53:28 INFO - 'USER': 'cltbld',
02:53:28 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:28 INFO - '_': '/tools/buildbot/bin/python'}
02:53:28 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:28 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))
02:53:28 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))
02:53:28 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))
02:53:28 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))
02:53:28 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))
02:53:28 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))
02:53:28 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))
02:53:28 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))
02:53:28 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))
02:53:28 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
02:53:29 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:53:30 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
02:53:30 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))
02:53:30 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))
02:53:30 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))
02:53:30 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))
02:53:31 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
02:53:32 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:53:33 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
02:53:33 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:53:34 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
02:53:34 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:53:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
02:53:35 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
02:53:35 INFO - Found existing installation: requests 1.2.3
02:53:35 INFO - Uninstalling requests-1.2.3:
02:53:35 INFO - Successfully uninstalled requests-1.2.3
02:53:35 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
02:53:35 INFO - Return code: 0
02:53:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
02:53:35 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')]}
02:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:53:35 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')]}
02:53:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:53:35 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 0x1e5d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2035a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21d8930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, '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': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656', '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
02:53:35 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
02:53:35 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
02:53:35 INFO - Using env: {'DISPLAY': ':0',
02:53:35 INFO - 'HOME': '/home/cltbld',
02:53:35 INFO - 'LANG': 'en_US.UTF-8',
02:53:35 INFO - 'LANGUAGE': 'en_US:en',
02:53:35 INFO - 'LOGNAME': 'cltbld',
02:53:35 INFO - 'MAIL': '/var/mail/cltbld',
02:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:35 INFO - 'MOZ_NO_REMOTE': '1',
02:53:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:35 INFO - 'NO_EM_RESTART': '1',
02:53:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:35 INFO - 'PWD': '/builds/slave/test',
02:53:35 INFO - 'SHELL': '/bin/bash',
02:53:35 INFO - 'SHLVL': '1',
02:53:35 INFO - 'TERM': 'linux',
02:53:35 INFO - 'TMOUT': '86400',
02:53:35 INFO - 'USER': 'cltbld',
02:53:35 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:35 INFO - '_': '/tools/buildbot/bin/python'}
02:53:36 INFO - Ignoring indexes: https://pypi.python.org/simple
02:53:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
02:53:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
02:53:36 INFO - Return code: 0
02:53:36 INFO - Running post-action listener: _resource_record_post_action
02:53:36 INFO - Running post-action listener: _start_resource_monitoring
02:53:36 INFO - Starting resource monitoring.
02:53:36 INFO - [mozharness: 2017-11-11 10:53:36.406107Z] Finished create-virtualenv step (success)
02:53:36 INFO - [mozharness: 2017-11-11 10:53:36.406622Z] Running install step.
02:53:36 INFO - Running pre-action listener: _resource_record_pre_action
02:53:36 INFO - Running main action method: install
02:53:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:53:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:53:36 INFO - Reading from file tmpfile_stdout
02:53:36 INFO - Reading from file tmpfile_stderr
02:53:36 INFO - Detecting whether we're running mozinstall >=1.0...
02:53:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
02:53:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
02:53:37 INFO - Reading from file tmpfile_stdout
02:53:37 INFO - Output received:
02:53:37 INFO - Usage: mozinstall [options] installer
02:53:37 INFO - Options:
02:53:37 INFO - -h, --help show this help message and exit
02:53:37 INFO - -d DEST, --destination=DEST
02:53:37 INFO - Directory to install application into. [default:
02:53:37 INFO - "/builds/slave/test"]
02:53:37 INFO - --app=APP Application being installed. [default: firefox]
02:53:37 INFO - mkdir: /builds/slave/test/build/application
02:53:37 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']
02:53:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
02:53:55 INFO - Reading from file tmpfile_stdout
02:53:55 INFO - Output received:
02:53:55 INFO - /builds/slave/test/build/application/firefox/firefox
02:53:55 INFO - Running post-action listener: _resource_record_post_action
02:53:55 INFO - [mozharness: 2017-11-11 10:53:55.327399Z] Finished install step (success)
02:53:55 INFO - [mozharness: 2017-11-11 10:53:55.327539Z] Running setup-mitmproxy step.
02:53:55 INFO - Running pre-action listener: _resource_record_pre_action
02:53:55 INFO - Running main action method: setup_mitmproxy
02:53:55 INFO - Skipping: mitmproxy is not required
02:53:55 INFO - Running post-action listener: _resource_record_post_action
02:53:55 INFO - [mozharness: 2017-11-11 10:53:55.328053Z] Finished setup-mitmproxy step (success)
02:53:55 INFO - [mozharness: 2017-11-11 10:53:55.328153Z] Running run-tests step.
02:53:55 INFO - Running pre-action listener: _resource_record_pre_action
02:53:55 INFO - Running pre-action listener: _set_gcov_prefix
02:53:55 INFO - Running main action method: run_tests
02:53:55 WARNING - Try message not found.
02:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
02:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
02:53:55 INFO - Python 2.7.3
02:53:55 INFO - Return code: 0
02:53:55 INFO - grabbing minidump binary from tooltool
02:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:53:55 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 0x21d5ad0>, '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 0x21d69d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21d6e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
02:53:55 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
02:53:55 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
02:53:55 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
02:53:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
02:53:55 INFO - Return code: 0
02:53:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
02:53:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
02:53:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
02:53:55 INFO - ENV: RUST_BACKTRACE is now full
02:53:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
02:53:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
02:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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
02:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-074 --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
02:53:55 INFO - Using env: {'DISPLAY': ':0',
02:53:55 INFO - 'HOME': '/home/cltbld',
02:53:55 INFO - 'LANG': 'en_US.UTF-8',
02:53:55 INFO - 'LANGUAGE': 'en_US:en',
02:53:55 INFO - 'LOGNAME': 'cltbld',
02:53:55 INFO - 'MAIL': '/var/mail/cltbld',
02:53:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:53:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:53:55 INFO - 'MOZ_NO_REMOTE': '1',
02:53:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:53:55 INFO - 'NO_EM_RESTART': '1',
02:53:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:53:55 INFO - 'PWD': '/builds/slave/test',
02:53:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:53:55 INFO - 'RUST_BACKTRACE': 'full',
02:53:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:53:55 INFO - 'SHELL': '/bin/bash',
02:53:55 INFO - 'SHLVL': '1',
02:53:55 INFO - 'TERM': 'linux',
02:53:55 INFO - 'TMOUT': '86400',
02:53:55 INFO - 'USER': 'cltbld',
02:53:55 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:53:55 INFO - '_': '/tools/buildbot/bin/python'}
02:53:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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
02:53:55 INFO - mozversion application_buildid: 20171111095948
02:53:55 INFO - mozversion application_changeset: 57eb0baf17ce8678767abfbf17bdb0acfd8909ad
02:53:55 INFO - mozversion application_display_name: Nightly
02:53:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:53:55 INFO - mozversion application_name: Firefox
02:53:55 INFO - mozversion application_remotingname: firefox
02:53:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
02:53:55 INFO - mozversion application_vendor: Mozilla
02:53:55 INFO - mozversion application_version: 58.0a1
02:53:55 INFO - mozversion platform_buildid: 20171111095948
02:53:55 INFO - mozversion platform_changeset: 57eb0baf17ce8678767abfbf17bdb0acfd8909ad
02:53:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
02:53:55 INFO - mozversion platform_version: 58.0a1
02:53:55 INFO - using testdate: 1510397635
02:53:55 INFO - actual date: 1510397635
02:53:55 INFO - starting webserver on 'localhost:46489'
02:53:55 INFO - SUITE-START | Running 3 tests
02:53:55 INFO - TEST-START | basic_compositor_video
02:53:55 INFO - Initialising browser for basic_compositor_video test...
02:53:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46489/getInfo.html -profile /tmp/tmpTyD3DS/profile
02:53:55 INFO - TEST-INFO | started process 11712 (/builds/slave/test/build/application/firefox/firefox http://localhost:46489/getInfo.html)
02:54:01 INFO - TEST-INFO | 11712: exit 0
02:54:01 INFO - Browser initialized.
02:54:01 INFO - Running cycle 1/1 for basic_compositor_video test...
02:54:01 INFO - Using env: {'DISPLAY': ':0',
02:54:01 INFO - 'HOME': '/home/cltbld',
02:54:01 INFO - 'JSGC_DISABLE_POISONING': '1',
02:54:01 INFO - 'LANG': 'en_US.UTF-8',
02:54:01 INFO - 'LANGUAGE': 'en_US:en',
02:54:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:54:01 INFO - 'LOGNAME': 'cltbld',
02:54:01 INFO - 'MAIL': '/var/mail/cltbld',
02:54:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:54:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:54:01 INFO - 'MOZ_CRASHREPORTER': '1',
02:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:54:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:54:01 INFO - 'MOZ_NO_REMOTE': '1',
02:54:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:54:01 INFO - 'NO_EM_RESTART': '1',
02:54:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:54:01 INFO - 'PWD': '/builds/slave/test',
02:54:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:54:01 INFO - 'RUST_BACKTRACE': 'full',
02:54:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:54:01 INFO - 'SHELL': '/bin/bash',
02:54:01 INFO - 'SHLVL': '1',
02:54:01 INFO - 'TERM': 'linux',
02:54:01 INFO - 'TMOUT': '86400',
02:54:01 INFO - 'USER': 'cltbld',
02:54:01 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
02:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:54:01 INFO - '_': '/tools/buildbot/bin/python'}
02:54:01 INFO - TEST-INFO | started process 11949 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTyD3DS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
02:54:02 INFO - PID 11949 |
02:54:02 INFO - PID 11949 | (/builds/slave/test/build/application/firefox/firefox:12001): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:54:02 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6887760270118177 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6774308466051975 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.74645227008149 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.725776531492667 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.071417910447762 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.158451143451144 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.51776670574443 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4610380622837362 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.526040515653781 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.699800569800562 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 9.680080645161294 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.618653846153848 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.815551181102354 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.112777777777794 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.715487288135607 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97922155688622 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.675622568093393 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.36778409090911 ms/frame
02:54:48 INFO - PID 11949 |
02:54:48 INFO - PID 11949 | Cycle 1(1): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
02:55:34 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6854466292134833 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6770871751886 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7659387875220718 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7391782608695663 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093403819918144 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.220601265822786 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.672613219094249 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5732232142857163 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.838219844357979 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.449836512261581 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 9.710226537216826 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.854433497536949 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.584285714285722 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.433228571428543 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.660485232067526 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052259036144596 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.81194881889764 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.49643678160918 ms/frame
02:55:34 INFO - PID 11949 |
02:55:34 INFO - PID 11949 | Cycle 1(2): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
02:56:20 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6887844682048394 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.68244322960471 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7373769542559356 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7212994836488817 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.138289655172414 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.96938492063492 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.6909532595325962 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6767279411764697 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.716123809523809 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.465150273224041 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 9.742256493506499 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.050552763819102 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.540942307692323 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.05279005524862 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.933943965517255 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.270950920245404 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 12.003859999999985 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.303192090395486 ms/frame
02:56:20 INFO - PID 11949 |
02:56:20 INFO - PID 11949 | Cycle 1(3): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
02:57:07 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.690332394366197 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6743598326359834 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7403248259860786 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.72800518134715 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.16698611111111 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.283672376873662 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.7085908529048206 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.57939177101968 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.65098870056497 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.683707386363639 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 9.868717105263158 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.26402777777779 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.861877470355733 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.176592178770953 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.821431623931609 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.124545454545473 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.860217391304326 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.30098870056498 ms/frame
02:57:07 INFO - PID 11949 |
02:57:07 INFO - PID 11949 | Cycle 1(4): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
02:57:53 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6880089988751406 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6770159262363786 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.784013079667063 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7705884955752216 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.15595567867036 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.210484210526315 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.593802395209584 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5526820603907634 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.770788461538464 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.668215297450428 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 10.205085034013612 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.21887096774193 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.586370656370653 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.872853260869583 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500791666666677 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.982485029940102 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.858695652173912 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.431457142857129 ms/frame
02:57:53 INFO - PID 11949 |
02:57:53 INFO - PID 11949 | Cycle 1(5): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
02:58:39 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.700274787535411 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6797984886649877 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7797093712930012 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.744520488230165 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093608458390177 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.175782881002089 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.555503554502371 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4618944636678175 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5987313432835855 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.555819444444448 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 10.036722408026755 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.619735576923071 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.67375486381321 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.994780219780235 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.767425531914867 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.770352941176508 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.45213740458015 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.934644808743174 ms/frame
02:58:39 INFO - PID 11949 |
02:58:39 INFO - PID 11949 | Cycle 1(6): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
02:59:25 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.693109480812641 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6909002535925612 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.769077240566038 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7938878923766812 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.238298022598871 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.06734756097561 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.7276335403726697 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6903228782287867 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.769807692307691 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.667436260623228 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 10.13814189189189 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.053844221105528 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.906527777777791 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.992335164835142 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.768595744680832 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053704819277113 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.542596153846143 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.054281767955786 ms/frame
02:59:25 INFO - PID 11949 |
02:59:25 INFO - PID 11949 | Cycle 1(7): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
03:00:11 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6902450704225351 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6811134453781513 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7703569321533923 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7443286835222318 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.285999999999999 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.108121149897332 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.576537544696067 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.51601933216169 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.8033752417794995 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.4954670329670385 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 9.616907051282059 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.09452272727272 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.812677165354339 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.237443820224716 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.50402083333335 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.352407407407386 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.676303501945517 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.112611111111134 ms/frame
03:00:11 INFO - PID 11949 |
03:00:11 INFO - PID 11949 | Cycle 1(8): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
03:00:58 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.685676966292135 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6759170854271357 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7652970588235295 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7140659811482437 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.317870503597122 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.056866125760648 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.779527707808564 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.649589416058398 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.662518867924532 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603907563025203 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 10.276438356164387 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.905940594059405 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.8593675889328 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.051574585635395 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.878562231759664 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.274417177914085 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.861067193675883 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.565173410404633 ms/frame
03:00:58 INFO - PID 11949 |
03:00:58 INFO - PID 11949 | Cycle 1(9): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
03:01:44 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6885481148002253 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6815546218487396 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7494956268221575 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7410748476936473 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.317561151079136 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.082071428571429 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.5887619617224877 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5588790035587192 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.871379647749514 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.636464788732393 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 10.240494880546075 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.857635467980307 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.859624505928856 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.304915254237295 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.555794979079494 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.199512195121958 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.905257936507937 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.43182857142857 ms/frame
03:01:44 INFO - PID 11949 |
03:01:44 INFO - PID 11949 | Cycle 1(10): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
03:02:30 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6855112359550561 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6813655462184873 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7504054842473749 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7518607705779325 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.191354748603352 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.065426829268294 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.69509852216749 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6568738574040176 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.716295238095238 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587304469273746 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 10.491118881118878 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.438349056603775 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.583841698841699 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.992747252747254 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659789029535846 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.906130952380972 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.860039525691686 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.365255681818201 ms/frame
03:02:30 INFO - PID 11949 |
03:02:30 INFO - PID 11949 | Cycle 1(11): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
03:03:16 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6831688166012337 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685654712260223 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7642857142857138 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7363281250000009 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116063100137175 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.09042944785276 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_startup = 3.522095070422536 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4969055944055936 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.749243295019164 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.714757142857135 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_startup = 10.312439862542949 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.389999999999999 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.452366412213728 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.873423913043514 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.606470588235279 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.905505952380953 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.858379446640338 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.303587570621472 ms/frame
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | Cycle 1(12): loaded http://localhost:46489/tests/video/video_playback.html (next: http://localhost:46489/tests/video/video_playback.html)
03:03:16 INFO - PID 11949 | __start_tp_report
03:03:16 INFO - PID 11949 | _x_x_mozilla_page_load
03:03:16 INFO - PID 11949 | _x_x_mozilla_page_load_details
03:03:16 INFO - PID 11949 | |i|pagename|runs|
03:03:16 INFO - PID 11949 | |0;240p.120fps.mp4_scale_1_startup;1.6887760270118177;1.6854466292134833;1.6887844682048394;1.690332394366197;1.6880089988751406;1.700274787535411;1.693109480812641;1.6902450704225351;1.685676966292135;1.6885481148002253;1.6855112359550561;1.6831688166012337
03:03:16 INFO - PID 11949 | |1;240p.120fps.mp4_scale_1_inclip;1.6774308466051975;1.6770871751886;1.68244322960471;1.6743598326359834;1.6770159262363786;1.6797984886649877;1.6909002535925612;1.6811134453781513;1.6759170854271357;1.6815546218487396;1.6813655462184873;1.6685654712260223
03:03:16 INFO - PID 11949 | |2;240p.120fps.mp4_scale_1.1_startup;1.74645227008149;1.7659387875220718;1.7373769542559356;1.7403248259860786;1.784013079667063;1.7797093712930012;1.769077240566038;1.7703569321533923;1.7652970588235295;1.7494956268221575;1.7504054842473749;1.7642857142857138
03:03:16 INFO - PID 11949 | |3;240p.120fps.mp4_scale_1.1_inclip;1.725776531492667;1.7391782608695663;1.7212994836488817;1.72800518134715;1.7705884955752216;1.744520488230165;1.7938878923766812;1.7443286835222318;1.7140659811482437;1.7410748476936473;1.7518607705779325;1.7363281250000009
03:03:16 INFO - PID 11949 | |4;240p.120fps.mp4_scale_2_startup;4.071417910447762;4.093403819918144;4.138289655172414;4.16698611111111;4.15595567867036;4.093608458390177;4.238298022598871;4.285999999999999;4.317870503597122;4.317561151079136;4.191354748603352;4.116063100137175
03:03:16 INFO - PID 11949 | |5;240p.120fps.mp4_scale_2_inclip;4.158451143451144;4.220601265822786;3.96938492063492;4.283672376873662;4.210484210526315;4.175782881002089;4.06734756097561;4.108121149897332;4.056866125760648;4.082071428571429;4.065426829268294;4.09042944785276
03:03:16 INFO - PID 11949 | |6;480p.60fps.webm_scale_1_startup;3.51776670574443;3.672613219094249;3.6909532595325962;3.7085908529048206;3.593802395209584;3.555503554502371;3.7276335403726697;3.576537544696067;3.779527707808564;3.5887619617224877;3.69509852216749;3.522095070422536
03:03:16 INFO - PID 11949 | |7;480p.60fps.webm_scale_1_inclip;3.4610380622837362;3.5732232142857163;3.6767279411764697;3.57939177101968;3.5526820603907634;3.4618944636678175;3.6903228782287867;3.51601933216169;3.649589416058398;3.5588790035587192;3.6568738574040176;3.4969055944055936
03:03:16 INFO - PID 11949 | |8;480p.60fps.webm_scale_1.1_startup;5.526040515653781;5.838219844357979;5.716123809523809;5.65098870056497;5.770788461538464;5.5987313432835855;5.769807692307691;5.8033752417794995;5.662518867924532;5.871379647749514;5.716295238095238;5.749243295019164
03:03:16 INFO - PID 11949 | |9;480p.60fps.webm_scale_1.1_inclip;5.699800569800562;5.449836512261581;5.465150273224041;5.683707386363639;5.668215297450428;5.555819444444448;5.667436260623228;5.4954670329670385;5.603907563025203;5.636464788732393;5.587304469273746;5.714757142857135
03:03:16 INFO - PID 11949 | |10;480p.60fps.webm_scale_2_startup;9.680080645161294;9.710226537216826;9.742256493506499;9.868717105263158;10.205085034013612;10.036722408026755;10.13814189189189;9.616907051282059;10.276438356164387;10.240494880546075;10.491118881118878;10.312439862542949
03:03:16 INFO - PID 11949 | |11;480p.60fps.webm_scale_2_inclip;9.618653846153848;9.854433497536949;10.050552763819102;9.26402777777779;9.21887096774193;9.619735576923071;10.053844221105528;9.09452272727272;9.905940594059405;9.857635467980307;9.438349056603775;9.389999999999999
03:03:16 INFO - PID 11949 | |12;1080p.60fps.mp4_scale_1_startup;11.815551181102354;11.584285714285722;11.540942307692323;11.861877470355733;11.586370656370653;11.67375486381321;11.906527777777791;11.812677165354339;11.8593675889328;11.859624505928856;11.583841698841699;11.452366412213728
03:03:16 INFO - PID 11949 | |13;1080p.60fps.mp4_scale_1_inclip;11.112777777777794;11.433228571428543;11.05279005524862;11.176592178770953;10.872853260869583;10.994780219780235;10.992335164835142;11.237443820224716;11.051574585635395;11.304915254237295;10.992747252747254;10.873423913043514
03:03:16 INFO - PID 11949 | |14;1080p.60fps.mp4_scale_1.1_startup;12.715487288135607;12.660485232067526;12.933943965517255;12.821431623931609;12.500791666666677;12.767425531914867;12.768595744680832;12.50402083333335;12.878562231759664;12.555794979079494;12.659789029535846;12.606470588235279
03:03:16 INFO - PID 11949 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.97922155688622;12.052259036144596;12.270950920245404;12.124545454545473;11.982485029940102;11.770352941176508;12.053704819277113;12.352407407407386;12.274417177914085;12.199512195121958;11.906130952380972;11.905505952380953
03:03:16 INFO - PID 11949 | |16;1080p.60fps.mp4_scale_2_startup;11.675622568093393;11.81194881889764;12.003859999999985;11.860217391304326;11.858695652173912;11.45213740458015;11.542596153846143;11.676303501945517;11.861067193675883;11.905257936507937;11.860039525691686;11.858379446640338
03:03:16 INFO - PID 11949 | |17;1080p.60fps.mp4_scale_2_inclip;11.36778409090911;11.49643678160918;11.303192090395486;11.30098870056498;11.431457142857129;10.934644808743174;11.054281767955786;11.112611111111134;11.565173410404633;11.43182857142857;11.365255681818201;11.303587570621472
03:03:16 INFO - PID 11949 | __end_tp_report
03:03:16 INFO - PID 11949 | __start_cc_report
03:03:16 INFO - PID 11949 | _x_x_mozilla_cycle_collect,568
03:03:16 INFO - PID 11949 | __end_cc_report
03:03:16 INFO - PID 11949 | __startTimestamp1510398196731__endTimestamp
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | ------- Summary: start -------
03:03:16 INFO - PID 11949 | Number of tests: 18
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.69 Median:1.69 stddev:0.00 (0.3%) stddev-sans-first:0.00
03:03:16 INFO - PID 11949 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.68 Median:1.68 stddev:0.01 (0.3%) stddev-sans-first:0.01
03:03:16 INFO - PID 11949 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.76 Median:1.77 stddev:0.02 (0.9%) stddev-sans-first:0.02
03:03:16 INFO - PID 11949 | Values: 1.7 1.8 1.7 1.7 1.8 1.8 1.8 1.8 1.8 1.7 1.8 1.8
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.74 Median:1.74 stddev:0.02 (1.3%) stddev-sans-first:0.02
03:03:16 INFO - PID 11949 | Values: 1.7 1.7 1.7 1.7 1.8 1.7 1.8 1.7 1.7 1.7 1.8 1.7
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.18 Median:4.18 stddev:0.09 (2.1%) stddev-sans-first:0.09
03:03:16 INFO - PID 11949 | Values: 4.1 4.1 4.1 4.2 4.2 4.1 4.2 4.3 4.3 4.3 4.2 4.1
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.12 Median:4.13 stddev:0.09 (2.1%) stddev-sans-first:0.09
03:03:16 INFO - PID 11949 | Values: 4.2 4.2 4.0 4.3 4.2 4.2 4.1 4.1 4.1 4.1 4.1 4.1
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.64 Median:3.68 stddev:0.09 (2.4%) stddev-sans-first:0.08
03:03:16 INFO - PID 11949 | Values: 3.5 3.7 3.7 3.7 3.6 3.6 3.7 3.6 3.8 3.6 3.7 3.5
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.57 Median:3.58 stddev:0.08 (2.3%) stddev-sans-first:0.08
03:03:16 INFO - PID 11949 | Values: 3.5 3.6 3.7 3.6 3.6 3.5 3.7 3.5 3.6 3.6 3.7 3.5
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.72 Median:5.76 stddev:0.10 (1.7%) stddev-sans-first:0.08
03:03:16 INFO - PID 11949 | Values: 5.5 5.8 5.7 5.7 5.8 5.6 5.8 5.8 5.7 5.9 5.7 5.7
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.60 Median:5.65 stddev:0.09 (1.6%) stddev-sans-first:0.09
03:03:16 INFO - PID 11949 | Values: 5.7 5.4 5.5 5.7 5.7 5.6 5.7 5.5 5.6 5.6 5.6 5.7
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.03 Median:10.05 stddev:0.29 (2.9%) stddev-sans-first:0.29
03:03:16 INFO - PID 11949 | Values: 9.7 9.7 9.7 9.9 10.2 10.0 10.1 9.6 10.3 10.2 10.5 10.3
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.61 Median:9.53 stddev:0.33 (3.5%) stddev-sans-first:0.35
03:03:16 INFO - PID 11949 | Values: 9.6 9.9 10.1 9.3 9.2 9.6 10.1 9.1 9.9 9.9 9.4 9.4
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.71 Median:11.81 stddev:0.16 (1.3%) stddev-sans-first:0.16
03:03:16 INFO - PID 11949 | Values: 11.8 11.6 11.5 11.9 11.6 11.7 11.9 11.8 11.9 11.9 11.6 11.5
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:11.09 Median:11.08 stddev:0.17 (1.5%) stddev-sans-first:0.18
03:03:16 INFO - PID 11949 | Values: 11.1 11.4 11.1 11.2 10.9 11.0 11.0 11.2 11.1 11.3 11.0 10.9
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.70 Median:12.74 stddev:0.14 (1.1%) stddev-sans-first:0.15
03:03:16 INFO - PID 11949 | Values: 12.7 12.7 12.9 12.8 12.5 12.8 12.8 12.5 12.9 12.6 12.7 12.6
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.07 Median:12.09 stddev:0.18 (1.5%) stddev-sans-first:0.18
03:03:16 INFO - PID 11949 | Values: 12.0 12.1 12.3 12.1 12.0 11.8 12.1 12.4 12.3 12.2 11.9 11.9
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.78 Median:11.86 stddev:0.16 (1.4%) stddev-sans-first:0.16
03:03:16 INFO - PID 11949 | Values: 11.7 11.8 12.0 11.9 11.9 11.5 11.5 11.7 11.9 11.9 11.9 11.9
03:03:16 INFO - PID 11949 |
03:03:16 INFO - PID 11949 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.31 Median:11.37 stddev:0.19 (1.6%) stddev-sans-first:0.19
03:03:16 INFO - PID 11949 | Values: 11.4 11.5 11.3 11.3 11.4 10.9 11.1 11.1 11.6 11.4 11.4 11.3
03:03:16 INFO - PID 11949 | -------- Summary: end --------
03:03:16 INFO - PID 11949 |
03:03:17 INFO - TEST-INFO | 11949: exit 0
03:03:17 INFO - TEST-OK | basic_compositor_video | took 561889ms
03:03:17 INFO - TEST-START | glvideo
03:03:17 INFO - Initialising browser for glvideo test...
03:03:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46489/getInfo.html -profile /tmp/tmpPMC1yo/profile
03:03:17 INFO - TEST-INFO | started process 13221 (/builds/slave/test/build/application/firefox/firefox http://localhost:46489/getInfo.html)
03:03:23 INFO - TEST-INFO | 13221: exit 0
03:03:23 INFO - Browser initialized.
03:03:23 INFO - Running cycle 1/1 for glvideo test...
03:03:23 INFO - Using env: {'DISPLAY': ':0',
03:03:23 INFO - 'HOME': '/home/cltbld',
03:03:23 INFO - 'JSGC_DISABLE_POISONING': '1',
03:03:23 INFO - 'LANG': 'en_US.UTF-8',
03:03:23 INFO - 'LANGUAGE': 'en_US:en',
03:03:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:03:23 INFO - 'LOGNAME': 'cltbld',
03:03:23 INFO - 'MAIL': '/var/mail/cltbld',
03:03:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:03:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:03:23 INFO - 'MOZ_CRASHREPORTER': '1',
03:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:03:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:03:23 INFO - 'MOZ_NO_REMOTE': '1',
03:03:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:03:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:03:23 INFO - 'NO_EM_RESTART': '1',
03:03:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:03:23 INFO - 'PWD': '/builds/slave/test',
03:03:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:03:23 INFO - 'RUST_BACKTRACE': 'full',
03:03:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:03:23 INFO - 'SHELL': '/bin/bash',
03:03:23 INFO - 'SHLVL': '1',
03:03:23 INFO - 'TERM': 'linux',
03:03:23 INFO - 'TMOUT': '86400',
03:03:23 INFO - 'USER': 'cltbld',
03:03:23 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
03:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:03:23 INFO - '_': '/tools/buildbot/bin/python'}
03:03:23 INFO - TEST-INFO | started process 13496 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPMC1yo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:03:24 INFO - PID 13496 |
03:03:24 INFO - PID 13496 | (/builds/slave/test/build/application/firefox/firefox:13548): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:03:24 INFO - PID 13496 |
03:03:25 INFO - PID 13496 | [talos glvideo result] Mean tick time across 100 ticks: 6.8902 ms
03:03:25 INFO - PID 13496 | Cycle 1(1): loaded http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html)
03:03:27 INFO - PID 13496 | [talos glvideo result] Mean tick time across 100 ticks: 6.6475 ms
03:03:27 INFO - PID 13496 | Cycle 1(2): loaded http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html)
03:03:28 INFO - PID 13496 | [talos glvideo result] Mean tick time across 100 ticks: 6.4859 ms
03:03:28 INFO - PID 13496 | Cycle 1(3): loaded http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html)
03:03:29 INFO - PID 13496 | [talos glvideo result] Mean tick time across 100 ticks: 6.70885 ms
03:03:29 INFO - PID 13496 | Cycle 1(4): loaded http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html)
03:03:31 INFO - PID 13496 | [talos glvideo result] Mean tick time across 100 ticks: 6.62955 ms
03:03:31 INFO - PID 13496 | Cycle 1(5): loaded http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46489/tests/webgl/benchmarks/video/video_upload.html)
03:03:31 INFO - PID 13496 | __start_tp_report
03:03:31 INFO - PID 13496 | _x_x_mozilla_page_load
03:03:31 INFO - PID 13496 | _x_x_mozilla_page_load_details
03:03:31 INFO - PID 13496 | |i|pagename|runs|
03:03:31 INFO - PID 13496 | |0;Mean tick time across 100 ticks: ;6.8902;6.6475;6.4859;6.70885;6.62955
03:03:31 INFO - PID 13496 | __end_tp_report
03:03:31 INFO - PID 13496 | __start_cc_report
03:03:31 INFO - PID 13496 | _x_x_mozilla_cycle_collect,275
03:03:31 INFO - PID 13496 | __end_cc_report
03:03:31 INFO - PID 13496 | __startTimestamp1510398211353__endTimestamp
03:03:31 INFO - PID 13496 |
03:03:31 INFO - PID 13496 | ------- Summary: start -------
03:03:31 INFO - PID 13496 | Number of tests: 1
03:03:31 INFO - PID 13496 |
03:03:31 INFO - PID 13496 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.67 Median:6.65 stddev:0.15 (2.2%) stddev-sans-first:0.09
03:03:31 INFO - PID 13496 | Values: 6.9 6.6 6.5 6.7 6.6
03:03:31 INFO - PID 13496 | -------- Summary: end --------
03:03:31 INFO - PID 13496 |
03:03:31 INFO - PID 13496 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:03:31 INFO - TEST-INFO | 13496: exit 0
03:03:31 INFO - TEST-OK | glvideo | took 14351ms
03:03:31 INFO - TEST-START | displaylist_mutate
03:03:31 INFO - Initialising browser for displaylist_mutate test...
03:03:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46489/getInfo.html -profile /tmp/tmp_LoAIV/profile
03:03:31 INFO - TEST-INFO | started process 13720 (/builds/slave/test/build/application/firefox/firefox http://localhost:46489/getInfo.html)
03:03:37 INFO - TEST-INFO | 13720: exit 0
03:03:37 INFO - Browser initialized.
03:03:37 INFO - Running cycle 1/1 for displaylist_mutate test...
03:03:37 INFO - Using env: {'DISPLAY': ':0',
03:03:37 INFO - 'HOME': '/home/cltbld',
03:03:37 INFO - 'JSGC_DISABLE_POISONING': '1',
03:03:37 INFO - 'LANG': 'en_US.UTF-8',
03:03:37 INFO - 'LANGUAGE': 'en_US:en',
03:03:37 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:03:37 INFO - 'LOGNAME': 'cltbld',
03:03:37 INFO - 'MAIL': '/var/mail/cltbld',
03:03:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:03:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:03:37 INFO - 'MOZ_CRASHREPORTER': '1',
03:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:03:37 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:03:37 INFO - 'MOZ_NO_REMOTE': '1',
03:03:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:03:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:03:37 INFO - 'NO_EM_RESTART': '1',
03:03:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:03:37 INFO - 'PWD': '/builds/slave/test',
03:03:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:03:37 INFO - 'RUST_BACKTRACE': 'full',
03:03:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:03:37 INFO - 'SHELL': '/bin/bash',
03:03:37 INFO - 'SHLVL': '1',
03:03:37 INFO - 'TERM': 'linux',
03:03:37 INFO - 'TMOUT': '86400',
03:03:37 INFO - 'USER': 'cltbld',
03:03:37 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656',
03:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:03:37 INFO - '_': '/tools/buildbot/bin/python'}
03:03:37 INFO - TEST-INFO | started process 13959 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_LoAIV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:03:38 INFO - PID 13959 |
03:03:38 INFO - PID 13959 | (/builds/slave/test/build/application/firefox/firefox:14010): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:03:38 INFO - PID 13959 |
03:03:59 INFO - PID 13959 | Cycle 1(1): loaded http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html)
03:04:20 INFO - PID 13959 | Cycle 1(2): loaded http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html)
03:04:41 INFO - PID 13959 | Cycle 1(3): loaded http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html)
03:05:02 INFO - PID 13959 | Cycle 1(4): loaded http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html)
03:05:24 INFO - PID 13959 | Cycle 1(5): loaded http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46489/tests/layout/benchmarks/displaylist_mutate.html)
03:05:24 INFO - PID 13959 | __start_tp_report
03:05:24 INFO - PID 13959 | _x_x_mozilla_page_load
03:05:24 INFO - PID 13959 | _x_x_mozilla_page_load_details
03:05:24 INFO - PID 13959 | |i|pagename|runs|
03:05:24 INFO - PID 13959 | |0;/displaylist_mutate.html;20006.59;20313.545000000002;20541.19;20417.795;20504.73
03:05:24 INFO - PID 13959 | __end_tp_report
03:05:24 INFO - PID 13959 | __start_cc_report
03:05:24 INFO - PID 13959 | _x_x_mozilla_cycle_collect,225
03:05:24 INFO - PID 13959 | __end_cc_report
03:05:24 INFO - PID 13959 | __startTimestamp1510398324417__endTimestamp
03:05:24 INFO - PID 13959 |
03:05:24 INFO - PID 13959 | ------- Summary: start -------
03:05:24 INFO - PID 13959 | Number of tests: 1
03:05:24 INFO - PID 13959 |
03:05:24 INFO - PID 13959 | [#0] /displaylist_mutate.html Cycles:5 Average:20356.77 Median:20417.79 stddev:214.55 (1.1%) stddev-sans-first:101.39
03:05:24 INFO - PID 13959 | Values: 20006.6 20313.5 20541.2 20417.8 20504.7
03:05:24 INFO - PID 13959 | -------- Summary: end --------
03:05:24 INFO - PID 13959 |
03:05:25 INFO - TEST-INFO | 13959: exit 0
03:05:25 INFO - TEST-OK | displaylist_mutate | took 113230ms
03:05:25 INFO - SUITE-END | took 689s
03:05:25 INFO - Completed test suite (00:11:29)
03:05:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.6778132036679585, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6887760270118177, 1.6854466292134833, 1.6887844682048394, 1.690332394366197, 1.6880089988751406, 1.700274787535411, 1.693109480812641, 1.6902450704225351, 1.685676966292135, 1.6885481148002253, 1.6855112359550561, 1.6831688166012337], "value": 1.6885481148002253, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6774308466051975, 1.6770871751886, 1.68244322960471, 1.6743598326359834, 1.6770159262363786, 1.6797984886649877, 1.6909002535925612, 1.6811134453781513, 1.6759170854271357, 1.6815546218487396, 1.6813655462184873, 1.6685654712260223], "value": 1.6797984886649877, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.74645227008149, 1.7659387875220718, 1.7373769542559356, 1.7403248259860786, 1.784013079667063, 1.7797093712930012, 1.769077240566038, 1.7703569321533923, 1.7652970588235295, 1.7494956268221575, 1.7504054842473749, 1.7642857142857138], "value": 1.7652970588235295, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.725776531492667, 1.7391782608695663, 1.7212994836488817, 1.72800518134715, 1.7705884955752216, 1.744520488230165, 1.7938878923766812, 1.7443286835222318, 1.7140659811482437, 1.7410748476936473, 1.7518607705779325, 1.7363281250000009], "value": 1.7410748476936473, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.071417910447762, 4.093403819918144, 4.138289655172414, 4.16698611111111, 4.15595567867036, 4.093608458390177, 4.238298022598871, 4.285999999999999, 4.317870503597122, 4.317561151079136, 4.191354748603352, 4.116063100137175], "value": 4.16698611111111, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.158451143451144, 4.220601265822786, 3.96938492063492, 4.283672376873662, 4.210484210526315, 4.175782881002089, 4.06734756097561, 4.108121149897332, 4.056866125760648, 4.082071428571429, 4.065426829268294, 4.09042944785276], "value": 4.09042944785276, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.51776670574443, 3.672613219094249, 3.6909532595325962, 3.7085908529048206, 3.593802395209584, 3.555503554502371, 3.7276335403726697, 3.576537544696067, 3.779527707808564, 3.5887619617224877, 3.69509852216749, 3.522095070422536], "value": 3.672613219094249, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4610380622837362, 3.5732232142857163, 3.6767279411764697, 3.57939177101968, 3.5526820603907634, 3.4618944636678175, 3.6903228782287867, 3.51601933216169, 3.649589416058398, 3.5588790035587192, 3.6568738574040176, 3.4969055944055936], "value": 3.5732232142857163, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.526040515653781, 5.838219844357979, 5.716123809523809, 5.65098870056497, 5.770788461538464, 5.5987313432835855, 5.769807692307691, 5.8033752417794995, 5.662518867924532, 5.871379647749514, 5.716295238095238, 5.749243295019164], "value": 5.749243295019164, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.699800569800562, 5.449836512261581, 5.465150273224041, 5.683707386363639, 5.668215297450428, 5.555819444444448, 5.667436260623228, 5.4954670329670385, 5.603907563025203, 5.636464788732393, 5.587304469273746, 5.714757142857135], "value": 5.603907563025203, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.680080645161294, 9.710226537216826, 9.742256493506499, 9.868717105263158, 10.205085034013612, 10.036722408026755, 10.13814189189189, 9.616907051282059, 10.276438356164387, 10.240494880546075, 10.491118881118878, 10.312439862542949], "value": 10.13814189189189, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.618653846153848, 9.854433497536949, 10.050552763819102, 9.26402777777779, 9.21887096774193, 9.619735576923071, 10.053844221105528, 9.09452272727272, 9.905940594059405, 9.857635467980307, 9.438349056603775, 9.389999999999999], "value": 9.619735576923071, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.815551181102354, 11.584285714285722, 11.540942307692323, 11.861877470355733, 11.586370656370653, 11.67375486381321, 11.906527777777791, 11.812677165354339, 11.8593675889328, 11.859624505928856, 11.583841698841699, 11.452366412213728], "value": 11.67375486381321, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.112777777777794, 11.433228571428543, 11.05279005524862, 11.176592178770953, 10.872853260869583, 10.994780219780235, 10.992335164835142, 11.237443820224716, 11.051574585635395, 11.304915254237295, 10.992747252747254, 10.873423913043514], "value": 11.051574585635395, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.715487288135607, 12.660485232067526, 12.933943965517255, 12.821431623931609, 12.500791666666677, 12.767425531914867, 12.768595744680832, 12.50402083333335, 12.878562231759664, 12.555794979079494, 12.659789029535846, 12.606470588235279], "value": 12.660485232067526, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.97922155688622, 12.052259036144596, 12.270950920245404, 12.124545454545473, 11.982485029940102, 11.770352941176508, 12.053704819277113, 12.352407407407386, 12.274417177914085, 12.199512195121958, 11.906130952380972, 11.905505952380953], "value": 12.053704819277113, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.675622568093393, 11.81194881889764, 12.003859999999985, 11.860217391304326, 11.858695652173912, 11.45213740458015, 11.542596153846143, 11.676303501945517, 11.861067193675883, 11.905257936507937, 11.860039525691686, 11.858379446640338], "value": 11.858695652173912, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.36778409090911, 11.49643678160918, 11.303192090395486, 11.30098870056498, 11.431457142857129, 10.934644808743174, 11.054281767955786, 11.112611111111134, 11.565173410404633, 11.43182857142857, 11.365255681818201, 11.303587570621472], "value": 11.303587570621472, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.8902, 6.6475, 6.4859, 6.70885, 6.62955], "value": 6.638525, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [20006.59, 20313.545000000002, 20541.19, 20417.795, 20504.73], "value": 20461.262499999997, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
03:05:25 INFO - Return code: 0
03:05:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:05:25 INFO - # TBPL SUCCESS #
03:05:25 INFO - Running post-action listener: _package_coverage_data
03:05:25 INFO - Running post-action listener: _resource_record_post_action
03:05:25 INFO - [mozharness: 2017-11-11 11:05:25.619165Z] Finished run-tests step (success)
03:05:25 INFO - Running post-run listener: _resource_record_post_run
03:05:25 INFO - Total resource usage - Wall time: 709s; CPU: 35.0%; Read bytes: 4096; Write bytes: 352755712; Read time: 12; Write time: 179368
03:05:25 INFO - TinderboxPrint: CPU usage
35.1%
03:05:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12
03:05:25 INFO - TinderboxPrint: I/O write bytes / time
352,755,712 / 179,368
03:05:25 INFO - TinderboxPrint: CPU idle
3,618.4 (64.9%)
03:05:25 INFO - TinderboxPrint: CPU system
323.0 (5.8%)
03:05:25 INFO - TinderboxPrint: CPU user
1,614.7 (29.0%)
03:05:25 INFO - TinderboxPrint: Swap in / out
0 / 0
03:05:25 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 8052736; Read time: 0; Write time: 724
03:05:25 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:05:25 INFO - run-tests - Wall time: 690s; CPU: 36.0%; Read bytes: 4096; Write bytes: 344702976; Read time: 12; Write time: 178644
03:05:26 INFO - Running post-run listener: _upload_blobber_files
03:05:26 INFO - Blob upload gear active.
03:05:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:05:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
03:05:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
03:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
03:05:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:26 INFO - SNIMissingWarning
03:05:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:26 INFO - InsecurePlatformWarning
03:05:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:05:26 INFO - SubjectAltNameWarning
03:05:26 INFO - (blobuploader) - INFO - Open directory for files ...
03:05:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:05:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:26 INFO - InsecurePlatformWarning
03:05:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:05:26 INFO - SubjectAltNameWarning
03:05:31 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:31 INFO - InsecurePlatformWarning
03:05:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:05:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:05:31 INFO - (blobuploader) - INFO - Done attempting.
03:05:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
03:05:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:05:31 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:05:31 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:31 INFO - InsecurePlatformWarning
03:05:31 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:05:31 INFO - SubjectAltNameWarning
03:05:32 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:32 INFO - InsecurePlatformWarning
03:05:32 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
03:05:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:05:32 INFO - (blobuploader) - INFO - Done attempting.
03:05:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:05:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:05:32 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:05:32 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:32 INFO - InsecurePlatformWarning
03:05:32 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:05:32 INFO - SubjectAltNameWarning
03:05:33 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:33 INFO - InsecurePlatformWarning
03:05:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:05:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:05:33 INFO - (blobuploader) - INFO - Done attempting.
03:05:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
03:05:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:05:33 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:05:33 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:33 INFO - InsecurePlatformWarning
03:05:33 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:05:33 INFO - SubjectAltNameWarning
03:05:34 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:05:34 INFO - InsecurePlatformWarning
03:05:34 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
03:05:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:05:34 INFO - (blobuploader) - INFO - Done attempting.
03:05:34 INFO - (blobuploader) - INFO - Iteration through files over.
03:05:34 INFO - Return code: 0
03:05:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:05:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:05:34 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78ca7925e9b0b256c93936683fe2d601bf8302512590c250301566ba485cb9af1929d655659ffdbcf31934c6dd7a79600294fa424d887b4e6aeb9abeca25560a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5917201488a28700a8478905f257148ef731644f7a4b35be87253685cd72679444105acc6741ec80f1be8a95c7b5ef983f52d22553a7d8c34c128eabb88fe14c", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aba73a5857c20fd232c930ccbf95044b3e5a45fb8c5a9456b644bce5d85dfedb117674f4c2a8989d07a0aedc831b0af50073b0082284b2c03c6bcc12788bdd6f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30e266710bc71b447bab054d8818e4ef43aec4dec5bfa9cf5df8397887a54b375ccd52854c39285c3dcf55ae5382fcddffb5c14f702c63617dcf6598373d2658"}
03:05:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:05:34 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:05:34 INFO - Contents:
03:05:34 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78ca7925e9b0b256c93936683fe2d601bf8302512590c250301566ba485cb9af1929d655659ffdbcf31934c6dd7a79600294fa424d887b4e6aeb9abeca25560a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5917201488a28700a8478905f257148ef731644f7a4b35be87253685cd72679444105acc6741ec80f1be8a95c7b5ef983f52d22553a7d8c34c128eabb88fe14c", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aba73a5857c20fd232c930ccbf95044b3e5a45fb8c5a9456b644bce5d85dfedb117674f4c2a8989d07a0aedc831b0af50073b0082284b2c03c6bcc12788bdd6f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30e266710bc71b447bab054d8818e4ef43aec4dec5bfa9cf5df8397887a54b375ccd52854c39285c3dcf55ae5382fcddffb5c14f702c63617dcf6598373d2658"}
03:05:34 INFO - Running post-run listener: copy_logs_to_upload_dir
03:05:34 INFO - Copying logs to upload dir...
03:05:34 INFO - mkdir: /builds/slave/test/build/upload/logs
03:05:34 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=771.205073
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 51 secs) (at 2017-11-11 03:05:34.420461) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-11 03:05:34.424805) =========
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=23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656
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/Firefox-Non-PGO/sha512/78ca7925e9b0b256c93936683fe2d601bf8302512590c250301566ba485cb9af1929d655659ffdbcf31934c6dd7a79600294fa424d887b4e6aeb9abeca25560a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5917201488a28700a8478905f257148ef731644f7a4b35be87253685cd72679444105acc6741ec80f1be8a95c7b5ef983f52d22553a7d8c34c128eabb88fe14c", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aba73a5857c20fd232c930ccbf95044b3e5a45fb8c5a9456b644bce5d85dfedb117674f4c2a8989d07a0aedc831b0af50073b0082284b2c03c6bcc12788bdd6f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30e266710bc71b447bab054d8818e4ef43aec4dec5bfa9cf5df8397887a54b375ccd52854c39285c3dcf55ae5382fcddffb5c14f702c63617dcf6598373d2658"}
build_url:https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011355
build_url: 'https://queue.taskcluster.net/v1/task/VYKuN5MRTg6oye4xoai1Tw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78ca7925e9b0b256c93936683fe2d601bf8302512590c250301566ba485cb9af1929d655659ffdbcf31934c6dd7a79600294fa424d887b4e6aeb9abeca25560a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5917201488a28700a8478905f257148ef731644f7a4b35be87253685cd72679444105acc6741ec80f1be8a95c7b5ef983f52d22553a7d8c34c128eabb88fe14c", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aba73a5857c20fd232c930ccbf95044b3e5a45fb8c5a9456b644bce5d85dfedb117674f4c2a8989d07a0aedc831b0af50073b0082284b2c03c6bcc12788bdd6f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/30e266710bc71b447bab054d8818e4ef43aec4dec5bfa9cf5df8397887a54b375ccd52854c39285c3dcf55ae5382fcddffb5c14f702c63617dcf6598373d2658"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-11 03:05:34.459186) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 03:05:34.459524) =========
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=23a51e5d4d00092b8466c6c0000001c3-1510391047.779263-1047488656
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004560
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 03:05:34.541220) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-11 03:05:34.543540) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-11 03:05:34.543827) =========
========= Total master_lag: 0.29 =========