builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s
slave: t-yosemite-r7-0130
starttime: 1495429659.73
results: success (0)
revision: 367944041b558ffe29edf64e8c83879a348de0ca
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.729550) =========
master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.730079) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.730381) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.753156) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.753484) =========
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:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dNT7CtzbnB/Render
GIT_SHARE_BASE_DIR=/builds/git-shared
HG_SHARE_BASE_DIR=/builds/hg-shared
HOME=/Users/cltbld
IDLEIZER_DISABLE_SHUTDOWN=true
LOGNAME=cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
PAGER=/bin/cat
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11
PWD=/builds/slave/test
RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg
SHELL=/bin/bash
SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners
TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
--2017-05-21 22:07:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: 'archiver_client.py'
0K .......... . 100% 7.92M=0.001s
2017-05-21 22:07:39 (7.92 MB/s) - 'archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.153634
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.929056) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.929376) =========
rm -rf scripts properties
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dNT7CtzbnB/Render
GIT_SHARE_BASE_DIR=/builds/git-shared
HG_SHARE_BASE_DIR=/builds/hg-shared
HOME=/Users/cltbld
IDLEIZER_DISABLE_SHUTDOWN=true
LOGNAME=cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
PAGER=/bin/cat
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11
PWD=/builds/slave/test
RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg
SHELL=/bin/bash
SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners
TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
program finished with exit code 0
elapsedTime=0.023224
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:39.977709) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 22:07:39.978061) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 367944041b558ffe29edf64e8c83879a348de0ca --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 367944041b558ffe29edf64e8c83879a348de0ca --destination scripts --debug']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dNT7CtzbnB/Render
GIT_SHARE_BASE_DIR=/builds/git-shared
HG_SHARE_BASE_DIR=/builds/hg-shared
HOME=/Users/cltbld
IDLEIZER_DISABLE_SHUTDOWN=true
LOGNAME=cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
PAGER=/bin/cat
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11
PWD=/builds/slave/test
RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg
SHELL=/bin/bash
SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners
TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
2017-05-21 22:07:40,075 truncating revision to first 12 chars
2017-05-21 22:07:40,075 Setting DEBUG logging.
2017-05-21 22:07:40,075 attempt 1/10
2017-05-21 22:07:40,075 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/367944041b55?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-05-21 22:07:41,109 unpacking tar archive at: mozilla-central-367944041b55/testing/mozharness/
program finished with exit code 0
elapsedTime=1.301858
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 22:07:41.302478) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:41.302865) =========
script_repo_revision: 367944041b558ffe29edf64e8c83879a348de0ca
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:41.303316) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:41.303592) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 22:07:41.322255) =========
========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-21 22:07:41.322665) =========
/tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dNT7CtzbnB/Render
GIT_SHARE_BASE_DIR=/builds/git-shared
HG_SHARE_BASE_DIR=/builds/hg-shared
HOME=/Users/cltbld
IDLEIZER_DISABLE_SHUTDOWN=true
LOGNAME=cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
PAGER=/bin/cat
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg
SHELL=/bin/bash
SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners
TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
22:07:41 INFO - MultiFileLogger online at 20170521 22:07:41 in /builds/slave/test
22:07:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
22:07:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
22:07:41 INFO - {'append_to_log': False,
22:07:41 INFO - 'base_work_dir': '/builds/slave/test',
22:07:41 INFO - 'blob_upload_branch': 'Firefox',
22:07:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
22:07:41 INFO - 'branch': 'Firefox',
22:07:41 INFO - 'buildbot_json_path': 'buildprops.json',
22:07:41 INFO - 'config_files': ('talos/mac_config.py',),
22:07:41 INFO - 'default_actions': ('clobber',
22:07:41 INFO - 'read-buildbot-config',
22:07:41 INFO - 'download-and-extract',
22:07:41 INFO - 'populate-webroot',
22:07:41 INFO - 'create-virtualenv',
22:07:41 INFO - 'install',
22:07:41 INFO - 'run-tests'),
22:07:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
22:07:41 INFO - 'download_minidump_stackwalk': True,
22:07:41 INFO - 'download_symbols': 'ondemand',
22:07:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python',
22:07:41 INFO - 'tooltool.py': '/tools/tooltool.py',
22:07:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python',
22:07:41 INFO - '/tools/misc-python/virtualenv.py')},
22:07:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
22:07:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
22:07:41 INFO - 'gecko_profile': False,
22:07:41 INFO - 'gecko_profile_interval': 0,
22:07:41 INFO - 'installer_path': 'installer.exe',
22:07:41 INFO - 'log_level': 'info',
22:07:41 INFO - 'log_name': 'talos',
22:07:41 INFO - 'log_to_console': True,
22:07:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk',
22:07:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest',
22:07:41 INFO - 'opt_config_files': (),
22:07:41 INFO - 'pip_index': False,
22:07:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
22:07:41 INFO - 'cmd': ('bash',
22:07:41 INFO - '-c',
22:07:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
22:07:41 INFO - 'enabled': True,
22:07:41 INFO - 'halt_on_failure': False,
22:07:41 INFO - 'name': 'check_screen_resolution'},),
22:07:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'),
22:07:41 INFO - 'cmd': ('bash',
22:07:41 INFO - '-c',
22:07:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'),
22:07:41 INFO - 'enabled': True,
22:07:41 INFO - 'halt_on_failure': False,
22:07:41 INFO - 'name': 'check_screen_resolution'},),
22:07:41 INFO - 'run_cmd_checks_enabled': True,
22:07:41 INFO - 'suite': 'dromaeojs-e10s',
22:07:41 INFO - 'system_bits': '32',
22:07:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
22:07:41 INFO - 'title': 't-yosemite-r7-0130',
22:07:41 INFO - 'tooltool_cache': '/builds/tooltool_cache',
22:07:41 INFO - 'use_talos_json': True,
22:07:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
22:07:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
22:07:41 INFO - 'work_dir': 'build'}
22:07:41 INFO - [mozharness: 2017-05-22 05:07:41.540465Z] Running clobber step.
22:07:41 INFO - Running pre-action listener: _resource_record_pre_action
22:07:41 INFO - Running main action method: clobber
22:07:41 INFO - rmtree: /builds/slave/test/build
22:07:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
22:07:44 INFO - Running post-action listener: _resource_record_post_action
22:07:44 INFO - [mozharness: 2017-05-22 05:07:44.091134Z] Finished clobber step (success)
22:07:44 INFO - [mozharness: 2017-05-22 05:07:44.091330Z] Running read-buildbot-config step.
22:07:44 INFO - Running pre-action listener: _resource_record_pre_action
22:07:44 INFO - Running main action method: read_buildbot_config
22:07:44 INFO - Using buildbot properties:
22:07:44 INFO - {
22:07:44 INFO - "project": "",
22:07:44 INFO - "product": "firefox",
22:07:44 INFO - "who": "ihsiao@mozilla.com",
22:07:44 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg",
22:07:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s",
22:07:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/",
22:07:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s",
22:07:44 INFO - "stage_platform": "macosx64",
22:07:44 INFO - "basedir": "/builds/slave/test",
22:07:44 INFO - "buildnumber": 61,
22:07:44 INFO - "platform": "yosemite_r7",
22:07:44 INFO - "parent_task_id": "VhaFt28oRZSQJdgmE_hNHQ",
22:07:44 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/",
22:07:44 INFO - "slavebuilddir": "test",
22:07:44 INFO - "taskId": "J0xeyDNjRXmi-BH49i8Tgw",
22:07:44 INFO - "branch": "mozilla-central",
22:07:44 INFO - "script_repo_revision": "production",
22:07:44 INFO - "revision": "367944041b558ffe29edf64e8c83879a348de0ca",
22:07:44 INFO - "slavename": "t-yosemite-r7-0130",
22:07:44 INFO - "repo_path": "mozilla-central"
22:07:44 INFO - }
22:07:44 INFO - Finding installer, test and symbols from parent task.
22:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0xeyDNjRXmi-BH49i8Tgw'}, attempt #1
22:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0xeyDNjRXmi-BH49i8Tgw'}, attempt #1
22:07:45 INFO - Task dependencies: Xz2EESGoR1mDhMcGiHY7kg VhaFt28oRZSQJdgmE_hNHQ
22:07:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ'}, attempt #1
22:07:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
22:07:45 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/test_packages.json
22:07:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/target.crashreporter-symbols.zip
22:07:45 INFO - Running post-action listener: _resource_record_post_action
22:07:45 INFO - [mozharness: 2017-05-22 05:07:45.785161Z] Finished read-buildbot-config step (success)
22:07:45 INFO - [mozharness: 2017-05-22 05:07:45.785249Z] Running download-and-extract step.
22:07:45 INFO - Running pre-action listener: _resource_record_pre_action
22:07:45 INFO - Running main action method: download_and_extract
22:07:45 INFO - mkdir: /builds/slave/test/build/tests
22:07: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')]}
22:07:45 INFO - trying https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json
22:07:45 INFO - Downloading https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json
22:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1
22:07:46 INFO - Downloaded 1325 bytes.
22:07:46 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json
22:07:46 INFO - Using the following test package requirements:
22:07:46 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'],
22:07:46 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'],
22:07:46 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'],
22:07:46 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'],
22:07:46 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'jsshell-mac.zip'],
22:07:46 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'],
22:07:46 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'],
22:07:46 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'],
22:07:46 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'],
22:07:46 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'],
22:07:46 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip',
22:07:46 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']}
22:07:46 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common
22:07:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip
22:07:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1
22:07:46 INFO - Fetch https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory
22:07:48 INFO - Content-Length response header: 57622781
22:07:48 INFO - Bytes received: 57622781
22:08:06 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos
22:08:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip
22:08:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1
22:08:06 INFO - Fetch https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory
22:08:08 INFO - Content-Length response header: 57622781
22:08:08 INFO - Bytes received: 57622781
22:08:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip
22:08:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1
22:08:25 INFO - Fetch https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory
22:08:26 INFO - Content-Length response header: 14410000
22:08:26 INFO - Bytes received: 14410000
22:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:27 INFO - trying https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
22:08:27 INFO - Downloading https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg
22:08:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1
22:08:29 INFO - Downloaded 68063529 bytes.
22:08:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
22:08:29 INFO - mkdir: /builds/slave/test/properties
22:08:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
22:08:29 INFO - Writing to file /builds/slave/test/properties/build_url
22:08:29 INFO - Contents:
22:08:29 INFO - build_url:https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
22:08:29 INFO - Running post-action listener: _resource_record_post_action
22:08:29 INFO - Running post-action listener: set_extra_try_arguments
22:08:29 INFO - [mozharness: 2017-05-22 05:08:29.025238Z] Finished download-and-extract step (success)
22:08:29 INFO - [mozharness: 2017-05-22 05:08:29.025372Z] Running populate-webroot step.
22:08:29 INFO - Running pre-action listener: _resource_record_pre_action
22:08:29 INFO - Running main action method: populate_webroot
22:08:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']},
22:08:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']},
22:08:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome',
22:08:29 INFO - '--tppagecycles',
22:08:29 INFO - '7'],
22:08:29 INFO - 'tests': ['tsvgm']}},
22:08:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['tresize', 'tcanvasmark']},
22:08:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
22:08:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['dromaeo_css', 'kraken']},
22:08:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
22:08:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip',
22:08:29 INFO - 'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
22:08:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
22:08:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
22:08:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip',
22:08:29 INFO - 'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['damp', 'tps']},
22:08:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
22:08:29 INFO - 'tests': ['damp', 'tps']},
22:08:29 INFO - 'g3': {'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['dromaeo_dom']},
22:08:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
22:08:29 INFO - 'g4': {'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']},
22:08:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
22:08:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
22:08:29 INFO - 'tests': ['ts_paint', 'tp5o']},
22:08:29 INFO - 'other': {'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['a11yr',
22:08:29 INFO - 'ts_paint',
22:08:29 INFO - 'tpaint',
22:08:29 INFO - 'sessionrestore',
22:08:29 INFO - 'sessionrestore_no_auto_restore',
22:08:29 INFO - 'tabpaint']},
22:08:29 INFO - 'other-e10s': {'tests': ['a11yr',
22:08:29 INFO - 'ts_paint',
22:08:29 INFO - 'tpaint',
22:08:29 INFO - 'sessionrestore',
22:08:29 INFO - 'sessionrestore_no_auto_restore',
22:08:29 INFO - 'tabpaint']},
22:08:29 INFO - 'other-e10s_l64': {'tests': ['a11yr',
22:08:29 INFO - 'ts_paint',
22:08:29 INFO - 'tpaint',
22:08:29 INFO - 'sessionrestore',
22:08:29 INFO - 'sessionrestore_no_auto_restore',
22:08:29 INFO - 'tabpaint']},
22:08:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr',
22:08:29 INFO - 'ts_paint',
22:08:29 INFO - 'tpaint',
22:08:29 INFO - 'sessionrestore',
22:08:29 INFO - 'sessionrestore_no_auto_restore',
22:08:29 INFO - 'tabpaint']},
22:08:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['a11yr',
22:08:29 INFO - 'ts_paint',
22:08:29 INFO - 'tpaint',
22:08:29 INFO - 'sessionrestore',
22:08:29 INFO - 'sessionrestore_no_auto_restore',
22:08:29 INFO - 'tabpaint']},
22:08:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['a11yr',
22:08:29 INFO - 'ts_paint',
22:08:29 INFO - 'tpaint',
22:08:29 INFO - 'sessionrestore',
22:08:29 INFO - 'sessionrestore_no_auto_restore',
22:08:29 INFO - 'tabpaint']},
22:08:29 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']},
22:08:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic',
22:08:29 INFO - 'bloom_basic_ref']},
22:08:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['tsvgx',
22:08:29 INFO - 'tsvgr_opacity',
22:08:29 INFO - 'tart',
22:08:29 INFO - 'tscrollx',
22:08:29 INFO - 'cart',
22:08:29 INFO - 'tsvg_static']},
22:08:29 INFO - 'svgr-e10s': {'tests': ['tsvgx',
22:08:29 INFO - 'tsvgr_opacity',
22:08:29 INFO - 'tart',
22:08:29 INFO - 'tscrollx',
22:08:29 INFO - 'cart',
22:08:29 INFO - 'tsvg_static']},
22:08:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip',
22:08:29 INFO - 'talos_options': ['--disable-e10s'],
22:08:29 INFO - 'tests': ['tp5o']},
22:08:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
22:08:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip',
22:08:29 INFO - 'talos_options': ['--disable-e10s',
22:08:29 INFO - '--xperf_path',
22:08:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
22:08:29 INFO - 'tests': ['tp5n']},
22:08:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
22:08:29 INFO - 'talos_options': ['--xperf_path',
22:08:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
22:08:29 INFO - 'tests': ['tp5n']}},
22:08:29 INFO - 'talos.zip': {'path': '',
22:08:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
22:08:29 INFO - Running post-action listener: _resource_record_post_action
22:08:29 INFO - [mozharness: 2017-05-22 05:08:29.046262Z] Finished populate-webroot step (success)
22:08:29 INFO - [mozharness: 2017-05-22 05:08:29.046436Z] Running create-virtualenv step.
22:08:29 INFO - Running pre-action listener: _resource_record_pre_action
22:08:29 INFO - Running main action method: create_virtualenv
22:08:29 INFO - Creating virtualenv /builds/slave/test/build/venv
22:08:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build
22:08:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv
22:08:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
22:08:29 INFO - Using real prefix '/tools/python27'
22:08:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python
22:08:29 INFO - Installing distribute.............................................................................................................................................................................................done.
22:08:32 INFO - Installing pip.................done.
22:08:32 INFO - Return code: 0
22:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version']
22:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version
22:08:33 INFO - Reading from file tmpfile_stdout
22:08:33 INFO - Output received:
22:08:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7)
22:08:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
22:08:33 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')]}
22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:33 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')]}
22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:33 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:33 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', 'pip>=1.5'] in /builds/slave/test/build
22:08:33 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 pip>=1.5
22:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:33 INFO - 'HOME': '/Users/cltbld',
22:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:33 INFO - 'LOGNAME': 'cltbld',
22:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:33 INFO - 'MOZ_NO_REMOTE': '1',
22:08:33 INFO - 'NO_EM_RESTART': '1',
22:08:33 INFO - 'PAGER': '/bin/cat',
22:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:33 INFO - 'PWD': '/builds/slave/test',
22:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:33 INFO - 'SHELL': '/bin/bash',
22:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:33 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:33 INFO - 'USER': 'cltbld',
22:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:33 INFO - 'XPC_FLAGS': '0x0',
22:08:33 INFO - 'XPC_SERVICE_NAME': '0',
22:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg
22:08:33 INFO - Cleaning up...
22:08:33 INFO - Return code: 0
22:08:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
22:08:33 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')]}
22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:33 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')]}
22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:33 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', '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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:33 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', 'psutil>=3.1.1'] in /builds/slave/test/build
22:08:33 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 psutil>=3.1.1
22:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:33 INFO - 'HOME': '/Users/cltbld',
22:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:33 INFO - 'LOGNAME': 'cltbld',
22:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:33 INFO - 'MOZ_NO_REMOTE': '1',
22:08:33 INFO - 'NO_EM_RESTART': '1',
22:08:33 INFO - 'PAGER': '/bin/cat',
22:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:33 INFO - 'PWD': '/builds/slave/test',
22:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:33 INFO - 'SHELL': '/bin/bash',
22:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:33 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:33 INFO - 'USER': 'cltbld',
22:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:33 INFO - 'XPC_FLAGS': '0x0',
22:08:33 INFO - 'XPC_SERVICE_NAME': '0',
22:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:33 INFO - Downloading/unpacking psutil>=3.1.1
22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
22:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
22:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
22:08:35 INFO - Installing collected packages: psutil
22:08:35 INFO - Running setup.py install for psutil
22:08:35 INFO - building 'psutil._psutil_osx' extension
22:08:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o
22:08:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o
22:08:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o
22:08:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit
22:08:36 INFO - building 'psutil._psutil_posix' extension
22:08:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o
22:08:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration]
22:08:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr);
22:08:36 INFO - ^
22:08:36 INFO - 1 warning generated.
22:08:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so
22:08:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
22:08:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
22:08:36 INFO - Successfully installed psutil
22:08:36 INFO - Cleaning up...
22:08:36 INFO - Return code: 0
22:08:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
22:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
22:08:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
22:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:37 INFO - 'HOME': '/Users/cltbld',
22:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:37 INFO - 'LOGNAME': 'cltbld',
22:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:37 INFO - 'MOZ_NO_REMOTE': '1',
22:08:37 INFO - 'NO_EM_RESTART': '1',
22:08:37 INFO - 'PAGER': '/bin/cat',
22:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:37 INFO - 'PWD': '/builds/slave/test',
22:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:37 INFO - 'SHELL': '/bin/bash',
22:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:37 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:37 INFO - 'USER': 'cltbld',
22:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:37 INFO - 'XPC_FLAGS': '0x0',
22:08:37 INFO - 'XPC_SERVICE_NAME': '0',
22:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:37 INFO - Downloading/unpacking mozsystemmonitor==0.3
22:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz
22:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
22:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
22:08:38 INFO - Installing collected packages: mozsystemmonitor
22:08:38 INFO - Running setup.py install for mozsystemmonitor
22:08:39 INFO - Successfully installed mozsystemmonitor
22:08:39 INFO - Cleaning up...
22:08:39 INFO - Return code: 0
22:08:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
22:08:39 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')]}
22:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:39 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')]}
22:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:39 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', '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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:39 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', 'jsonschema==2.5.1'] in /builds/slave/test/build
22:08:39 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 jsonschema==2.5.1
22:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:39 INFO - 'HOME': '/Users/cltbld',
22:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:39 INFO - 'LOGNAME': 'cltbld',
22:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:39 INFO - 'MOZ_NO_REMOTE': '1',
22:08:39 INFO - 'NO_EM_RESTART': '1',
22:08:39 INFO - 'PAGER': '/bin/cat',
22:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:39 INFO - 'PWD': '/builds/slave/test',
22:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:39 INFO - 'SHELL': '/bin/bash',
22:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:39 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:39 INFO - 'USER': 'cltbld',
22:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:39 INFO - 'XPC_FLAGS': '0x0',
22:08:39 INFO - 'XPC_SERVICE_NAME': '0',
22:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:39 INFO - Downloading/unpacking jsonschema==2.5.1
22:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
22:08:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
22:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:40 INFO - Downloading functools32-3.2.3-2.tar.gz
22:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32
22:08:41 INFO - warning: no files found matching '*.txt'
22:08:41 INFO - no previously-included directories found matching 'build'
22:08:41 INFO - no previously-included directories found matching 'dist'
22:08:41 INFO - no previously-included directories found matching '.git*'
22:08:41 INFO - Installing collected packages: jsonschema, functools32
22:08:41 INFO - Running setup.py install for functools32
22:08:41 INFO - warning: no files found matching '*.txt'
22:08:41 INFO - no previously-included directories found matching 'build'
22:08:41 INFO - no previously-included directories found matching 'dist'
22:08:41 INFO - no previously-included directories found matching '.git*'
22:08:41 INFO - Successfully installed jsonschema functools32
22:08:41 INFO - Cleaning up...
22:08:41 INFO - Return code: 0
22:08:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
22:08:41 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')]}
22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:41 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')]}
22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:41 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', '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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:41 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', 'functools32==3.2.3-2'] in /builds/slave/test/build
22:08:41 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 functools32==3.2.3-2
22:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:41 INFO - 'HOME': '/Users/cltbld',
22:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:41 INFO - 'LOGNAME': 'cltbld',
22:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:41 INFO - 'MOZ_NO_REMOTE': '1',
22:08:41 INFO - 'NO_EM_RESTART': '1',
22:08:41 INFO - 'PAGER': '/bin/cat',
22:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:41 INFO - 'PWD': '/builds/slave/test',
22:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:41 INFO - 'SHELL': '/bin/bash',
22:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:41 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:41 INFO - 'USER': 'cltbld',
22:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:41 INFO - 'XPC_FLAGS': '0x0',
22:08:41 INFO - 'XPC_SERVICE_NAME': '0',
22:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
22:08:41 INFO - Cleaning up...
22:08:41 INFO - Return code: 0
22:08:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
22:08:41 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')]}
22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:41 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')]}
22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:41 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', '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 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:41 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', 'blobuploader==1.2.4'] in /builds/slave/test/build
22:08:41 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 blobuploader==1.2.4
22:08:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:41 INFO - 'HOME': '/Users/cltbld',
22:08:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:41 INFO - 'LOGNAME': 'cltbld',
22:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:41 INFO - 'MOZ_NO_REMOTE': '1',
22:08:41 INFO - 'NO_EM_RESTART': '1',
22:08:41 INFO - 'PAGER': '/bin/cat',
22:08:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:41 INFO - 'PWD': '/builds/slave/test',
22:08:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:41 INFO - 'SHELL': '/bin/bash',
22:08:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:41 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:41 INFO - 'USER': 'cltbld',
22:08:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:41 INFO - 'XPC_FLAGS': '0x0',
22:08:41 INFO - 'XPC_SERVICE_NAME': '0',
22:08:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:41 INFO - Downloading/unpacking blobuploader==1.2.4
22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:43 INFO - Downloading blobuploader-1.2.4.tar.gz
22:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
22:08:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
22:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
22:08:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
22:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:44 INFO - Downloading docopt-0.6.1.tar.gz
22:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
22:08:44 INFO - Installing collected packages: blobuploader, requests, docopt
22:08:44 INFO - Running setup.py install for blobuploader
22:08:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
22:08:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
22:08:44 INFO - Running setup.py install for requests
22:08:44 INFO - Running setup.py install for docopt
22:08:44 INFO - Successfully installed blobuploader requests docopt
22:08:44 INFO - Cleaning up...
22:08:45 INFO - Return code: 0
22:08:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv
22:08: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')]}
22:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08: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')]}
22:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:45 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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:45 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'] in /builds/slave/test/build/tests/config
22:08:45 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
22:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:45 INFO - 'HOME': '/Users/cltbld',
22:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:45 INFO - 'LOGNAME': 'cltbld',
22:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:45 INFO - 'MOZ_NO_REMOTE': '1',
22:08:45 INFO - 'NO_EM_RESTART': '1',
22:08:45 INFO - 'PAGER': '/bin/cat',
22:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:45 INFO - 'PWD': '/builds/slave/test',
22:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:45 INFO - 'SHELL': '/bin/bash',
22:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:45 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:45 INFO - 'USER': 'cltbld',
22:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:45 INFO - 'XPC_FLAGS': '0x0',
22:08:45 INFO - 'XPC_SERVICE_NAME': '0',
22:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-iAFfMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-rwii9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-s6Fe_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-VBmWVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-8myfRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-alBzGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
22:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
22:08:45 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-Hxy32t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-ehhl6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-AutSIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-pG2CIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-T0mTVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-jFr3iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-onvZYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-62LwQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-3rP8V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-vOItvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
22:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
22:08:46 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-lcdiDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
22:08:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
22:08:47 INFO - Running setup.py install for manifestparser
22:08:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
22:08:47 INFO - Running setup.py install for mozcrash
22:08:47 INFO - Running setup.py install for mozdebug
22:08:47 INFO - Running setup.py install for mozdevice
22:08:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin
22:08:47 INFO - Running setup.py install for mozfile
22:08:47 INFO - Running setup.py install for mozhttpd
22:08:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
22:08:47 INFO - Running setup.py install for mozinfo
22:08:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Running setup.py install for mozInstall
22:08:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Running setup.py install for mozleak
22:08:48 INFO - Running setup.py install for mozlog
22:08:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Running setup.py install for moznetwork
22:08:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Running setup.py install for mozprocess
22:08:48 INFO - Running setup.py install for mozprofile
22:08:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
22:08:48 INFO - Running setup.py install for mozrunner
22:08:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
22:08:49 INFO - Running setup.py install for mozscreenshot
22:08:49 INFO - Running setup.py install for moztest
22:08:49 INFO - Running setup.py install for mozversion
22:08:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
22:08:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
22:08:49 INFO - Cleaning up...
22:08:49 INFO - Return code: 0
22:08:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv
22:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:49 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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:49 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'] in /builds/slave/test/build/tests/config
22:08:49 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
22:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:49 INFO - 'HOME': '/Users/cltbld',
22:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:49 INFO - 'LOGNAME': 'cltbld',
22:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:49 INFO - 'MOZ_NO_REMOTE': '1',
22:08:49 INFO - 'NO_EM_RESTART': '1',
22:08:49 INFO - 'PAGER': '/bin/cat',
22:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:49 INFO - 'PWD': '/builds/slave/test',
22:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:49 INFO - 'SHELL': '/bin/bash',
22:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:49 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:49 INFO - 'USER': 'cltbld',
22:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:49 INFO - 'XPC_FLAGS': '0x0',
22:08:49 INFO - 'XPC_SERVICE_NAME': '0',
22:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
22:08:49 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-UBPMBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
22:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-stHknA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
22:08:50 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-jyiSXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
22:08:50 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-4mdY8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
22:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-xYxzmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
22:08:50 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-c6i5tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
22:08:50 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-CTh3wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
22:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-2v425K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
22:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-NPOWnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
22:08:50 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-cIw9sJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
22:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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))
22:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
22:08:50 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-KBxcRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
22:08:51 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))
22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-Yyqzgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
22:08:51 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))
22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-LJg2W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
22:08:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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))
22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-fC654Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
22:08:51 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))
22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-POMV3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
22:08:51 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))
22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-9JPZdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
22:08:51 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))
22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
22:08:51 INFO - Running setup.py (path:/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/pip-LNvdXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
22:08:51 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))
22:08:51 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))
22:08:51 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))
22:08:51 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))
22:08:51 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
22:08:51 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
22:08:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
22:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
22:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
22:08:53 INFO - Downloading blessings-1.6.tar.gz
22:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
22:08:53 INFO - Installing collected packages: blessings
22:08:53 INFO - Running setup.py install for blessings
22:08:53 INFO - Successfully installed blessings
22:08:53 INFO - Cleaning up...
22:08:53 INFO - Return code: 0
22:08:53 INFO - Done creating virtualenv /builds/slave/test/build/venv.
22:08:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
22:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
22:08:53 INFO - Reading from file tmpfile_stdout
22:08:53 INFO - Current package versions:
22:08:53 INFO - blessings == 1.6
22:08:53 INFO - blobuploader == 1.2.4
22:08:53 INFO - docopt == 0.6.1
22:08:53 INFO - functools32 == 3.2.3-2
22:08:53 INFO - jsonschema == 2.5.1
22:08:53 INFO - manifestparser == 1.1
22:08:53 INFO - mozInstall == 1.12
22:08:53 INFO - mozcrash == 1.0
22:08:53 INFO - mozdebug == 0.1
22:08:53 INFO - mozdevice == 0.50
22:08:53 INFO - mozfile == 1.2
22:08:53 INFO - mozhttpd == 0.7
22:08:53 INFO - mozinfo == 0.9
22:08:53 INFO - mozleak == 0.1
22:08:53 INFO - mozlog == 3.4
22:08:53 INFO - moznetwork == 0.27
22:08:53 INFO - mozprocess == 0.25
22:08:53 INFO - mozprofile == 0.28
22:08:53 INFO - mozrunner == 6.13
22:08:53 INFO - mozscreenshot == 0.1
22:08:53 INFO - mozsystemmonitor == 0.3
22:08:53 INFO - moztest == 0.8
22:08:53 INFO - mozversion == 1.4
22:08:53 INFO - psutil == 3.1.1
22:08:53 INFO - requests == 1.2.3
22:08:53 INFO - wsgiref == 0.1.2
22:08:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv
22:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:53 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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:53 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'] in /builds/slave/test/build/tests/talos
22:08:53 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
22:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:53 INFO - 'HOME': '/Users/cltbld',
22:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:53 INFO - 'LOGNAME': 'cltbld',
22:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:53 INFO - 'MOZ_NO_REMOTE': '1',
22:08:53 INFO - 'NO_EM_RESTART': '1',
22:08:53 INFO - 'PAGER': '/bin/cat',
22:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:53 INFO - 'PWD': '/builds/slave/test',
22:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:53 INFO - 'SHELL': '/bin/bash',
22:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:53 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:53 INFO - 'USER': 'cltbld',
22:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:53 INFO - 'XPC_FLAGS': '0x0',
22:08:53 INFO - 'XPC_SERVICE_NAME': '0',
22:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:53 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))
22:08:53 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))
22:08:53 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))
22:08:53 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))
22:08:53 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))
22:08:53 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))
22:08:53 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))
22:08:53 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))
22:08:53 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))
22:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
22:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
22:08:53 INFO - Cleaning up...
22:08:53 INFO - Return code: 0
22:08:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
22:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
22:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
22:08:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108f11768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2bad84990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1
22:08:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build
22:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
22:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:08:53 INFO - 'HOME': '/Users/cltbld',
22:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:08:53 INFO - 'LOGNAME': 'cltbld',
22:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:08:53 INFO - 'MOZ_NO_REMOTE': '1',
22:08:53 INFO - 'NO_EM_RESTART': '1',
22:08:53 INFO - 'PAGER': '/bin/cat',
22:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:08:53 INFO - 'PWD': '/builds/slave/test',
22:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:08:53 INFO - 'SHELL': '/bin/bash',
22:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:08:53 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:08:53 INFO - 'USER': 'cltbld',
22:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:08:53 INFO - 'XPC_FLAGS': '0x0',
22:08:53 INFO - 'XPC_SERVICE_NAME': '0',
22:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
22:08:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
22:08:54 INFO - Cleaning up...
22:08:54 INFO - Return code: 0
22:08:54 INFO - Running post-action listener: _resource_record_post_action
22:08:54 INFO - Running post-action listener: _start_resource_monitoring
22:08:54 INFO - Starting resource monitoring.
22:08:54 INFO - [mozharness: 2017-05-22 05:08:54.278009Z] Finished create-virtualenv step (success)
22:08:54 INFO - [mozharness: 2017-05-22 05:08:54.279096Z] Running install step.
22:08:54 INFO - Running pre-action listener: _resource_record_pre_action
22:08:54 INFO - Running main action method: install
22:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
22:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
22:08:54 INFO - Reading from file tmpfile_stdout
22:08:54 INFO - Detecting whether we're running mozinstall >=1.0...
22:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
22:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
22:08:54 INFO - Reading from file tmpfile_stdout
22:08:54 INFO - Output received:
22:08:54 INFO - Usage: mozinstall [options] installer
22:08:54 INFO - Options:
22:08:54 INFO - -h, --help show this help message and exit
22:08:54 INFO - -d DEST, --destination=DEST
22:08:54 INFO - Directory to install application into. [default:
22:08:54 INFO - "/builds/slave/test"]
22:08:54 INFO - --app=APP Application being installed. [default: firefox]
22:08:54 INFO - mkdir: /builds/slave/test/build/application
22:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application']
22:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application
22:09:15 INFO - Reading from file tmpfile_stdout
22:09:15 INFO - Output received:
22:09:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox
22:09:15 INFO - Running post-action listener: _resource_record_post_action
22:09:15 INFO - [mozharness: 2017-05-22 05:09:15.813935Z] Finished install step (success)
22:09:15 INFO - [mozharness: 2017-05-22 05:09:15.814074Z] Running run-tests step.
22:09:15 INFO - Running pre-action listener: _resource_record_pre_action
22:09:15 INFO - Running main action method: run_tests
22:09:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
22:09:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
22:09:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
22:09:15 INFO - 2017-05-21 22:09:15.828 screenresolution[3452:53071] starting screenresolution argv=screenresolution get
22:09:15 INFO - 2017-05-21 22:09:15.831 screenresolution[3452:53071] Display 0: 1600x1200x32@60
22:09:15 INFO - 2017-05-21 22:09:15.837 screenresolution[3453:53073] starting screenresolution argv=screenresolution list
22:09:15 INFO - Available Modes on Display 0
22:09:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
22:09:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
22:09:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
22:09:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
22:09:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
22:09:16 INFO - Intel Iris:
22:09:16 INFO - Chipset Model: Intel Iris
22:09:16 INFO - Type: GPU
22:09:16 INFO - Bus: Built-In
22:09:16 INFO - VRAM (Dynamic, Max): 1536 MB
22:09:16 INFO - Vendor: Intel (0x8086)
22:09:16 INFO - Device ID: 0x0a2e
22:09:16 INFO - Revision ID: 0x0009
22:09:16 INFO - Displays:
22:09:16 INFO - Display:
22:09:16 INFO - Resolution: 1600 x 1200 @ 60 Hz
22:09:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
22:09:16 INFO - Display Serial Number: 1600x1200 60
22:09:16 INFO - Main Display: Yes
22:09:16 INFO - Mirror: Off
22:09:16 INFO - Online: Yes
22:09:16 INFO - Rotation: Supported
22:09:16 INFO - Return code: 0
22:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
22:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
22:09:16 INFO - Python 2.7.3
22:09:16 INFO - Return code: 0
22:09:16 INFO - grabbing minidump binary from tooltool
22:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
22:09:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/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 0x108c1dd30>, '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 0x108bd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c12be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
22:09:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
22:09:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache
22:09:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
22:09:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
22:09:16 INFO - Return code: 0
22:09:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755
22:09:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
22:09:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
22:09:16 INFO - ENV: RUST_BACKTRACE is now 1
22:09:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk
22:09:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
22:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build
22:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0130 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log
22:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dNT7CtzbnB/Render',
22:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared',
22:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared',
22:09:16 INFO - 'HOME': '/Users/cltbld',
22:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true',
22:09:16 INFO - 'LOGNAME': 'cltbld',
22:09:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
22:09:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk',
22:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
22:09:16 INFO - 'MOZ_NO_REMOTE': '1',
22:09:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
22:09:16 INFO - 'NO_EM_RESTART': '1',
22:09:16 INFO - 'PAGER': '/bin/cat',
22:09:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11',
22:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
22:09:16 INFO - 'PWD': '/builds/slave/test',
22:09:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
22:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg',
22:09:16 INFO - 'RUST_BACKTRACE': '1',
22:09:16 INFO - 'SHELL': '/bin/bash',
22:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners',
22:09:16 INFO - 'TMPDIR': '/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/',
22:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log',
22:09:16 INFO - 'USER': 'cltbld',
22:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no',
22:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7',
22:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
22:09:16 INFO - 'XPC_FLAGS': '0x0',
22:09:16 INFO - 'XPC_SERVICE_NAME': '0',
22:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'}
22:09:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0130', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600
22:09:16 INFO - mozversion application_buildid: 20170521203316
22:09:16 INFO - mozversion application_changeset: 367944041b558ffe29edf64e8c83879a348de0ca
22:09:16 INFO - mozversion application_display_name: Nightly
22:09:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
22:09:16 INFO - mozversion application_name: Firefox
22:09:16 INFO - mozversion application_remotingname: firefox
22:09:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
22:09:16 INFO - mozversion application_vendor: Mozilla
22:09:16 INFO - mozversion application_version: 55.0a1
22:09:16 INFO - mozversion platform_buildid: 20170521203316
22:09:16 INFO - mozversion platform_changeset: 367944041b558ffe29edf64e8c83879a348de0ca
22:09:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
22:09:16 INFO - mozversion platform_version: 55.0a1
22:09:16 INFO - using testdate: 1495429756
22:09:16 INFO - actual date: 1495429756
22:09:16 INFO - starting webserver on 'localhost:61897'
22:09:16 INFO - SUITE-START | Running 2 tests
22:09:16 INFO - TEST-START | dromaeo_css
22:09:16 INFO - operating with platform_type : mac_
22:09:16 INFO - Initialising browser for dromaeo_css test...
22:09:16 INFO - TEST-INFO | started process 3463 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpEZ_vGR/profile http://localhost:61897/getInfo.html)
22:09:16 INFO - PID 3463 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead
22:09:18 INFO - PID 3463 | 2017-05-21 22:09:18.934 plugin-container[3465:53245] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4b, name = 'com.apple.tsm.portname'
22:09:18 INFO - PID 3463 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:09:18 INFO - PID 3463 | 2017-05-21 22:09:18.937 plugin-container[3465:53245] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa303, name = 'com.apple.CFPasteboardClient'
22:09:18 INFO - PID 3463 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:09:18 INFO - PID 3463 | 2017-05-21 22:09:18.937 plugin-container[3465:53245] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
22:09:19 INFO - PID 3463 | __metrics Screen width/height:1600/1200
22:09:19 INFO - PID 3463 | colorDepth:24
22:09:19 INFO - PID 3463 | Browser inner width/height: 1024/691
22:09:19 INFO - PID 3463 | __metrics
22:09:19 INFO - PID 3463 | 2017-05-21 22:09:19.513 plugin-container[3466:53301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname'
22:09:19 INFO - PID 3463 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:09:19 INFO - PID 3463 | 2017-05-21 22:09:19.513 plugin-container[3466:53301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient'
22:09:19 INFO - PID 3463 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:09:19 INFO - PID 3463 | 2017-05-21 22:09:19.513 plugin-container[3466:53301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
22:09:20 INFO - TEST-INFO | 3463: exit 0
22:09:20 INFO - Browser initialized.
22:09:20 INFO - Running cycle 1/1 for dromaeo_css test...
22:09:20 INFO - TEST-INFO | started process 3467 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmpEZ_vGR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.153 plugin-container[3468:53399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname'
22:09:22 INFO - PID 3467 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.156 plugin-container[3468:53399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient'
22:09:22 INFO - PID 3467 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.156 plugin-container[3468:53399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.933 plugin-container[3469:53445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname'
22:09:22 INFO - PID 3467 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.934 plugin-container[3469:53445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient'
22:09:22 INFO - PID 3467 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:09:22 INFO - PID 3467 | 2017-05-21 22:09:22.934 plugin-container[3469:53445] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
22:09:32 INFO - PID 3467 | RSS: Main: 242745344
22:09:32 INFO - PID 3467 |
22:12:35 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61897/tests/dromaeo/cssquery-ext.html)
22:12:36 INFO - PID 3467 | RSS: Main: 264818688
22:12:36 INFO - PID 3467 |
22:15:38 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-ext.html (next: http://localhost:61897/tests/dromaeo/cssquery-jquery.html)
22:15:39 INFO - PID 3467 | RSS: Main: 270639104
22:15:39 INFO - PID 3467 |
22:18:40 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61897/tests/dromaeo/cssquery-mootools.html)
22:18:41 INFO - PID 3467 | RSS: Main: 274116608
22:18:41 INFO - PID 3467 |
22:21:43 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61897/tests/dromaeo/cssquery-prototype.html)
22:21:43 INFO - PID 3467 | RSS: Main: 271831040
22:21:43 INFO - PID 3467 |
22:24:46 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61897/tests/dromaeo/cssquery-yui.html)
22:24:47 INFO - PID 3467 | RSS: Main: 273403904
22:24:47 INFO - PID 3467 |
22:27:48 INFO - PID 3467 | Cycle 1(1): loaded http://localhost:61897/tests/dromaeo/cssquery-yui.html (next: http://localhost:61897/tests/dromaeo/cssquery-dojo.html)
22:27:49 INFO - PID 3467 | RSS: Main: 274542592
22:27:49 INFO - PID 3467 |
22:27:49 INFO - PID 3467 | __start_tp_report
22:27:49 INFO - PID 3467 | _x_x_mozilla_page_load
22:27:49 INFO - PID 3467 | _x_x_mozilla_page_load_details
22:27:49 INFO - PID 3467 | |i|pagename|runs|
22:27:49 INFO - PID 3467 | |0;dojo.html;2868.627078479797;3527.1182204448905;3562.091666625012;3570.8571657133716;3592.461130830375;4663.323818046386;5420.322459692538;5668.631538949965;6104.816855494342;6121.6020958637655;6139.522803442366;6151.8769624607485;6152.661603611799;6171.598846075013;6192.597481163722;5425.864353391157;5491.945080549203;5536.2249285099915;5605.327360716719;5616.213730077772;4330;5492.450754924515;5606.691631960241;5613.186088017235;5627.7467513961765;4601.792919318638;4754.952450475503;4899.583535399495;5491.478309560605;5610.214569960209;4906.681065730716;5380;5476.397596264408;5496.890062198733;5507.779688812443;5199.350081239845;5441.700706461183;5466.398696143421;5510.889782204334;5538;6095.05526643371;6191.628502289877;6233.0338797486465;6276;6310.92714238576;6477.028445733178;6778.084958530634;6781.38967492928;6793.694283757242;6818;5901.203337549418;6705.832354191184;6844.93155068448;6850.691718872663;6860.108185935796;6293.748250070037;6308.400701933309;6322.873542529123;6345.238571371418;6356.300806911236;7008.544444611084;7021;7032.015517827508;7034.2965703429245;7035.92964070363;6397.808065758035;6454.677266136674;6570.540062195696;6590.670466476657;6631.104800851823;6739.427148692416;6830.282820303796;6842.4183944364295;6849.760258390932;6855.760048398282;6044.607100538451;6379.3620637936765;6500;6530.020496925499;6648.1025061616065;6615.702293396808;6661.034150048215;6692.765753198712;6711.127553418024;6716.966415167991;5341.4925582069645;5388.191771234347;5397.217403476474;5399.622026458189;5449.455054494598;4706.811727530929;4753.025629745894;4803.5916947059195;4813.879653008633;4842.782074806642;6314.905276420857;6339.936600634027;6359.395857393541;6377.681115944184;6422.6467544285515;6882.03651488792;6885.931140688629;6886.690098945459;6890.517663763489;6915.273896240923;6252.406021427957;6366.140571022853;6382.808515744535;6407.359264073649;6417.711202995876;5877.647341159544;5980.880382392328;6003.699815009232;6007.609505382136;6045.516358691294;6962.512624116263;7005.124359455068;7044.612546310104;7047.119110111236;7048.964755176191;6796.150481189851;6819.318068193141;6834.8633027340165;6853.554518956252;6905.482088843457;6796.93203067963;6863.828404289933;6933.791986240421;7028.543144695578;7051.43588512928;6695.832604184934;6751.189857217166;6777.423918966943;6777.966110169417;6785.457163426815;6799.777707208488;6876.621785801829;6909.205441374306;6911.3779759821855;6919.757808476679;6428.228612566335;6474.320196379313;6629.370209830058;6631.0716499689115;6685.8997115044285;5924.526037917042;6198.132261483486;6547.541672083099;6614.437772789367;6663.533552651462;6289.528285378524;6295.1186833842385;6402.295747467868;6468.514861385509;6507.7722279721875;5624.915626265692;6496.317886621837;6512.772052978123;6682.064510968566;6715;6300.527460440394;6331.113644089924;6438.098666186644;6449.322821103904;6491.350864913471
22:27:49 INFO - PID 3467 | |1;ext.html;795.8209402884348;810.335524869607;824.5052968219068;1222.7248869004475;1277.2591896699914;38500.22999540008;38774.61225387752;38807;38823.835284941495;38931;39249.95375947931;39261.80369098158;39547.40678889819;39594.8020259899;39622.00944976377;7558.395328373717;7798.5320880747195;7916.881246781303;8258.587070646474;8524.275436587897;7742;8227.95886020569;8435.072142064368;8535.658573657047;8565.772899046231;8508.276796472308;8560.571971401436;8579.056303806576;8581.056083830774;8591.35564832637;8440.029396619375;8527.616257268437;8536.317094632415;8548.871766923501;8562.571871406468;7115.5730656160795;7921.287084162453;8250.917490825135;8297.83404331916;8310.25207731301;36907;36916.63083369159;36919.630803692155;36931;36938.89183324505;3642.453631955201;3642.763220390666;3682.760620559655;3683.0055884911194;3683.0608194910396;30122.945696900722;30235.09294721162;30279.848600757075;30353.241168970733;30412.695873041208;3045.4670432674193;3096.628404591441;3109.7667674924473;3117.283024904262;3123.687631236881;299832;302032.9796702027;304405;304424;305988;46711.766441168256;46845.765771170925;46846.76576617095;46953;47039.76480117646;49127;49167.508324917006;51546.74226628843;54511.727441363335;54597.72701136469;13947.86052139486;13990;14031.78952315716;14162.575122746333;14166.079204851652;11176.050035747052;11221;11256.943715281372;11343.659690209306;11395.544178232944;14053.400120977447;14064.085834420934;14090.084144530572;14090.295485225697;14153.85846141546;6223.564350495421;6286.874262514724;6304.621722696653;6306.801707675527;6309.495240380758;1018.2414101494434;1050.3855244681795;1057.461393672205;1094.5731164845718;1100.8844071372391;24125;24286.635700464507;24422.26733198007;24436.8232129822;24466.87766561156;1030.6341248856697;1099.7133353975867;1144.6794897428733;1170.7365842685329;1179.6637958181877;12309.522857257007;12556.372181390894;12568.74862502763;16483.83516164847;16515.33938642465;339127;341109.58890411275;341202;342068;342975;56283.71858140683;56622;56896.146557801665;56913;56961;1497.161589509878;1501.5795577238391;1542.3367951780617;1543.8919275650596;1549.147968617256;26294.62315469964;29595.40809183847;30280.84859575688;30457.086287411414;30506.694933050385;38776.030599235244;38890.02774930649;38897.80551097226;38898;39413.21173576455;294550;295220;295400;295748;296246;32354.676453235166;33032.83483582567;34216;34318.656813431546;34358.82820585981;41119.177616446905;41560;42924;42974.140517190106;43276;29251.268718282212;29823.10530684083;30325.545116823705;30337.089887303417;30704.232394190323;29300.853495732386;29832.552511711907;30336.84831575828;30358.69641303559;30464.695353046187;29157.7084229155;33839;36133.457998129525;36490.452643210905;36590.26819463649;29636.555451668675;29716.85141574278;30235.244118897204;30597.847010764803;30791.384172316877;22021.669674954566;22890.771092288865;23540.411489712893;24049.75950240475;24467.38831529226
22:27:49 INFO - PID 3467 | |2;jquery.html;436.7641473604254;441.2036274524484;445.5633479190394;459.7976890168326;463.7912939177369;20951;21272.57454850904;21577.784222157774;21806.781932180675;21810;21541.815200163986;21672.34982950514;21964.670529942025;22062.558748825013;22721.659175112385;5965.403459654043;6030.698465076762;6230.096635987779;6242.531810114237;6248.718807653644;5934.080217566284;6085;6156.907646385308;6157.230346206724;6165.383461653843;5892.175095486636;6001.189839371695;6078.787242446493;6130.908036379458;6147.846303842417;6052.667103309316;6094.116353128816;6128.877422451571;6141;6141.938580614182;5373.194020896858;5383.300170977787;5448.019356515825;5450.345958484968;5473.124300112003;18985.62028759431;26729.663516824083;27516.03693870705;27603.86198069017;27645.86177069122;2043.948901277465;2243.9439014024615;2248.7638797926147;2267.0591704442636;2267.501149747053;13739.519116830963;16417.42539011129;17127;18024.188911498884;18092.54768630795;2065.896705164751;2160.25471212433;2179.455136215946;2192.561487702466;2210.2264207527396;161735;162521.9999999988;164512;164784.3521564781;167985.16007420007;12760;12771.808422873479;12841.357932103358;12987.896028837466;13167.934160329138;16347.34610615586;16529.00825950447;16532.256048477848;16558.83441165573;16570.337186512646;8147.837043259102;8588.711693246063;8753;9074.45553266806;9252.444853308823;8623.655053797778;8631.741047768577;8642.740717778477;8795.780105497413;8812.515311657791;6661.966690166615;7983.481073730305;8015.318697910627;8057.234562716532;8070.435069545193;2177.466520702438;2179.2372669565843;2195.9121635134734;2196.099599164335;2373.4778348763243;830.9542975136426;946.9857952130586;976.3848775271533;977.5161295158941;1014.4826138669332;15465.690686186213;15994.840051599567;18124.093795310182;21375.03812328449;21390.82350470739;865.6710450029075;992.8709267795141;999.3054826895238;1010.1968934696934;1035.2649618770756;9617.471039092778;10593.84109238362;10612.310199836986;10913.39976301311;11043;536976.3151184297;539030;540114;557540;558036;96233.51883240679;128048;137376;140323;141924.72463276054;1182.266994463421;1201.1711918776016;1262.4093642011132;1266.4301064521003;1269.4224128021729;21331.78668213298;22203.888980554995;22476.32571022872;22710;22796.658050128928;57978.420215797305;58144.63915206292;59788;60546.394536055835;61571.46071348073;75341.6232918832;77167.05496505341;80495.98760031046;81234.59382703049;82386.17613824025;8800.95599522024;8828.470291782514;8854.468731876108;9217.677381291622;9289.953550232205;14329.9283503586;14352.282385880873;14740.13869211824;14886.18126003037;15040.924795375953;9782.804343913223;11017.559297628006;12009.879901200877;12150.116731906215;12270.141090123605;11692.00617947484;11931.343776092051;12004.75990480168;12086.697832554257;12163.087768417226;11744.530218791495;12097.395130243629;12253.816192757295;12506.499740010298;12738.172018818746;8820.514871682119;9016;9137.761248493896;9166.495842728715;9368.531573421438;11803;11993.64019079429;12027.338496382434;12118.151729378858;12349.876501234872
22:27:49 INFO - PID 3467 | |3;mootools.html;1414.724128794885;1429.9564947172157;1477.79559658878;1488.5459934719913;1489.9031562948408;10077.294589378746;10415.270931034831;11513.251638643482;11833.171677982535;11996.700082497931;11544.307341559512;11870.584529541467;11891.881081189185;11907.7618447631;11914.10644201684;2509.222141136249;2553.3744232663025;2564;2566.0633868638;2571.627114068459;2493.9002439902383;2560.4111054457485;2560.7695307422327;2560.7695307422327;2563.5257477366745;2507.4608959073794;2521.3318470605213;2526.68416447944;2536.3278731136265;2543.9618405723836;2468.7037555493366;2472.134752836502;2475.3192871960173;2493.7880280176028;2495.201535508638;2444.340028192378;2457.5699252630898;2466.1491785334033;2469.469064151216;2472.5920223163157;11424.828627570592;11568.190226684135;11571.884281157165;11606.129540284428;11663.766724665544;6340.556161068727;6504.8699026019685;6557.114789503404;6558.573692709959;6565.737370505174;3937.5471820740695;4212.662986961066;4251.319788833772;4287.378330142144;4316.920769807548;1204.4339160594507;1211.9757604847941;1256.824044633752;1268.5560053981126;1271.974560508794;13649.38577763993;16287.83712162875;16418.26117824701;16447.91776041124;16450.424235151833;5104.821331253463;5603.8792241551855;5770.42295770428;6020.247469066367;6100.420460056288;13542.864571354161;13627.727445451035;13725.656858578615;13750;13793.655158621115;6666.500012499082;6681.1314529110905;6708.765193218312;6711.697973591103;6712.6979285931275;5888.234529511222;6142.969285153546;6180.536459765535;6231.937680623136;6265.561410701298;4114.6091121343425;4275.251830929575;4311.288637374798;4315.611594956469;4317.805698743564;1082.7888561730545;1270.6315168601002;1276.495784165268;1284.6017734502334;1287.8905293050195;786.9842603147905;797.7833803449786;809.6113865344668;813.4265342933336;839.6557411461272;5642.887142257132;6085.174447665771;6320.241571011508;6362.231417318928;6378.681065946684;593.8337265565649;595.6396380190009;608.3034925010837;611.0071134406589;612.788587937161;3632.654897784706;3810.8094595270127;3876.8836934891997;3980.104476492766;4275.551067137968;13700.45198192061;14588.927055364868;14910.105393676415;15012.249387530579;15014.774778378332;12127.02983761297;13721.725565488834;13739;13961.022728409116;14920.552383428514;7383.81540461482;8441.535715535581;8600.440971336842;8629.223369896614;8724.302055835646;6638;6843.589384636938;6872.69072891711;6961;6998.580085194904;12690.936545317214;13542.932285338511;13620.250886201355;13697.863021370058;13786.034977551475;13192.406341714453;13229.404676789374;13485.797713034117;13921.58235252944;13963;8504;8822.558872056243;9172.633094676139;9188.21900138469;9351.672691455766;10939.671809845717;11047.226694131334;11059.72350691239;11072.501737421997;11076.612318569134;8854.335924805537;9039.186473217442;9044.728658140266;9046.365983355812;9276.62893484253;7669.159401743653;8440.53577053268;8850.849389579313;8873.157050080232;8962.462252264884;7227.7831665050135;8090.312323452573;8102.108768035393;8156.592170391576;8188.50868947865;5034.622403319692;5636.84444688846;5914.112883067574;6086.60437071589;6124.479419249235;5580.972095139498;5960.165576819336;6108.602940808833;6211.844703882439;6217.347178546188
22:27:49 INFO - PID 3467 | |4;prototype.html;342.39567163955616;399.19960479239137;399.5485101834926;403.21574537524526;412.6348181859054;5031.647784655075;6089.269287685478;6111.7249723762425;6120.969395153029;6286.2456505219325;6004.919114559378;6122.877542449149;6146.508279337653;6151.600145990507;6160.829442405951;4359.324304732756;4470.597646211832;4474.955250447487;4485.461744590653;4502.909941801162;4246.957530424703;4447.088346888896;4465.866024019285;4494.68537202396;4512.435945506812;4459.554044595547;4473.9552604473865;4480.708753930984;4513.480949690778;4521.299198624219;4358.607725304738;4424.800883960197;4437.334399840018;4452.376667266585;4475.104979004196;3358.80762329372;3780.7731536107644;3800.6959443244473;3815.4276858471176;3816.3321418751607;5441.80280338325;5450.454954504517;5534.861628459281;5665.376808551097;5801.970990145064;5228;5272.710000949946;5294.549963253128;5324.281222035053;5349.277847490617;5420.970015697005;5657.321121465409;5823.816068974134;6433.549651524395;6544.280129185786;4129.752214867118;4314.374415709736;4342.478902531717;4349.412829268072;4394.670399720002;6778.559393639398;6832.555883867532;6998.230194678581;7064.894026589605;7108.822279443004;4386.363977223285;4398.23030969579;4407.316865885793;4415.536368681306;4430.734155950653;3582.0507565495163;3908.4304865566455;3960.4257382679925;4482.932756008662;4482.977585112054;3987.5813039631003;4282.678799090081;4490.595846373842;4500.729956202638;5357.035733567917;6293.905591416133;6609.050330152617;6609.867802644017;6629.602223866584;6667.86664266712;1449.5723761490385;1498.9400423982918;1499.1529785671062;1509.7131545006414;1512.8260250071382;886.2334081019872;931.729798358482;1000.5047501486665;1009.0666133826328;1018.6248564438098;5339.679619222859;6251.78118765841;6259.186305780219;6274.686265686789;6289.7169627366875;5835.708214589254;6791.864162716718;6804.9931526419605;6867.381935625718;6905.723771049102;6164.3835616438;6166.260048794174;6284.748610055546;6301.338359472189;6311.400416960381;3591.712662986955;3597.9100522486624;3606.9819650902105;3614.8734794282072;3615.240799432096;6463.903041454382;6865.910340346863;7031.542949708252;7074.92925070753;7104.538205016657;5883.382244864313;6475.838104047437;7063.470239731938;7098.3256590623805;7111.146662400545;6221.329415644791;6505.837354066186;7051.365377116084;7137.393321567621;7163.78508644731;6991.545549539263;6997.1253593300835;7002.334778196063;7011.26381729911;7021.507364885392;5786.627482732228;6681.532292739462;6863.519553631198;6872.69072891711;6900.481893983316;6869.793906182823;7194;7199.832073887473;7213.350798428167;7230.13238411394;3872.5159355080614;4073.531543872374;4419.18245124653;4425.919817685683;4436.800343984464;6411.455026322628;6452.869968375247;6871.025908702571;6952.339527744856;6971.581705097507;6668.333166683293;6712.328767123444;6727.394534491919;6757.391834734897;6767.492438067263;5062.987402519584;6105.603135796159;6405.295417504164;6763.6618169092335;6790.660466976533;5023.874403139951;6555.4427873629365;6584.8353791155605;6592.43964263043;6622.966885165543;5806.983777838776;6417.2299324081405;6436.8390790230615;6737.9326206737305;6779.525433219627;5744.569157313134;6443.162388889415;6483.935160648333;6523;6529.608223506605
22:27:49 INFO - PID 3467 | |5;yui.html;835.9791005224872;849.295085079384;863.0851297624522;866.8439680857443;879.2614204068581;16445.50663480096;17089.230984605692;17117.914410427948;18675.06624668768;19269.421917342468;17847.375341863004;18438.354657586955;18603.720944185847;18607.441776746684;18871;960.3709570231509;1002.413588050991;1025.84612308154;1028.5702873006526;1033.534909290822;1000.0749306891133;1011.9342242754198;1017.6845177994808;1018.210886562916;1018.836986082227;1057.1965306367179;1073.5598404654093;1075.983860242093;1076.8438576406456;1079.7732476179972;1057.3338796558157;1059.607945060325;1074.049466222399;1074.978500429995;1077.4720387010348;762.2796457347808;769.707511145761;779.8869163971249;781.7889169924146;782.1708988472237;16536.834631653648;16928.66142677152;17153.228104735317;17179.398721044705;17184.656306873916;16751;17004.489865303938;17083.743743843854;17263.827361726475;17336.566585835328;13922.651933701638;14120.152790832582;14225.857741422557;14341.354639041268;14462.855371446361;3098.349346637209;3518.8064656443885;3541.8052007139595;3576.5708115026105;3576.7675101118343;94358.64103397401;108717.45641271822;129673;132094;132535;12009.699757506132;13427.530036448677;13516.053876228767;13727.039107262395;13794.517191898236;25210.873945630156;25794.613080803803;25889.22332330033;26167.345816354744;26294.6055809163;7856.803579910434;7939.761807145679;7978.60106994648;7979.3217576505485;7988.760337189894;3848.692104631679;3937.5078115235597;3941.4481972523868;3947.940780888289;3949.4273330366937;3860.3244432224246;4163.770992595436;4329.718568293051;4375.912481750347;4377.058932329564;776.3517462918526;916.2349438219162;948.5636607160645;955.6225291010151;958.5782255807422;648.3386945315751;668.3283300283184;669.778972938929;678.7861823525574;687.3744892148221;11073.667789966314;12001.759964800654;12012.279263244232;12151.453184606715;12383.876161238453;644.5616980453243;650.5933791380387;654.7839213059679;683.6274230544366;685.0204207982533;5394.0290747665795;5659.603827732019;5670.262865827416;5694.487496125285;5703.030484817591;106898.98257630553;107185;111172.02607603504;111900;113501;65778;87706.56146719225;88062;88523;97929.51035244777;75244;77314;80340.59829700814;81347;83519.58240208881;10725.41010244444;11393.031592314746;12161.939190304345;12752.617421477371;12955.481780728665;40226.59773402228;41543.792281038404;41878.58121418747;42047;42103;58327.708361457924;68614;71870.28129718636;72956;74482.62758686172;16502;16818.747718784474;16835.915820420818;17216.225269862636;17337.95972241619;18226;18487.075646217905;18578.232140630844;18624.813751862308;18928.33750818715;14169.929150354183;16339.509814705578;17375.7393639093;17435.738463923306;17569;16107.919460402623;16451.506454806375;17098.316067357664;17156.05641689719;17169.399071032454;16272.349106035626;16291.266892990081;16471.505854824376;16690.582735431224;16745.413910513074;11754.823677645014;11790.469428875893;11862.822057669315;11901.523939042341;12352.444140013542;11753.840653829839;13285;13692.52076177329;13873.930630346784;13895.444182232597
22:27:49 INFO - PID 3467 | __end_tp_report
22:27:49 INFO - PID 3467 | __start_cc_report
22:27:49 INFO - PID 3467 | _x_x_mozilla_cycle_collect,343
22:27:49 INFO - PID 3467 | __end_cc_report
22:27:49 INFO - PID 3467 | __startTimestamp1495430869038__endTimestamp
22:27:49 INFO - PID 3467 |
22:27:49 INFO - PID 3467 | ------- Summary: start -------
22:27:49 INFO - PID 3467 | Number of tests: 6
22:27:49 INFO - PID 3467 |
22:27:49 INFO - PID 3467 | [#0] dojo.html Cycles:165 Average:6172.03 Median:6397.81 stddev:801.12 (12.5%) stddev-sans-first:760.51
22:27:49 INFO - PID 3467 | Values: 2868.6 3527.1 3562.1 3570.9 3592.5 4663.3 5420.3 5668.6 6104.8 6121.6 6139.5 6151.9 6152.7 6171.6 6192.6 5425.9 5491.9 5536.2 5605.3 5616.2 4330.0 5492.5 5606.7 5613.2 5627.7 4601.8 4755.0 4899.6 5491.5 5610.2 4906.7 5380.0 5476.4 5496.9 5507.8 5199.4 5441.7 5466.4 5510.9 5538.0 6095.1 6191.6 6233.0 6276.0 6310.9 6477.0 6778.1 6781.4 6793.7 6818.0 5901.2 6705.8 6844.9 6850.7 6860.1 6293.7 6308.4 6322.9 6345.2 6356.3 7008.5 7021.0 7032.0 7034.3 7035.9 6397.8 6454.7 6570.5 6590.7 6631.1 6739.4 6830.3 6842.4 6849.8 6855.8 6044.6 6379.4 6500.0 6530.0 6648.1 6615.7 6661.0 6692.8 6711.1 6717.0 5341.5 5388.2 5397.2 5399.6 5449.5 4706.8 4753.0 4803.6 4813.9 4842.8 6314.9 6339.9 6359.4 6377.7 6422.6 6882.0 6885.9 6886.7 6890.5 6915.3 6252.4 6366.1 6382.8 6407.4 6417.7 5877.6 5980.9 6003.7 6007.6 6045.5 6962.5 7005.1 7044.6 7047.1 7049.0 6796.2 6819.3 6834.9 6853.6 6905.5 6796.9 6863.8 6933.8 7028.5 7051.4 6695.8 6751.2 6777.4 6778.0 6785.5 6799.8 6876.6 6909.2 6911.4 6919.8 6428.2 6474.3 6629.4 6631.1 6685.9 5924.5 6198.1 6547.5 6614.4 6663.5 6289.5 6295.1 6402.3 6468.5 6507.8 5624.9 6496.3 6512.8 6682.1 6715.0 6300.5 6331.1 6438.1 6449.3 6491.4
22:27:49 INFO - PID 3467 |
22:27:49 INFO - PID 3467 | [#1] ext.html Cycles:180 Average:46402.77 Median:186483.00 stddev:82425.31 (44.2%) stddev-sans-first:82585.40
22:27:49 INFO - PID 3467 | Values: 795.8 810.3 824.5 1222.7 1277.3 38500.2 38774.6 38807.0 38823.8 38931.0 39250.0 39261.8 39547.4 39594.8 39622.0 7558.4 7798.5 7916.9 8258.6 8524.3 7742.0 8228.0 8435.1 8535.7 8565.8 8508.3 8560.6 8579.1 8581.1 8591.4 8440.0 8527.6 8536.3 8548.9 8562.6 7115.6 7921.3 8250.9 8297.8 8310.3 36907.0 36916.6 36919.6 36931.0 36938.9 3642.5 3642.8 3682.8 3683.0 3683.1 30122.9 30235.1 30279.8 30353.2 30412.7 3045.5 3096.6 3109.8 3117.3 3123.7 299832.0 302033.0 304405.0 304424.0 305988.0 46711.8 46845.8 46846.8 46953.0 47039.8 49127.0 49167.5 51546.7 54511.7 54597.7 13947.9 13990.0 14031.8 14162.6 14166.1 11176.1 11221.0 11256.9 11343.7 11395.5 14053.4 14064.1 14090.1 14090.3 14153.9 6223.6 6286.9 6304.6 6306.8 6309.5 1018.2 1050.4 1057.5 1094.6 1100.9 24125.0 24286.6 24422.3 24436.8 24466.9 1030.6 1099.7 1144.7 1170.7 1179.7 12309.5 12556.4 12568.7 16483.8 16515.3 339127.0 341109.6 341202.0 342068.0 342975.0 56283.7 56622.0 56896.1 56913.0 56961.0 1497.2 1501.6 1542.3 1543.9 1549.1 26294.6 29595.4 30280.8 30457.1 30506.7 38776.0 38890.0 38897.8 38898.0 39413.2 294550.0 295220.0 295400.0 295748.0 296246.0 32354.7 33032.8 34216.0 34318.7 34358.8 41119.2 41560.0 42924.0 42974.1 43276.0 29251.3 29823.1 30325.5 30337.1 30704.2 29300.9 29832.6 30336.8 30358.7 30464.7 29157.7 33839.0 36133.5 36490.5 36590.3 29636.6 29716.9 30235.2 30597.8 30791.4 22021.7 22890.8 23540.4 24049.8 24467.4
22:27:49 INFO - PID 3467 |
22:27:49 INFO - PID 3467 | [#2] jquery.html Cycles:180 Average:35853.94 Median:2267.28 stddev:93156.86 (4108.7%) stddev-sans-first:93380.23
22:27:49 INFO - PID 3467 | Values: 436.8 441.2 445.6 459.8 463.8 20951.0 21272.6 21577.8 21806.8 21810.0 21541.8 21672.3 21964.7 22062.6 22721.7 5965.4 6030.7 6230.1 6242.5 6248.7 5934.1 6085.0 6156.9 6157.2 6165.4 5892.2 6001.2 6078.8 6130.9 6147.8 6052.7 6094.1 6128.9 6141.0 6141.9 5373.2 5383.3 5448.0 5450.3 5473.1 18985.6 26729.7 27516.0 27603.9 27645.9 2043.9 2243.9 2248.8 2267.1 2267.5 13739.5 16417.4 17127.0 18024.2 18092.5 2065.9 2160.3 2179.5 2192.6 2210.2 161735.0 162522.0 164512.0 164784.4 167985.2 12760.0 12771.8 12841.4 12987.9 13167.9 16347.3 16529.0 16532.3 16558.8 16570.3 8147.8 8588.7 8753.0 9074.5 9252.4 8623.7 8631.7 8642.7 8795.8 8812.5 6662.0 7983.5 8015.3 8057.2 8070.4 2177.5 2179.2 2195.9 2196.1 2373.5 831.0 947.0 976.4 977.5 1014.5 15465.7 15994.8 18124.1 21375.0 21390.8 865.7 992.9 999.3 1010.2 1035.3 9617.5 10593.8 10612.3 10913.4 11043.0 536976.3 539030.0 540114.0 557540.0 558036.0 96233.5 128048.0 137376.0 140323.0 141924.7 1182.3 1201.2 1262.4 1266.4 1269.4 21331.8 22203.9 22476.3 22710.0 22796.7 57978.4 58144.6 59788.0 60546.4 61571.5 75341.6 77167.1 80496.0 81234.6 82386.2 8801.0 8828.5 8854.5 9217.7 9290.0 14329.9 14352.3 14740.1 14886.2 15040.9 9782.8 11017.6 12009.9 12150.1 12270.1 11692.0 11931.3 12004.8 12086.7 12163.1 11744.5 12097.4 12253.8 12506.5 12738.2 8820.5 9016.0 9137.8 9166.5 9368.5 11803.0 11993.6 12027.3 12118.2 12349.9
22:27:49 INFO - PID 3467 |
22:27:49 INFO - PID 3467 | [#3] mootools.html Cycles:180 Average:7044.49 Median:3721.73 stddev:4467.12 (120.0%) stddev-sans-first:4459.62
22:27:49 INFO - PID 3467 | Values: 1414.7 1430.0 1477.8 1488.5 1489.9 10077.3 10415.3 11513.3 11833.2 11996.7 11544.3 11870.6 11891.9 11907.8 11914.1 2509.2 2553.4 2564.0 2566.1 2571.6 2493.9 2560.4 2560.8 2560.8 2563.5 2507.5 2521.3 2526.7 2536.3 2544.0 2468.7 2472.1 2475.3 2493.8 2495.2 2444.3 2457.6 2466.1 2469.5 2472.6 11424.8 11568.2 11571.9 11606.1 11663.8 6340.6 6504.9 6557.1 6558.6 6565.7 3937.5 4212.7 4251.3 4287.4 4316.9 1204.4 1212.0 1256.8 1268.6 1272.0 13649.4 16287.8 16418.3 16447.9 16450.4 5104.8 5603.9 5770.4 6020.2 6100.4 13542.9 13627.7 13725.7 13750.0 13793.7 6666.5 6681.1 6708.8 6711.7 6712.7 5888.2 6143.0 6180.5 6231.9 6265.6 4114.6 4275.3 4311.3 4315.6 4317.8 1082.8 1270.6 1276.5 1284.6 1287.9 787.0 797.8 809.6 813.4 839.7 5642.9 6085.2 6320.2 6362.2 6378.7 593.8 595.6 608.3 611.0 612.8 3632.7 3810.8 3876.9 3980.1 4275.6 13700.5 14588.9 14910.1 15012.2 15014.8 12127.0 13721.7 13739.0 13961.0 14920.6 7383.8 8441.5 8600.4 8629.2 8724.3 6638.0 6843.6 6872.7 6961.0 6998.6 12690.9 13542.9 13620.3 13697.9 13786.0 13192.4 13229.4 13485.8 13921.6 13963.0 8504.0 8822.6 9172.6 9188.2 9351.7 10939.7 11047.2 11059.7 11072.5 11076.6 8854.3 9039.2 9044.7 9046.4 9276.6 7669.2 8440.5 8850.8 8873.2 8962.5 7227.8 8090.3 8102.1 8156.6 8188.5 5034.6 5636.8 5914.1 6086.6 6124.5 5581.0 5960.2 6108.6 6211.8 6217.3
22:27:49 INFO - PID 3467 |
22:27:49 INFO - PID 3467 | [#4] prototype.html Cycles:180 Average:5302.58 Median:6047.09 stddev:1720.41 (28.5%) stddev-sans-first:1684.47
22:27:49 INFO - PID 3467 | Values: 342.4 399.2 399.5 403.2 412.6 5031.6 6089.3 6111.7 6121.0 6286.2 6004.9 6122.9 6146.5 6151.6 6160.8 4359.3 4470.6 4475.0 4485.5 4502.9 4247.0 4447.1 4465.9 4494.7 4512.4 4459.6 4474.0 4480.7 4513.5 4521.3 4358.6 4424.8 4437.3 4452.4 4475.1 3358.8 3780.8 3800.7 3815.4 3816.3 5441.8 5450.5 5534.9 5665.4 5802.0 5228.0 5272.7 5294.5 5324.3 5349.3 5421.0 5657.3 5823.8 6433.5 6544.3 4129.8 4314.4 4342.5 4349.4 4394.7 6778.6 6832.6 6998.2 7064.9 7108.8 4386.4 4398.2 4407.3 4415.5 4430.7 3582.1 3908.4 3960.4 4482.9 4483.0 3987.6 4282.7 4490.6 4500.7 5357.0 6293.9 6609.1 6609.9 6629.6 6667.9 1449.6 1498.9 1499.2 1509.7 1512.8 886.2 931.7 1000.5 1009.1 1018.6 5339.7 6251.8 6259.2 6274.7 6289.7 5835.7 6791.9 6805.0 6867.4 6905.7 6164.4 6166.3 6284.7 6301.3 6311.4 3591.7 3597.9 3607.0 3614.9 3615.2 6463.9 6865.9 7031.5 7074.9 7104.5 5883.4 6475.8 7063.5 7098.3 7111.1 6221.3 6505.8 7051.4 7137.4 7163.8 6991.5 6997.1 7002.3 7011.3 7021.5 5786.6 6681.5 6863.5 6872.7 6900.5 6869.8 7194.0 7199.8 7213.4 7230.1 3872.5 4073.5 4419.2 4425.9 4436.8 6411.5 6452.9 6871.0 6952.3 6971.6 6668.3 6712.3 6727.4 6757.4 6767.5 5063.0 6105.6 6405.3 6763.7 6790.7 5023.9 6555.4 6584.8 6592.4 6623.0 5807.0 6417.2 6436.8 6737.9 6779.5 5744.6 6443.2 6483.9 6523.0 6529.6
22:27:49 INFO - PID 3467 |
22:27:49 INFO - PID 3467 | [#5] yui.html Cycles:180 Average:22138.58 Median:17708.19 stddev:30744.96 (173.6%) stddev-sans-first:30789.60
22:27:49 INFO - PID 3467 | Values: 836.0 849.3 863.1 866.8 879.3 16445.5 17089.2 17117.9 18675.1 19269.4 17847.4 18438.4 18603.7 18607.4 18871.0 960.4 1002.4 1025.8 1028.6 1033.5 1000.1 1011.9 1017.7 1018.2 1018.8 1057.2 1073.6 1076.0 1076.8 1079.8 1057.3 1059.6 1074.0 1075.0 1077.5 762.3 769.7 779.9 781.8 782.2 16536.8 16928.7 17153.2 17179.4 17184.7 16751.0 17004.5 17083.7 17263.8 17336.6 13922.7 14120.2 14225.9 14341.4 14462.9 3098.3 3518.8 3541.8 3576.6 3576.8 94358.6 108717.5 129673.0 132094.0 132535.0 12009.7 13427.5 13516.1 13727.0 13794.5 25210.9 25794.6 25889.2 26167.3 26294.6 7856.8 7939.8 7978.6 7979.3 7988.8 3848.7 3937.5 3941.4 3947.9 3949.4 3860.3 4163.8 4329.7 4375.9 4377.1 776.4 916.2 948.6 955.6 958.6 648.3 668.3 669.8 678.8 687.4 11073.7 12001.8 12012.3 12151.5 12383.9 644.6 650.6 654.8 683.6 685.0 5394.0 5659.6 5670.3 5694.5 5703.0 106899.0 107185.0 111172.0 111900.0 113501.0 65778.0 87706.6 88062.0 88523.0 97929.5 75244.0 77314.0 80340.6 81347.0 83519.6 10725.4 11393.0 12161.9 12752.6 12955.5 40226.6 41543.8 41878.6 42047.0 42103.0 58327.7 68614.0 71870.3 72956.0 74482.6 16502.0 16818.7 16835.9 17216.2 17338.0 18226.0 18487.1 18578.2 18624.8 18928.3 14169.9 16339.5 17375.7 17435.7 17569.0 16107.9 16451.5 17098.3 17156.1 17169.4 16272.3 16291.3 16471.5 16690.6 16745.4 11754.8 11790.5 11862.8 11901.5 12352.4 11753.8 13285.0 13692.5 13873.9 13895.4
22:27:49 INFO - PID 3467 | -------- Summary: end --------
22:27:49 INFO - PID 3467 |
22:27:49 INFO - TEST-INFO | 3467: exit 0
22:27:49 INFO - TEST-OK | dromaeo_css | took 1113272ms
22:27:49 INFO - TEST-START | kraken
22:27:49 INFO - operating with platform_type : mac_
22:27:49 INFO - Initialising browser for kraken test...
22:27:49 INFO - TEST-INFO | started process 3475 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmp9wYXJV/profile http://localhost:61897/getInfo.html)
22:27:50 INFO - PID 3475 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead
22:27:52 INFO - PID 3475 | 2017-05-21 22:27:52.152 plugin-container[3476:55551] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname'
22:27:52 INFO - PID 3475 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:27:52 INFO - PID 3475 | 2017-05-21 22:27:52.156 plugin-container[3476:55551] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient'
22:27:52 INFO - PID 3475 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:27:52 INFO - PID 3475 | 2017-05-21 22:27:52.157 plugin-container[3476:55551] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
22:27:52 INFO - PID 3475 | __metrics Screen width/height:1600/1200
22:27:52 INFO - PID 3475 | colorDepth:24
22:27:52 INFO - PID 3475 | Browser inner width/height: 1024/691
22:27:52 INFO - PID 3475 | __metrics
22:27:53 INFO - TEST-INFO | 3475: exit 0
22:27:53 INFO - Browser initialized.
22:27:53 INFO - Running cycle 1/1 for kraken test...
22:27:53 INFO - TEST-INFO | started process 3478 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/tmp9wYXJV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
22:27:55 INFO - PID 3478 | 2017-05-21 22:27:55.519 plugin-container[3479:55693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname'
22:27:55 INFO - PID 3478 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:27:55 INFO - PID 3478 | 2017-05-21 22:27:55.521 plugin-container[3479:55693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient'
22:27:55 INFO - PID 3478 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:27:55 INFO - PID 3478 | 2017-05-21 22:27:55.521 plugin-container[3479:55693] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
22:27:56 INFO - PID 3478 | 2017-05-21 22:27:56.260 plugin-container[3480:55739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname'
22:27:56 INFO - PID 3478 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:27:56 INFO - PID 3478 | 2017-05-21 22:27:56.260 plugin-container[3480:55739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient'
22:27:56 INFO - PID 3478 | See /usr/include/servers/bootstrap_defs.h for the error codes.
22:27:56 INFO - PID 3478 | 2017-05-21 22:27:56.260 plugin-container[3480:55739] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
22:28:05 INFO - PID 3478 | RSS: Main: 242073600
22:28:05 INFO - PID 3478 |
22:28:08 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61897/tests/kraken/driver.html?testName=audio-beat-detection)
22:28:09 INFO - PID 3478 | RSS: Main: 253722624
22:28:09 INFO - PID 3478 |
22:28:11 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61897/tests/kraken/driver.html?testName=audio-dft)
22:28:12 INFO - PID 3478 | RSS: Main: 262823936
22:28:12 INFO - PID 3478 |
22:28:16 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61897/tests/kraken/driver.html?testName=audio-fft)
22:28:16 INFO - PID 3478 | RSS: Main: 264994816
22:28:16 INFO - PID 3478 |
22:28:18 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61897/tests/kraken/driver.html?testName=audio-oscillator)
22:28:19 INFO - PID 3478 | RSS: Main: 266854400
22:28:19 INFO - PID 3478 |
22:28:21 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61897/tests/kraken/driver.html?testName=imaging-gaussian-blur)
22:28:21 INFO - PID 3478 | RSS: Main: 267321344
22:28:21 INFO - PID 3478 |
22:28:24 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61897/tests/kraken/driver.html?testName=imaging-darkroom)
22:28:25 INFO - PID 3478 | RSS: Main: 276287488
22:28:25 INFO - PID 3478 |
22:28:28 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61897/tests/kraken/driver.html?testName=imaging-desaturate)
22:28:29 INFO - PID 3478 | RSS: Main: 276733952
22:28:29 INFO - PID 3478 |
22:28:32 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61897/tests/kraken/driver.html?testName=json-parse-financial)
22:28:32 INFO - PID 3478 | RSS: Main: 275644416
22:28:32 INFO - PID 3478 |
22:28:34 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61897/tests/kraken/driver.html?testName=json-stringify-tinderbox)
22:28:34 INFO - PID 3478 | RSS: Main: 275222528
22:28:34 INFO - PID 3478 |
22:28:36 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-aes)
22:28:37 INFO - PID 3478 | RSS: Main: 275324928
22:28:37 INFO - PID 3478 |
22:28:39 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-ccm)
22:28:40 INFO - PID 3478 | RSS: Main: 274952192
22:28:40 INFO - PID 3478 |
22:28:43 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
22:28:43 INFO - PID 3478 | RSS: Main: 275222528
22:28:43 INFO - PID 3478 |
22:28:47 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
22:28:47 INFO - PID 3478 | RSS: Main: 275464192
22:28:47 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | Cycle 1(1): loaded http://localhost:61897/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61897/tests/kraken/driver.html?testName=ai-astar)
22:28:49 INFO - PID 3478 | RSS: Main: 277032960
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | __start_tp_report
22:28:49 INFO - PID 3478 | _x_x_mozilla_page_load
22:28:49 INFO - PID 3478 | _x_x_mozilla_page_load_details
22:28:49 INFO - PID 3478 | |i|pagename|runs|
22:28:49 INFO - PID 3478 | |0;ai-astar;148;167;116;113;116;113;113;116;113;114
22:28:49 INFO - PID 3478 | |1;audio-beat-detection;140;110;114;154;117;150;98;149;164;148
22:28:49 INFO - PID 3478 | |2;audio-dft;200;202;196;217;208;271;277;204;199;201
22:28:49 INFO - PID 3478 | |3;audio-fft;81;56;60;116;79;58;62;81;93;83
22:28:49 INFO - PID 3478 | |4;audio-oscillator;80;70;86;71;68;69;90;70;69;89
22:28:49 INFO - PID 3478 | |5;imaging-gaussian-blur;89;89;90;95;102;136;136;127;89;92
22:28:49 INFO - PID 3478 | |6;imaging-darkroom;94;96;95;96;97;96;96;96;96;96
22:28:49 INFO - PID 3478 | |7;imaging-desaturate;93;94;90;122;138;91;95;92;92;103
22:28:49 INFO - PID 3478 | |8;json-parse-financial;49;49;53;50;51;49;50;52;51;49
22:28:49 INFO - PID 3478 | |9;json-stringify-tinderbox;55;61;61;72;67;62;57;59;59;60
22:28:49 INFO - PID 3478 | |10;stanford-crypto-aes;81;84;85;86;87;102;107;105;110;83
22:28:49 INFO - PID 3478 | |11;stanford-crypto-ccm;142;150;147;140;136;144;156;161;190;149
22:28:49 INFO - PID 3478 | |12;stanford-crypto-pbkdf2;228;281;189;184;181;186;182;189;183;184
22:28:49 INFO - PID 3478 | |13;stanford-crypto-sha256-iterative;71;71;67;69;70;69;66;70;66;69
22:28:49 INFO - PID 3478 | __end_tp_report
22:28:49 INFO - PID 3478 | __start_cc_report
22:28:49 INFO - PID 3478 | _x_x_mozilla_cycle_collect,914
22:28:49 INFO - PID 3478 | __end_cc_report
22:28:49 INFO - PID 3478 | __startTimestamp1495430929589__endTimestamp
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | ------- Summary: start -------
22:28:49 INFO - PID 3478 | Number of tests: 14
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#0] ai-astar Cycles:10 Average:122.90 Median:116.00 stddev:18.82 (16.2%) stddev-sans-first:17.64
22:28:49 INFO - PID 3478 | Values: 148.0 167.0 116.0 113.0 116.0 113.0 113.0 116.0 113.0 114.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:149.50 stddev:22.54 (15.1%) stddev-sans-first:23.82
22:28:49 INFO - PID 3478 | Values: 140.0 110.0 114.0 154.0 117.0 150.0 98.0 149.0 164.0 148.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#2] audio-dft Cycles:10 Average:217.50 Median:206.00 stddev:30.37 (14.7%) stddev-sans-first:31.54
22:28:49 INFO - PID 3478 | Values: 200.0 202.0 196.0 217.0 208.0 271.0 277.0 204.0 199.0 201.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#3] audio-fft Cycles:10 Average:76.90 Median:80.00 stddev:18.75 (23.4%) stddev-sans-first:19.83
22:28:49 INFO - PID 3478 | Values: 81.0 56.0 60.0 116.0 79.0 58.0 62.0 81.0 93.0 83.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:75.50 stddev:9.07 (12.0%) stddev-sans-first:9.51
22:28:49 INFO - PID 3478 | Values: 80.0 70.0 86.0 71.0 68.0 69.0 90.0 70.0 69.0 89.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#5] imaging-gaussian-blur Cycles:10 Average:104.50 Median:89.00 stddev:20.21 (22.7%) stddev-sans-first:20.64
22:28:49 INFO - PID 3478 | Values: 89.0 89.0 90.0 95.0 102.0 136.0 136.0 127.0 89.0 92.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.50
22:28:49 INFO - PID 3478 | Values: 94.0 96.0 95.0 96.0 97.0 96.0 96.0 96.0 96.0 96.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#7] imaging-desaturate Cycles:10 Average:101.00 Median:92.00 stddev:16.15 (17.5%) stddev-sans-first:16.86
22:28:49 INFO - PID 3478 | Values: 93.0 94.0 90.0 122.0 138.0 91.0 95.0 92.0 92.0 103.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.42 (2.8%) stddev-sans-first:1.42
22:28:49 INFO - PID 3478 | Values: 49.0 49.0 53.0 50.0 51.0 49.0 50.0 52.0 51.0 49.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:4.92 (8.1%) stddev-sans-first:4.66
22:28:49 INFO - PID 3478 | Values: 55.0 61.0 61.0 72.0 67.0 62.0 57.0 59.0 59.0 60.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:83.50 stddev:11.47 (13.7%) stddev-sans-first:11.31
22:28:49 INFO - PID 3478 | Values: 81.0 84.0 85.0 86.0 87.0 102.0 107.0 105.0 110.0 83.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:149.50 stddev:15.42 (10.3%) stddev-sans-first:15.97
22:28:49 INFO - PID 3478 | Values: 142.0 150.0 147.0 140.0 136.0 144.0 156.0 161.0 190.0 149.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.70 Median:187.50 stddev:32.06 (17.1%) stddev-sans-first:32.21
22:28:49 INFO - PID 3478 | Values: 228.0 281.0 189.0 184.0 181.0 186.0 182.0 189.0 183.0 184.0
22:28:49 INFO - PID 3478 |
22:28:49 INFO - PID 3478 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:1.87 (2.7%) stddev-sans-first:1.81
22:28:49 INFO - PID 3478 | Values: 71.0 71.0 67.0 69.0 70.0 69.0 66.0 70.0 66.0 69.0
22:28:49 INFO - PID 3478 | -------- Summary: end --------
22:28:49 INFO - PID 3478 |
22:28:50 INFO - TEST-INFO | 3478: exit 0
22:28:50 INFO - TEST-OK | kraken | took 60803ms
22:28:50 INFO - SUITE-END | took 1174s
22:28:50 INFO - Completed test suite (00:19:34)
22:28:50 INFO - javascript benchmark
22:28:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7912.532079168813, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2868.627078479797, 3527.1182204448905, 3562.091666625012, 3570.8571657133716, 3592.461130830375, 4663.323818046386, 5420.322459692538, 5668.631538949965, 6104.816855494342, 6121.6020958637655, 6139.522803442366, 6151.8769624607485, 6152.661603611799, 6171.598846075013, 6192.597481163722, 5425.864353391157, 5491.945080549203, 5536.2249285099915, 5605.327360716719, 5616.213730077772, 4330.0, 5492.450754924515, 5606.691631960241, 5613.186088017235, 5627.7467513961765, 4601.792919318638, 4754.952450475503, 4899.583535399495, 5491.478309560605, 5610.214569960209, 4906.681065730716, 5380.0, 5476.397596264408, 5496.890062198733, 5507.779688812443, 5199.350081239845, 5441.700706461183, 5466.398696143421, 5510.889782204334, 5538.0, 6095.05526643371, 6191.628502289877, 6233.0338797486465, 6276.0, 6310.92714238576, 6477.028445733178, 6778.084958530634, 6781.38967492928, 6793.694283757242, 6818.0, 5901.203337549418, 6705.832354191184, 6844.93155068448, 6850.691718872663, 6860.108185935796, 6293.748250070037, 6308.400701933309, 6322.873542529123, 6345.238571371418, 6356.300806911236, 7008.544444611084, 7021.0, 7032.015517827508, 7034.2965703429245, 7035.92964070363, 6397.808065758035, 6454.677266136674, 6570.540062195696, 6590.670466476657, 6631.104800851823, 6739.427148692416, 6830.282820303796, 6842.4183944364295, 6849.760258390932, 6855.760048398282, 6044.607100538451, 6379.3620637936765, 6500.0, 6530.020496925499, 6648.1025061616065, 6615.702293396808, 6661.034150048215, 6692.765753198712, 6711.127553418024, 6716.966415167991, 5341.4925582069645, 5388.191771234347, 5397.217403476474, 5399.622026458189, 5449.455054494598, 4706.811727530929, 4753.025629745894, 4803.5916947059195, 4813.879653008633, 4842.782074806642, 6314.905276420857, 6339.936600634027, 6359.395857393541, 6377.681115944184, 6422.6467544285515, 6882.03651488792, 6885.931140688629, 6886.690098945459, 6890.517663763489, 6915.273896240923, 6252.406021427957, 6366.140571022853, 6382.808515744535, 6407.359264073649, 6417.711202995876, 5877.647341159544, 5980.880382392328, 6003.699815009232, 6007.609505382136, 6045.516358691294, 6962.512624116263, 7005.124359455068, 7044.612546310104, 7047.119110111236, 7048.964755176191, 6796.150481189851, 6819.318068193141, 6834.8633027340165, 6853.554518956252, 6905.482088843457, 6796.93203067963, 6863.828404289933, 6933.791986240421, 7028.543144695578, 7051.43588512928, 6695.832604184934, 6751.189857217166, 6777.423918966943, 6777.966110169417, 6785.457163426815, 6799.777707208488, 6876.621785801829, 6909.205441374306, 6911.3779759821855, 6919.757808476679, 6428.228612566335, 6474.320196379313, 6629.370209830058, 6631.0716499689115, 6685.8997115044285, 5924.526037917042, 6198.132261483486, 6547.541672083099, 6614.437772789367, 6663.533552651462, 6289.528285378524, 6295.1186833842385, 6402.295747467868, 6468.514861385509, 6507.7722279721875, 5624.915626265692, 6496.317886621837, 6512.772052978123, 6682.064510968566, 6715.0, 6300.527460440394, 6331.113644089924, 6438.098666186644, 6449.322821103904, 6491.350864913471], "value": 6114.524622329393, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.8209402884348, 810.335524869607, 824.5052968219068, 1222.7248869004475, 1277.2591896699914, 38500.22999540008, 38774.61225387752, 38807.0, 38823.835284941495, 38931.0, 39249.95375947931, 39261.80369098158, 39547.40678889819, 39594.8020259899, 39622.00944976377, 7558.395328373717, 7798.5320880747195, 7916.881246781303, 8258.587070646474, 8524.275436587897, 7742.0, 8227.95886020569, 8435.072142064368, 8535.658573657047, 8565.772899046231, 8508.276796472308, 8560.571971401436, 8579.056303806576, 8581.056083830774, 8591.35564832637, 8440.029396619375, 8527.616257268437, 8536.317094632415, 8548.871766923501, 8562.571871406468, 7115.5730656160795, 7921.287084162453, 8250.917490825135, 8297.83404331916, 8310.25207731301, 36907.0, 36916.63083369159, 36919.630803692155, 36931.0, 36938.89183324505, 3642.453631955201, 3642.763220390666, 3682.760620559655, 3683.0055884911194, 3683.0608194910396, 30122.945696900722, 30235.09294721162, 30279.848600757075, 30353.241168970733, 30412.695873041208, 3045.4670432674193, 3096.628404591441, 3109.7667674924473, 3117.283024904262, 3123.687631236881, 299832.0, 302032.9796702027, 304405.0, 304424.0, 305988.0, 46711.766441168256, 46845.765771170925, 46846.76576617095, 46953.0, 47039.76480117646, 49127.0, 49167.508324917006, 51546.74226628843, 54511.727441363335, 54597.72701136469, 13947.86052139486, 13990.0, 14031.78952315716, 14162.575122746333, 14166.079204851652, 11176.050035747052, 11221.0, 11256.943715281372, 11343.659690209306, 11395.544178232944, 14053.400120977447, 14064.085834420934, 14090.084144530572, 14090.295485225697, 14153.85846141546, 6223.564350495421, 6286.874262514724, 6304.621722696653, 6306.801707675527, 6309.495240380758, 1018.2414101494434, 1050.3855244681795, 1057.461393672205, 1094.5731164845718, 1100.8844071372391, 24125.0, 24286.635700464507, 24422.26733198007, 24436.8232129822, 24466.87766561156, 1030.6341248856697, 1099.7133353975867, 1144.6794897428733, 1170.7365842685329, 1179.6637958181877, 12309.522857257007, 12556.372181390894, 12568.74862502763, 16483.83516164847, 16515.33938642465, 339127.0, 341109.58890411275, 341202.0, 342068.0, 342975.0, 56283.71858140683, 56622.0, 56896.146557801665, 56913.0, 56961.0, 1497.161589509878, 1501.5795577238391, 1542.3367951780617, 1543.8919275650596, 1549.147968617256, 26294.62315469964, 29595.40809183847, 30280.84859575688, 30457.086287411414, 30506.694933050385, 38776.030599235244, 38890.02774930649, 38897.80551097226, 38898.0, 39413.21173576455, 294550.0, 295220.0, 295400.0, 295748.0, 296246.0, 32354.676453235166, 33032.83483582567, 34216.0, 34318.656813431546, 34358.82820585981, 41119.177616446905, 41560.0, 42924.0, 42974.140517190106, 43276.0, 29251.268718282212, 29823.10530684083, 30325.545116823705, 30337.089887303417, 30704.232394190323, 29300.853495732386, 29832.552511711907, 30336.84831575828, 30358.69641303559, 30464.695353046187, 29157.7084229155, 33839.0, 36133.457998129525, 36490.452643210905, 36590.26819463649, 29636.555451668675, 29716.85141574278, 30235.244118897204, 30597.847010764803, 30791.384172316877, 22021.669674954566, 22890.771092288865, 23540.411489712893, 24049.75950240475, 24467.38831529226], "value": 17780.17290588505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.7641473604254, 441.2036274524484, 445.5633479190394, 459.7976890168326, 463.7912939177369, 20951.0, 21272.57454850904, 21577.784222157774, 21806.781932180675, 21810.0, 21541.815200163986, 21672.34982950514, 21964.670529942025, 22062.558748825013, 22721.659175112385, 5965.403459654043, 6030.698465076762, 6230.096635987779, 6242.531810114237, 6248.718807653644, 5934.080217566284, 6085.0, 6156.907646385308, 6157.230346206724, 6165.383461653843, 5892.175095486636, 6001.189839371695, 6078.787242446493, 6130.908036379458, 6147.846303842417, 6052.667103309316, 6094.116353128816, 6128.877422451571, 6141.0, 6141.938580614182, 5373.194020896858, 5383.300170977787, 5448.019356515825, 5450.345958484968, 5473.124300112003, 18985.62028759431, 26729.663516824083, 27516.03693870705, 27603.86198069017, 27645.86177069122, 2043.948901277465, 2243.9439014024615, 2248.7638797926147, 2267.0591704442636, 2267.501149747053, 13739.519116830963, 16417.42539011129, 17127.0, 18024.188911498884, 18092.54768630795, 2065.896705164751, 2160.25471212433, 2179.455136215946, 2192.561487702466, 2210.2264207527396, 161735.0, 162521.9999999988, 164512.0, 164784.3521564781, 167985.16007420007, 12760.0, 12771.808422873479, 12841.357932103358, 12987.896028837466, 13167.934160329138, 16347.34610615586, 16529.00825950447, 16532.256048477848, 16558.83441165573, 16570.337186512646, 8147.837043259102, 8588.711693246063, 8753.0, 9074.45553266806, 9252.444853308823, 8623.655053797778, 8631.741047768577, 8642.740717778477, 8795.780105497413, 8812.515311657791, 6661.966690166615, 7983.481073730305, 8015.318697910627, 8057.234562716532, 8070.435069545193, 2177.466520702438, 2179.2372669565843, 2195.9121635134734, 2196.099599164335, 2373.4778348763243, 830.9542975136426, 946.9857952130586, 976.3848775271533, 977.5161295158941, 1014.4826138669332, 15465.690686186213, 15994.840051599567, 18124.093795310182, 21375.03812328449, 21390.82350470739, 865.6710450029075, 992.8709267795141, 999.3054826895238, 1010.1968934696934, 1035.2649618770756, 9617.471039092778, 10593.84109238362, 10612.310199836986, 10913.39976301311, 11043.0, 536976.3151184297, 539030.0, 540114.0, 557540.0, 558036.0, 96233.51883240679, 128048.0, 137376.0, 140323.0, 141924.72463276054, 1182.266994463421, 1201.1711918776016, 1262.4093642011132, 1266.4301064521003, 1269.4224128021729, 21331.78668213298, 22203.888980554995, 22476.32571022872, 22710.0, 22796.658050128928, 57978.420215797305, 58144.63915206292, 59788.0, 60546.394536055835, 61571.46071348073, 75341.6232918832, 77167.05496505341, 80495.98760031046, 81234.59382703049, 82386.17613824025, 8800.95599522024, 8828.470291782514, 8854.468731876108, 9217.677381291622, 9289.953550232205, 14329.9283503586, 14352.282385880873, 14740.13869211824, 14886.18126003037, 15040.924795375953, 9782.804343913223, 11017.559297628006, 12009.879901200877, 12150.116731906215, 12270.141090123605, 11692.00617947484, 11931.343776092051, 12004.75990480168, 12086.697832554257, 12163.087768417226, 11744.530218791495, 12097.395130243629, 12253.816192757295, 12506.499740010298, 12738.172018818746, 8820.514871682119, 9016.0, 9137.761248493896, 9166.495842728715, 9368.531573421438, 11803.0, 11993.64019079429, 12027.338496382434, 12118.151729378858, 12349.876501234872], "value": 10517.641879769553, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.724128794885, 1429.9564947172157, 1477.79559658878, 1488.5459934719913, 1489.9031562948408, 10077.294589378746, 10415.270931034831, 11513.251638643482, 11833.171677982535, 11996.700082497931, 11544.307341559512, 11870.584529541467, 11891.881081189185, 11907.7618447631, 11914.10644201684, 2509.222141136249, 2553.3744232663025, 2564.0, 2566.0633868638, 2571.627114068459, 2493.9002439902383, 2560.4111054457485, 2560.7695307422327, 2560.7695307422327, 2563.5257477366745, 2507.4608959073794, 2521.3318470605213, 2526.68416447944, 2536.3278731136265, 2543.9618405723836, 2468.7037555493366, 2472.134752836502, 2475.3192871960173, 2493.7880280176028, 2495.201535508638, 2444.340028192378, 2457.5699252630898, 2466.1491785334033, 2469.469064151216, 2472.5920223163157, 11424.828627570592, 11568.190226684135, 11571.884281157165, 11606.129540284428, 11663.766724665544, 6340.556161068727, 6504.8699026019685, 6557.114789503404, 6558.573692709959, 6565.737370505174, 3937.5471820740695, 4212.662986961066, 4251.319788833772, 4287.378330142144, 4316.920769807548, 1204.4339160594507, 1211.9757604847941, 1256.824044633752, 1268.5560053981126, 1271.974560508794, 13649.38577763993, 16287.83712162875, 16418.26117824701, 16447.91776041124, 16450.424235151833, 5104.821331253463, 5603.8792241551855, 5770.42295770428, 6020.247469066367, 6100.420460056288, 13542.864571354161, 13627.727445451035, 13725.656858578615, 13750.0, 13793.655158621115, 6666.500012499082, 6681.1314529110905, 6708.765193218312, 6711.697973591103, 6712.6979285931275, 5888.234529511222, 6142.969285153546, 6180.536459765535, 6231.937680623136, 6265.561410701298, 4114.6091121343425, 4275.251830929575, 4311.288637374798, 4315.611594956469, 4317.805698743564, 1082.7888561730545, 1270.6315168601002, 1276.495784165268, 1284.6017734502334, 1287.8905293050195, 786.9842603147905, 797.7833803449786, 809.6113865344668, 813.4265342933336, 839.6557411461272, 5642.887142257132, 6085.174447665771, 6320.241571011508, 6362.231417318928, 6378.681065946684, 593.8337265565649, 595.6396380190009, 608.3034925010837, 611.0071134406589, 612.788587937161, 3632.654897784706, 3810.8094595270127, 3876.8836934891997, 3980.104476492766, 4275.551067137968, 13700.45198192061, 14588.927055364868, 14910.105393676415, 15012.249387530579, 15014.774778378332, 12127.02983761297, 13721.725565488834, 13739.0, 13961.022728409116, 14920.552383428514, 7383.81540461482, 8441.535715535581, 8600.440971336842, 8629.223369896614, 8724.302055835646, 6638.0, 6843.589384636938, 6872.69072891711, 6961.0, 6998.580085194904, 12690.936545317214, 13542.932285338511, 13620.250886201355, 13697.863021370058, 13786.034977551475, 13192.406341714453, 13229.404676789374, 13485.797713034117, 13921.58235252944, 13963.0, 8504.0, 8822.558872056243, 9172.633094676139, 9188.21900138469, 9351.672691455766, 10939.671809845717, 11047.226694131334, 11059.72350691239, 11072.501737421997, 11076.612318569134, 8854.335924805537, 9039.186473217442, 9044.728658140266, 9046.365983355812, 9276.62893484253, 7669.159401743653, 8440.53577053268, 8850.849389579313, 8873.157050080232, 8962.462252264884, 7227.7831665050135, 8090.312323452573, 8102.108768035393, 8156.592170391576, 8188.50868947865, 5034.622403319692, 5636.84444688846, 5914.112883067574, 6086.60437071589, 6124.479419249235, 5580.972095139498, 5960.165576819336, 6108.602940808833, 6211.844703882439, 6217.347178546188], "value": 5286.89966867025, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.39567163955616, 399.19960479239137, 399.5485101834926, 403.21574537524526, 412.6348181859054, 5031.647784655075, 6089.269287685478, 6111.7249723762425, 6120.969395153029, 6286.2456505219325, 6004.919114559378, 6122.877542449149, 6146.508279337653, 6151.600145990507, 6160.829442405951, 4359.324304732756, 4470.597646211832, 4474.955250447487, 4485.461744590653, 4502.909941801162, 4246.957530424703, 4447.088346888896, 4465.866024019285, 4494.68537202396, 4512.435945506812, 4459.554044595547, 4473.9552604473865, 4480.708753930984, 4513.480949690778, 4521.299198624219, 4358.607725304738, 4424.800883960197, 4437.334399840018, 4452.376667266585, 4475.104979004196, 3358.80762329372, 3780.7731536107644, 3800.6959443244473, 3815.4276858471176, 3816.3321418751607, 5441.80280338325, 5450.454954504517, 5534.861628459281, 5665.376808551097, 5801.970990145064, 5228.0, 5272.710000949946, 5294.549963253128, 5324.281222035053, 5349.277847490617, 5420.970015697005, 5657.321121465409, 5823.816068974134, 6433.549651524395, 6544.280129185786, 4129.752214867118, 4314.374415709736, 4342.478902531717, 4349.412829268072, 4394.670399720002, 6778.559393639398, 6832.555883867532, 6998.230194678581, 7064.894026589605, 7108.822279443004, 4386.363977223285, 4398.23030969579, 4407.316865885793, 4415.536368681306, 4430.734155950653, 3582.0507565495163, 3908.4304865566455, 3960.4257382679925, 4482.932756008662, 4482.977585112054, 3987.5813039631003, 4282.678799090081, 4490.595846373842, 4500.729956202638, 5357.035733567917, 6293.905591416133, 6609.050330152617, 6609.867802644017, 6629.602223866584, 6667.86664266712, 1449.5723761490385, 1498.9400423982918, 1499.1529785671062, 1509.7131545006414, 1512.8260250071382, 886.2334081019872, 931.729798358482, 1000.5047501486665, 1009.0666133826328, 1018.6248564438098, 5339.679619222859, 6251.78118765841, 6259.186305780219, 6274.686265686789, 6289.7169627366875, 5835.708214589254, 6791.864162716718, 6804.9931526419605, 6867.381935625718, 6905.723771049102, 6164.3835616438, 6166.260048794174, 6284.748610055546, 6301.338359472189, 6311.400416960381, 3591.712662986955, 3597.9100522486624, 3606.9819650902105, 3614.8734794282072, 3615.240799432096, 6463.903041454382, 6865.910340346863, 7031.542949708252, 7074.92925070753, 7104.538205016657, 5883.382244864313, 6475.838104047437, 7063.470239731938, 7098.3256590623805, 7111.146662400545, 6221.329415644791, 6505.837354066186, 7051.365377116084, 7137.393321567621, 7163.78508644731, 6991.545549539263, 6997.1253593300835, 7002.334778196063, 7011.26381729911, 7021.507364885392, 5786.627482732228, 6681.532292739462, 6863.519553631198, 6872.69072891711, 6900.481893983316, 6869.793906182823, 7194.0, 7199.832073887473, 7213.350798428167, 7230.13238411394, 3872.5159355080614, 4073.531543872374, 4419.18245124653, 4425.919817685683, 4436.800343984464, 6411.455026322628, 6452.869968375247, 6871.025908702571, 6952.339527744856, 6971.581705097507, 6668.333166683293, 6712.328767123444, 6727.394534491919, 6757.391834734897, 6767.492438067263, 5062.987402519584, 6105.603135796159, 6405.295417504164, 6763.6618169092335, 6790.660466976533, 5023.874403139951, 6555.4427873629365, 6584.8353791155605, 6592.43964263043, 6622.966885165543, 5806.983777838776, 6417.2299324081405, 6436.8390790230615, 6737.9326206737305, 6779.525433219627, 5744.569157313134, 6443.162388889415, 6483.935160648333, 6523.0, 6529.608223506605], "value": 4760.61344143508, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [835.9791005224872, 849.295085079384, 863.0851297624522, 866.8439680857443, 879.2614204068581, 16445.50663480096, 17089.230984605692, 17117.914410427948, 18675.06624668768, 19269.421917342468, 17847.375341863004, 18438.354657586955, 18603.720944185847, 18607.441776746684, 18871.0, 960.3709570231509, 1002.413588050991, 1025.84612308154, 1028.5702873006526, 1033.534909290822, 1000.0749306891133, 1011.9342242754198, 1017.6845177994808, 1018.210886562916, 1018.836986082227, 1057.1965306367179, 1073.5598404654093, 1075.983860242093, 1076.8438576406456, 1079.7732476179972, 1057.3338796558157, 1059.607945060325, 1074.049466222399, 1074.978500429995, 1077.4720387010348, 762.2796457347808, 769.707511145761, 779.8869163971249, 781.7889169924146, 782.1708988472237, 16536.834631653648, 16928.66142677152, 17153.228104735317, 17179.398721044705, 17184.656306873916, 16751.0, 17004.489865303938, 17083.743743843854, 17263.827361726475, 17336.566585835328, 13922.651933701638, 14120.152790832582, 14225.857741422557, 14341.354639041268, 14462.855371446361, 3098.349346637209, 3518.8064656443885, 3541.8052007139595, 3576.5708115026105, 3576.7675101118343, 94358.64103397401, 108717.45641271822, 129673.0, 132094.0, 132535.0, 12009.699757506132, 13427.530036448677, 13516.053876228767, 13727.039107262395, 13794.517191898236, 25210.873945630156, 25794.613080803803, 25889.22332330033, 26167.345816354744, 26294.6055809163, 7856.803579910434, 7939.761807145679, 7978.60106994648, 7979.3217576505485, 7988.760337189894, 3848.692104631679, 3937.5078115235597, 3941.4481972523868, 3947.940780888289, 3949.4273330366937, 3860.3244432224246, 4163.770992595436, 4329.718568293051, 4375.912481750347, 4377.058932329564, 776.3517462918526, 916.2349438219162, 948.5636607160645, 955.6225291010151, 958.5782255807422, 648.3386945315751, 668.3283300283184, 669.778972938929, 678.7861823525574, 687.3744892148221, 11073.667789966314, 12001.759964800654, 12012.279263244232, 12151.453184606715, 12383.876161238453, 644.5616980453243, 650.5933791380387, 654.7839213059679, 683.6274230544366, 685.0204207982533, 5394.0290747665795, 5659.603827732019, 5670.262865827416, 5694.487496125285, 5703.030484817591, 106898.98257630553, 107185.0, 111172.02607603504, 111900.0, 113501.0, 65778.0, 87706.56146719225, 88062.0, 88523.0, 97929.51035244777, 75244.0, 77314.0, 80340.59829700814, 81347.0, 83519.58240208881, 10725.41010244444, 11393.031592314746, 12161.939190304345, 12752.617421477371, 12955.481780728665, 40226.59773402228, 41543.792281038404, 41878.58121418747, 42047.0, 42103.0, 58327.708361457924, 68614.0, 71870.28129718636, 72956.0, 74482.62758686172, 16502.0, 16818.747718784474, 16835.915820420818, 17216.225269862636, 17337.95972241619, 18226.0, 18487.075646217905, 18578.232140630844, 18624.813751862308, 18928.33750818715, 14169.929150354183, 16339.509814705578, 17375.7393639093, 17435.738463923306, 17569.0, 16107.919460402623, 16451.506454806375, 17098.316067357664, 17156.05641689719, 17169.399071032454, 16272.349106035626, 16291.266892990081, 16471.505854824376, 16690.582735431224, 16745.413910513074, 11754.823677645014, 11790.469428875893, 11862.822057669315, 11901.523939042341, 12352.444140013542, 11753.840653829839, 13285.0, 13692.52076177329, 13873.930630346784, 13895.444182232597], "value": 8526.683392810302, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 167.0, 116.0, 113.0, 116.0, 113.0, 113.0, 116.0, 113.0, 114.0], "value": 122.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 114.0, 154.0, 117.0, 150.0, 98.0, 149.0, 164.0, 148.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 202.0, 196.0, 217.0, 208.0, 271.0, 277.0, 204.0, 199.0, 201.0], "value": 217.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 56.0, 60.0, 116.0, 79.0, 58.0, 62.0, 81.0, 93.0, 83.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 70.0, 86.0, 71.0, 68.0, 69.0, 90.0, 70.0, 69.0, 89.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 95.0, 102.0, 136.0, 136.0, 127.0, 89.0, 92.0], "value": 104.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 90.0, 122.0, 138.0, 91.0, 95.0, 92.0, 92.0, 103.0], "value": 101.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 53.0, 50.0, 51.0, 49.0, 50.0, 52.0, 51.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 61.0, 61.0, 72.0, 67.0, 62.0, 57.0, 59.0, 59.0, 60.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 85.0, 86.0, 87.0, 102.0, 107.0, 105.0, 110.0, 83.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 150.0, 147.0, 140.0, 136.0, 144.0, 156.0, 161.0, 190.0, 149.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 281.0, 189.0, 184.0, 181.0, 186.0, 182.0, 189.0, 183.0, 184.0], "value": 198.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 67.0, 69.0, 70.0, 69.0, 66.0, 70.0, 66.0, 69.0], "value": 68.8, "unit": "score"}]}]}
22:28:50 INFO - Return code: 0
22:28:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
22:28:50 INFO - # TBPL SUCCESS #
22:28:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'
22:28:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build
22:28:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType"
22:28:50 INFO - 2017-05-21 22:28:50.929 screenresolution[3485:56120] starting screenresolution argv=screenresolution get
22:28:50 INFO - 2017-05-21 22:28:50.931 screenresolution[3485:56120] Display 0: 1600x1200x32@60
22:28:50 INFO - 2017-05-21 22:28:50.937 screenresolution[3486:56125] starting screenresolution argv=screenresolution list
22:28:50 INFO - Available Modes on Display 0
22:28:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75
22:28:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60
22:28:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75
22:28:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60
22:28:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays:
22:28:51 INFO - Intel Iris:
22:28:51 INFO - Chipset Model: Intel Iris
22:28:51 INFO - Type: GPU
22:28:51 INFO - Bus: Built-In
22:28:51 INFO - VRAM (Dynamic, Max): 1536 MB
22:28:51 INFO - Vendor: Intel (0x8086)
22:28:51 INFO - Device ID: 0x0a2e
22:28:51 INFO - Revision ID: 0x0009
22:28:51 INFO - Displays:
22:28:51 INFO - Display:
22:28:51 INFO - Resolution: 1600 x 1200 @ 60 Hz
22:28:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888)
22:28:51 INFO - Display Serial Number: 1600x1200 60
22:28:51 INFO - Main Display: Yes
22:28:51 INFO - Mirror: Off
22:28:51 INFO - Online: Yes
22:28:51 INFO - Rotation: Supported
22:28:51 INFO - Return code: 0
22:28:51 INFO - Running post-action listener: _resource_record_post_action
22:28:51 INFO - [mozharness: 2017-05-22 05:28:51.179915Z] Finished run-tests step (success)
22:28:51 INFO - Running post-run listener: _resource_record_post_run
22:28:51 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 233472; Write bytes: 565697536; Read time: 2; Write time: 1734
22:28:51 INFO - TinderboxPrint: CPU usage
54.3%
22:28:51 INFO - TinderboxPrint: I/O read bytes / time
233,472 / 2
22:28:51 INFO - TinderboxPrint: I/O write bytes / time
565,697,536 / 1,734
22:28:51 INFO - TinderboxPrint: CPU idle
2,184.7 (45.7%)
22:28:51 INFO - TinderboxPrint: CPU system
966.1 (20.2%)
22:28:51 INFO - TinderboxPrint: CPU user
1,634.9 (34.2%)
22:28:51 INFO - TinderboxPrint: Swap in / out
244,576,256 / 0
22:28:51 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 233980416; Write bytes: 230725120; Read time: 16857; Write time: 483
22:28:51 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 233472; Write bytes: 315627008; Read time: 2; Write time: 1204
22:28:51 INFO - Running post-run listener: _upload_blobber_files
22:28:51 INFO - Blob upload gear active.
22:28:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
22:28:51 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
22:28:51 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
22:28:51 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
22:28:52 INFO - (blobuploader) - INFO - Open directory for files ...
22:28:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ...
22:28:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:28:52 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:28:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded
22:28:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:28:52 INFO - (blobuploader) - INFO - Done attempting.
22:28:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ...
22:28:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:28:52 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:28:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded
22:28:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:28:54 INFO - (blobuploader) - INFO - Done attempting.
22:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
22:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:28:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
22:28:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:28:55 INFO - (blobuploader) - INFO - Done attempting.
22:28:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
22:28:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
22:28:55 INFO - (blobuploader) - INFO - Uploading, attempt #1.
22:28:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
22:28:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
22:28:57 INFO - (blobuploader) - INFO - Done attempting.
22:28:57 INFO - (blobuploader) - INFO - Iteration through files over.
22:28:57 INFO - Return code: 0
22:28:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
22:28:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
22:28:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fb3062874401c26096393434aa87bebde0166e829ce143a5220aaadf4501a40d52ef2725fc209cb9577e3790461eaa1071fb986728cfc861866b321596b3f60", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/325f3e68fbbbc72d1316f9d357eba4dd9259d90d71287d6fc746ec894e599dec13e110a5cab44b329f05c1fd5cb4aaea3d9be1e2b5d46410007a2c495da32608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02f795364fb29760b6c51c9ceea644251779638dc70a95d32d67a32496cbb9bff119cf91ccef0bf0fa2bd36c711f43ae103d530f296bc393c23c1d305c61d837", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87455d4776729fa775d218e9b79eb542387330365d190410b3a680d97e10192b9db3476163e90f40a7eec3ee669a05d880a0a2f6d4b5cc9b80da33ea965ed3a"}
22:28:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
22:28:57 INFO - Writing to file /builds/slave/test/properties/blobber_files
22:28:57 INFO - Contents:
22:28:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fb3062874401c26096393434aa87bebde0166e829ce143a5220aaadf4501a40d52ef2725fc209cb9577e3790461eaa1071fb986728cfc861866b321596b3f60", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/325f3e68fbbbc72d1316f9d357eba4dd9259d90d71287d6fc746ec894e599dec13e110a5cab44b329f05c1fd5cb4aaea3d9be1e2b5d46410007a2c495da32608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02f795364fb29760b6c51c9ceea644251779638dc70a95d32d67a32496cbb9bff119cf91ccef0bf0fa2bd36c711f43ae103d530f296bc393c23c1d305c61d837", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87455d4776729fa775d218e9b79eb542387330365d190410b3a680d97e10192b9db3476163e90f40a7eec3ee669a05d880a0a2f6d4b5cc9b80da33ea965ed3a"}
22:28:57 INFO - Running post-run listener: copy_logs_to_upload_dir
22:28:57 INFO - Copying logs to upload dir...
22:28:57 INFO - mkdir: /builds/slave/test/build/upload/logs
22:28:57 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=1275.870179
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-21 22:28:57.244580) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 22:28:57.252481) =========
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:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dNT7CtzbnB/Render
GIT_SHARE_BASE_DIR=/builds/git-shared
HG_SHARE_BASE_DIR=/builds/hg-shared
HOME=/Users/cltbld
IDLEIZER_DISABLE_SHUTDOWN=true
LOGNAME=cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
PAGER=/bin/cat
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11
PWD=/builds/slave/test/properties
RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg
SHELL=/bin/bash
SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners
TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fb3062874401c26096393434aa87bebde0166e829ce143a5220aaadf4501a40d52ef2725fc209cb9577e3790461eaa1071fb986728cfc861866b321596b3f60", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/325f3e68fbbbc72d1316f9d357eba4dd9259d90d71287d6fc746ec894e599dec13e110a5cab44b329f05c1fd5cb4aaea3d9be1e2b5d46410007a2c495da32608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02f795364fb29760b6c51c9ceea644251779638dc70a95d32d67a32496cbb9bff119cf91ccef0bf0fa2bd36c711f43ae103d530f296bc393c23c1d305c61d837", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87455d4776729fa775d218e9b79eb542387330365d190410b3a680d97e10192b9db3476163e90f40a7eec3ee669a05d880a0a2f6d4b5cc9b80da33ea965ed3a"}
build_url:https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg
program finished with exit code 0
elapsedTime=0.011107
build_url: 'https://queue.taskcluster.net/v1/task/VhaFt28oRZSQJdgmE_hNHQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg'
blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1fb3062874401c26096393434aa87bebde0166e829ce143a5220aaadf4501a40d52ef2725fc209cb9577e3790461eaa1071fb986728cfc861866b321596b3f60", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/325f3e68fbbbc72d1316f9d357eba4dd9259d90d71287d6fc746ec894e599dec13e110a5cab44b329f05c1fd5cb4aaea3d9be1e2b5d46410007a2c495da32608", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02f795364fb29760b6c51c9ceea644251779638dc70a95d32d67a32496cbb9bff119cf91ccef0bf0fa2bd36c711f43ae103d530f296bc393c23c1d305c61d837", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87455d4776729fa775d218e9b79eb542387330365d190410b3a680d97e10192b9db3476163e90f40a7eec3ee669a05d880a0a2f6d4b5cc9b80da33ea965ed3a"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 22:28:57.288457) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:28:57.288764) =========
rm -f oauth.txt
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dNT7CtzbnB/Render
GIT_SHARE_BASE_DIR=/builds/git-shared
HG_SHARE_BASE_DIR=/builds/hg-shared
HOME=/Users/cltbld
IDLEIZER_DISABLE_SHUTDOWN=true
LOGNAME=cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
PAGER=/bin/cat
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11
PWD=/builds/slave/test
RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg
SHELL=/bin/bash
SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XVvM02IiK9/Listeners
TMPDIR=/var/folders/3s/p289pc_x541gq_zzpzjxs8pm00000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPCOM_DEBUG_BREAK=warn
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
program finished with exit code 0
elapsedTime=0.005286
========= master_lag: 0.04 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 22:28:57.337574) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 22:28:57.337855) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 22:28:57.338156) =========
========= Total master_lag: 0.19 =========