builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0382 starttime: 1477668477.66 results: success (0) buildid: 20161028035458 builduid: 416795ccdd68432d9f6167d12b710dda revision: 03c8f612cf5a53eb8a0625ba178580e9aeab7935 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-28 08:27:57.656461) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-28 08:27:57.657261) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-28 08:27:57.657762) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-28 08:27:57.683597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:27:57.684296) ========= 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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 --2016-10-28 08:27:57-- 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% 55.6M=0s 2016-10-28 08:27:57 (55.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102088 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:27:57.813692) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:27:57.814029) ========= 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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.018785 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:27:57.864979) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-28 08:27:57.865803) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 03c8f612cf5a53eb8a0625ba178580e9aeab7935 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 03c8f612cf5a53eb8a0625ba178580e9aeab7935 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 2016-10-28 08:27:57,947 truncating revision to first 12 chars 2016-10-28 08:27:57,947 Setting DEBUG logging. 2016-10-28 08:27:57,947 attempt 1/10 2016-10-28 08:27:57,948 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/03c8f612cf5a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-28 08:28:00,031 unpacking tar archive at: larch-03c8f612cf5a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.346747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-28 08:28:00.236682) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-28 08:28:00.237054) ========= script_repo_revision: 03c8f612cf5a53eb8a0625ba178580e9aeab7935 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-28 08:28:00.237592) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-28 08:28:00.237900) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-28 08:28:00.256513) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-28 08:28:00.256846) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:00 INFO - MultiFileLogger online at 20161028 08:28:00 in /builds/slave/test 08:28:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 08:28:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:28:00 INFO - {'append_to_log': False, 08:28:00 INFO - 'base_work_dir': '/builds/slave/test', 08:28:00 INFO - 'blob_upload_branch': 'Larch', 08:28:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:28:00 INFO - 'branch': 'Larch', 08:28:00 INFO - 'buildbot_json_path': 'buildprops.json', 08:28:00 INFO - 'config_files': ('talos/mac_config.py',), 08:28:00 INFO - 'default_actions': ('clobber', 08:28:00 INFO - 'read-buildbot-config', 08:28:00 INFO - 'download-and-extract', 08:28:00 INFO - 'populate-webroot', 08:28:00 INFO - 'create-virtualenv', 08:28:00 INFO - 'install', 08:28:00 INFO - 'run-tests'), 08:28:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:28:00 INFO - 'download_minidump_stackwalk': True, 08:28:00 INFO - 'download_symbols': 'ondemand', 08:28:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:28:00 INFO - 'tooltool.py': '/tools/tooltool.py', 08:28:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:28:00 INFO - '/tools/misc-python/virtualenv.py')}, 08:28:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:28:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:28:00 INFO - 'installer_path': 'installer.exe', 08:28:00 INFO - 'log_level': 'info', 08:28:00 INFO - 'log_name': 'talos', 08:28:00 INFO - 'log_to_console': True, 08:28:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:28:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:28:00 INFO - 'opt_config_files': (), 08:28:00 INFO - 'pip_index': False, 08:28:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:28:00 INFO - 'cmd': ('bash', 08:28:00 INFO - '-c', 08:28:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:28:00 INFO - 'enabled': True, 08:28:00 INFO - 'halt_on_failure': False, 08:28:00 INFO - 'name': 'check_screen_resolution'},), 08:28:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:28:00 INFO - 'cmd': ('bash', 08:28:00 INFO - '-c', 08:28:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:28:00 INFO - 'enabled': True, 08:28:00 INFO - 'halt_on_failure': False, 08:28:00 INFO - 'name': 'check_screen_resolution'},), 08:28:00 INFO - 'run_cmd_checks_enabled': True, 08:28:00 INFO - 'sps_profile': False, 08:28:00 INFO - 'sps_profile_interval': 0, 08:28:00 INFO - 'suite': 'dromaeojs-e10s', 08:28:00 INFO - 'system_bits': '32', 08:28:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:28:00 INFO - 'title': 't-yosemite-r7-0382', 08:28:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:28:00 INFO - 'use_talos_json': True, 08:28:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:28:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:28:00 INFO - 'work_dir': 'build'} 08:28:00 INFO - [mozharness: 2016-10-28 15:28:00.455801Z] Running clobber step. 08:28:00 INFO - Running pre-action listener: _resource_record_pre_action 08:28:00 INFO - Running main action method: clobber 08:28:00 INFO - rmtree: /builds/slave/test/build 08:28:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:28:01 INFO - Running post-action listener: _resource_record_post_action 08:28:01 INFO - [mozharness: 2016-10-28 15:28:01.968094Z] Finished clobber step (success) 08:28:01 INFO - [mozharness: 2016-10-28 15:28:01.968255Z] Running read-buildbot-config step. 08:28:01 INFO - Running pre-action listener: _resource_record_pre_action 08:28:01 INFO - Running main action method: read_buildbot_config 08:28:01 INFO - Using buildbot properties: 08:28:01 INFO - { 08:28:01 INFO - "project": "", 08:28:01 INFO - "product": "firefox", 08:28:01 INFO - "script_repo_revision": "production", 08:28:01 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 08:28:01 INFO - "repository": "", 08:28:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 08:28:01 INFO - "buildid": "20161028035458", 08:28:01 INFO - "pgo_build": "False", 08:28:01 INFO - "basedir": "/builds/slave/test", 08:28:01 INFO - "buildnumber": 4, 08:28:01 INFO - "slavename": "t-yosemite-r7-0382", 08:28:01 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:28:01 INFO - "platform": "yosemite_r7", 08:28:01 INFO - "scheduler": "tests-larch-macosx64-talos", 08:28:01 INFO - "branch": "larch", 08:28:01 INFO - "repo_path": "projects/larch", 08:28:01 INFO - "revision": "03c8f612cf5a53eb8a0625ba178580e9aeab7935", 08:28:01 INFO - "stage_platform": "macosx64", 08:28:01 INFO - "builduid": "416795ccdd68432d9f6167d12b710dda", 08:28:01 INFO - "slavebuilddir": "test" 08:28:01 INFO - } 08:28:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 08:28:01 INFO - Running post-action listener: _resource_record_post_action 08:28:01 INFO - [mozharness: 2016-10-28 15:28:01.972297Z] Finished read-buildbot-config step (success) 08:28:01 INFO - [mozharness: 2016-10-28 15:28:01.972419Z] Running download-and-extract step. 08:28:01 INFO - Running pre-action listener: _resource_record_pre_action 08:28:01 INFO - Running main action method: download_and_extract 08:28:01 INFO - mkdir: /builds/slave/test/build/tests 08:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:01 INFO - trying https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 08:28:01 INFO - Downloading https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:28:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 08:28:03 INFO - Downloaded 1199 bytes. 08:28:03 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 08:28:03 INFO - Using the following test package requirements: 08:28:03 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:28:03 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:28:03 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 08:28:03 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:28:03 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 08:28:03 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:28:03 INFO - u'jsshell-mac.zip'], 08:28:03 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:28:03 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 08:28:03 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 08:28:03 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:28:03 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 08:28:03 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:28:03 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 08:28:03 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:28:03 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 08:28:03 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 08:28:03 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 08:28:03 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:28:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:28:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:28:03 INFO - Fetch https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:28:04 INFO - Http code: 200 08:28:04 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 08:28:04 INFO - x-amz-cf-id: yIYYCmD28MS0CR-Oxzj9TYklbpLdSSYIdTOzoAa8l-oTeoIKnlGX4w== 08:28:04 INFO - x-amz-version-id: 1vOEMg_H43B8f3Z5K5qG6P8_rbMYHKu_ 08:28:04 INFO - Expected file size: 16409322 08:28:04 INFO - Obtained file size: 16409322 08:28:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:28:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 08:28:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:28:10 INFO - Fetch https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 08:28:11 INFO - Http code: 200 08:28:11 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 08:28:11 INFO - x-amz-cf-id: RgQK1-DYtCxvKjRb_yawkQEeP8IkSRn2Z8U1iW0OMYbggH3Fk2oogw== 08:28:11 INFO - x-amz-version-id: 1vOEMg_H43B8f3Z5K5qG6P8_rbMYHKu_ 08:28:11 INFO - Expected file size: 16409322 08:28:11 INFO - Obtained file size: 16409322 08:28:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 08:28:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:28:17 INFO - Fetch https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 08:28:19 INFO - Http code: 200 08:28:19 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 08:28:19 INFO - x-amz-cf-id: bxoVLwGh9ihVTM-Uz9yPsKplJgbAh3iQISwaYWNBFi8Qj3Syltyo8A== 08:28:19 INFO - x-amz-version-id: UEOF4eqx8EZZvaBbxdNd4zbJXdotBvBw 08:28:19 INFO - Expected file size: 14409028 08:28:19 INFO - Obtained file size: 14409028 08:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:19 INFO - trying https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:28:19 INFO - Downloading https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 08:28:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 08:28:22 INFO - Downloaded 111299542 bytes. 08:28:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:28:22 INFO - mkdir: /builds/slave/test/properties 08:28:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:28:22 INFO - Writing to file /builds/slave/test/properties/build_url 08:28:22 INFO - Contents: 08:28:22 INFO - build_url:https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 08:28:23 INFO - Running post-action listener: _resource_record_post_action 08:28:23 INFO - Running post-action listener: set_extra_try_arguments 08:28:23 INFO - [mozharness: 2016-10-28 15:28:23.120405Z] Finished download-and-extract step (success) 08:28:23 INFO - [mozharness: 2016-10-28 15:28:23.120555Z] Running populate-webroot step. 08:28:23 INFO - Running pre-action listener: _resource_record_pre_action 08:28:23 INFO - Running main action method: populate_webroot 08:28:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:28:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:28:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:28:23 INFO - '--tppagecycles', 08:28:23 INFO - '7'], 08:28:23 INFO - 'tests': ['tsvgm']}}, 08:28:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:28:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:28:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:28:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:28:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:28:23 INFO - 'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:28:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:28:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:28:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:28:23 INFO - 'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['damp', 'tps']}, 08:28:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:28:23 INFO - 'tests': ['damp', 'tps']}, 08:28:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['dromaeo_dom']}, 08:28:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:28:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['basic_compositor_video']}, 08:28:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:28:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['a11yr', 08:28:23 INFO - 'ts_paint', 08:28:23 INFO - 'tpaint', 08:28:23 INFO - 'sessionrestore', 08:28:23 INFO - 'sessionrestore_no_auto_restore', 08:28:23 INFO - 'tabpaint']}, 08:28:23 INFO - 'other-e10s': {'tests': ['a11yr', 08:28:23 INFO - 'ts_paint', 08:28:23 INFO - 'tpaint', 08:28:23 INFO - 'sessionrestore', 08:28:23 INFO - 'sessionrestore_no_auto_restore', 08:28:23 INFO - 'tabpaint']}, 08:28:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:28:23 INFO - 'ts_paint', 08:28:23 INFO - 'tpaint', 08:28:23 INFO - 'sessionrestore', 08:28:23 INFO - 'sessionrestore_no_auto_restore', 08:28:23 INFO - 'tabpaint']}, 08:28:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:28:23 INFO - 'ts_paint', 08:28:23 INFO - 'tpaint', 08:28:23 INFO - 'sessionrestore', 08:28:23 INFO - 'sessionrestore_no_auto_restore', 08:28:23 INFO - 'tabpaint']}, 08:28:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['a11yr', 08:28:23 INFO - 'ts_paint', 08:28:23 INFO - 'tpaint', 08:28:23 INFO - 'sessionrestore', 08:28:23 INFO - 'sessionrestore_no_auto_restore', 08:28:23 INFO - 'tabpaint']}, 08:28:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['a11yr', 08:28:23 INFO - 'ts_paint', 08:28:23 INFO - 'tpaint', 08:28:23 INFO - 'sessionrestore', 08:28:23 INFO - 'sessionrestore_no_auto_restore', 08:28:23 INFO - 'tabpaint']}, 08:28:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['tsvgx', 08:28:23 INFO - 'tsvgr_opacity', 08:28:23 INFO - 'tart', 08:28:23 INFO - 'tscrollx', 08:28:23 INFO - 'cart']}, 08:28:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:28:23 INFO - 'tsvgr_opacity', 08:28:23 INFO - 'tart', 08:28:23 INFO - 'tscrollx', 08:28:23 INFO - 'cart']}, 08:28:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:28:23 INFO - 'talos_options': ['--disable-e10s'], 08:28:23 INFO - 'tests': ['tp5o']}, 08:28:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:28:23 INFO - 'tests': ['tp5o']}, 08:28:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:28:23 INFO - 'talos_options': ['--disable-e10s', 08:28:23 INFO - '--xperf_path', 08:28:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:28:23 INFO - 'tests': ['tp5n']}, 08:28:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:28:23 INFO - 'talos_options': ['--xperf_path', 08:28:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:28:23 INFO - 'tests': ['tp5n']}}, 08:28:23 INFO - 'talos.zip': {'path': '', 08:28:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:28:23 INFO - Running post-action listener: _resource_record_post_action 08:28:23 INFO - [mozharness: 2016-10-28 15:28:23.139172Z] Finished populate-webroot step (success) 08:28:23 INFO - [mozharness: 2016-10-28 15:28:23.139303Z] Running create-virtualenv step. 08:28:23 INFO - Running pre-action listener: _resource_record_pre_action 08:28:23 INFO - Running main action method: create_virtualenv 08:28:23 INFO - Creating virtualenv /builds/slave/test/build/venv 08:28:23 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 08:28:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:28:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:28:23 INFO - Using real prefix '/tools/python27' 08:28:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:28:23 INFO - Installing distribute.............................................................................................................................................................................................done. 08:28:26 INFO - Installing pip.................done. 08:28:26 INFO - Return code: 0 08:28:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:28:27 INFO - Reading from file tmpfile_stdout 08:28:27 INFO - Output received: 08:28:27 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) 08:28:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:28:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:27 INFO - 'HOME': '/Users/cltbld', 08:28:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:27 INFO - 'LOGNAME': 'cltbld', 08:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:27 INFO - 'MOZ_NO_REMOTE': '1', 08:28:27 INFO - 'NO_EM_RESTART': '1', 08:28:27 INFO - 'PAGER': '/bin/cat', 08:28:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:27 INFO - 'PWD': '/builds/slave/test', 08:28:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:27 INFO - 'SHELL': '/bin/bash', 08:28:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:27 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:27 INFO - 'USER': 'cltbld', 08:28:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:27 INFO - 'XPC_FLAGS': '0x0', 08:28:27 INFO - 'XPC_SERVICE_NAME': '0', 08:28:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:27 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 08:28:27 INFO - Cleaning up... 08:28:27 INFO - Return code: 0 08:28:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:28:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:27 INFO - 'HOME': '/Users/cltbld', 08:28:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:27 INFO - 'LOGNAME': 'cltbld', 08:28:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:27 INFO - 'MOZ_NO_REMOTE': '1', 08:28:27 INFO - 'NO_EM_RESTART': '1', 08:28:27 INFO - 'PAGER': '/bin/cat', 08:28:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:27 INFO - 'PWD': '/builds/slave/test', 08:28:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:27 INFO - 'SHELL': '/bin/bash', 08:28:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:27 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:27 INFO - 'USER': 'cltbld', 08:28:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:27 INFO - 'XPC_FLAGS': '0x0', 08:28:27 INFO - 'XPC_SERVICE_NAME': '0', 08:28:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:27 INFO - Downloading/unpacking psutil>=3.1.1 08:28:27 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 08:28:27 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 08:28:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:27 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 08:28:27 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 08:28:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:28:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:28:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:28:31 INFO - Installing collected packages: psutil 08:28:31 INFO - Running setup.py install for psutil 08:28:31 INFO - building 'psutil._psutil_osx' extension 08:28:31 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 08:28:31 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 08:28:31 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 08:28:31 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 08:28:31 INFO - building 'psutil._psutil_posix' extension 08:28:31 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 08:28:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:28:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:28:31 INFO - ^ 08:28:31 INFO - 1 warning generated. 08:28:31 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 08:28:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:28:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:28:31 INFO - Successfully installed psutil 08:28:31 INFO - Cleaning up... 08:28:32 INFO - Return code: 0 08:28:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:28:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:32 INFO - 'HOME': '/Users/cltbld', 08:28:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:32 INFO - 'LOGNAME': 'cltbld', 08:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:32 INFO - 'MOZ_NO_REMOTE': '1', 08:28:32 INFO - 'NO_EM_RESTART': '1', 08:28:32 INFO - 'PAGER': '/bin/cat', 08:28:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:32 INFO - 'PWD': '/builds/slave/test', 08:28:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:32 INFO - 'SHELL': '/bin/bash', 08:28:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:32 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:32 INFO - 'USER': 'cltbld', 08:28:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:32 INFO - 'XPC_FLAGS': '0x0', 08:28:32 INFO - 'XPC_SERVICE_NAME': '0', 08:28:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:28:32 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 08:28:32 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 08:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:32 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 08:28:32 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 08:28:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:28:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:28:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:28:34 INFO - Installing collected packages: mozsystemmonitor 08:28:34 INFO - Running setup.py install for mozsystemmonitor 08:28:35 INFO - Successfully installed mozsystemmonitor 08:28:35 INFO - Cleaning up... 08:28:35 INFO - Return code: 0 08:28:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:28:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:35 INFO - 'HOME': '/Users/cltbld', 08:28:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:35 INFO - 'LOGNAME': 'cltbld', 08:28:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:35 INFO - 'MOZ_NO_REMOTE': '1', 08:28:35 INFO - 'NO_EM_RESTART': '1', 08:28:35 INFO - 'PAGER': '/bin/cat', 08:28:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:35 INFO - 'PWD': '/builds/slave/test', 08:28:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:35 INFO - 'SHELL': '/bin/bash', 08:28:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:35 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:35 INFO - 'USER': 'cltbld', 08:28:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:35 INFO - 'XPC_FLAGS': '0x0', 08:28:35 INFO - 'XPC_SERVICE_NAME': '0', 08:28:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:35 INFO - Downloading/unpacking jsonschema==2.5.1 08:28:35 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 08:28:35 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 08:28:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:35 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 08:28:35 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 08:28:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:28:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:28: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 08:28: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 08:28:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28: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 08:28: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 08:28:38 INFO - Downloading functools32-3.2.3-2.tar.gz 08:28:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:28:38 INFO - warning: no files found matching '*.txt' 08:28:38 INFO - no previously-included directories found matching 'build' 08:28:38 INFO - no previously-included directories found matching 'dist' 08:28:38 INFO - no previously-included directories found matching '.git*' 08:28:38 INFO - Installing collected packages: jsonschema, functools32 08:28:38 INFO - Running setup.py install for functools32 08:28:38 INFO - warning: no files found matching '*.txt' 08:28:38 INFO - no previously-included directories found matching 'build' 08:28:38 INFO - no previously-included directories found matching 'dist' 08:28:38 INFO - no previously-included directories found matching '.git*' 08:28:38 INFO - Successfully installed jsonschema functools32 08:28:38 INFO - Cleaning up... 08:28:38 INFO - Return code: 0 08:28:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:38 INFO - 'HOME': '/Users/cltbld', 08:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:38 INFO - 'LOGNAME': 'cltbld', 08:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:38 INFO - 'MOZ_NO_REMOTE': '1', 08:28:38 INFO - 'NO_EM_RESTART': '1', 08:28:38 INFO - 'PAGER': '/bin/cat', 08:28:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:38 INFO - 'PWD': '/builds/slave/test', 08:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:38 INFO - 'SHELL': '/bin/bash', 08:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:38 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:38 INFO - 'USER': 'cltbld', 08:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:38 INFO - 'XPC_FLAGS': '0x0', 08:28:38 INFO - 'XPC_SERVICE_NAME': '0', 08:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:28:38 INFO - Cleaning up... 08:28:38 INFO - Return code: 0 08:28:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:28:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:38 INFO - 'HOME': '/Users/cltbld', 08:28:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:38 INFO - 'LOGNAME': 'cltbld', 08:28:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:38 INFO - 'MOZ_NO_REMOTE': '1', 08:28:38 INFO - 'NO_EM_RESTART': '1', 08:28:38 INFO - 'PAGER': '/bin/cat', 08:28:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:38 INFO - 'PWD': '/builds/slave/test', 08:28:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:38 INFO - 'SHELL': '/bin/bash', 08:28:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:38 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:38 INFO - 'USER': 'cltbld', 08:28:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:38 INFO - 'XPC_FLAGS': '0x0', 08:28:38 INFO - 'XPC_SERVICE_NAME': '0', 08:28:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:39 INFO - Downloading/unpacking blobuploader==1.2.4 08:28: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 08:28: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 08:28:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28: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 08:28: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 08:28:41 INFO - Downloading blobuploader-1.2.4.tar.gz 08:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:28:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:28: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 08:28: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 08:28:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28: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 08:28: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 08:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:28:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:28:42 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 08:28:42 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 08:28:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:42 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 08:28:42 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 08:28:42 INFO - Downloading docopt-0.6.1.tar.gz 08:28:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:28:42 INFO - Installing collected packages: blobuploader, requests, docopt 08:28:42 INFO - Running setup.py install for blobuploader 08:28:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:28:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:28:42 INFO - Running setup.py install for requests 08:28:43 INFO - Running setup.py install for docopt 08:28:43 INFO - Successfully installed blobuploader requests docopt 08:28:43 INFO - Cleaning up... 08:28:43 INFO - Return code: 0 08:28:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:43 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:43 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:43 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:43 INFO - 'HOME': '/Users/cltbld', 08:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:43 INFO - 'LOGNAME': 'cltbld', 08:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:43 INFO - 'MOZ_NO_REMOTE': '1', 08:28:43 INFO - 'NO_EM_RESTART': '1', 08:28:43 INFO - 'PAGER': '/bin/cat', 08:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:43 INFO - 'PWD': '/builds/slave/test', 08:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:43 INFO - 'SHELL': '/bin/bash', 08:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:43 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:43 INFO - 'USER': 'cltbld', 08:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:43 INFO - 'XPC_FLAGS': '0x0', 08:28:43 INFO - 'XPC_SERVICE_NAME': '0', 08:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-QwK8_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-BoJVCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ciXncr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-aTWlrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-HJthgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-KjLTus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-5ZExqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-SPTZLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-BKvt9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:28:44 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-nrqZvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:28:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-6LqtGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:28:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-8Zc_Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:28:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-kK_ipg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:28:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-BwKPZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-6ETmPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:28:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Ea5eDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:28:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:28:45 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-FSgiSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:28:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:28:45 INFO - Running setup.py install for manifestparser 08:28:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:28:45 INFO - Running setup.py install for mozcrash 08:28:46 INFO - Running setup.py install for mozdebug 08:28:46 INFO - Running setup.py install for mozdevice 08:28:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:28:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:28:46 INFO - Running setup.py install for mozfile 08:28:46 INFO - Running setup.py install for mozhttpd 08:28:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:28:46 INFO - Running setup.py install for mozinfo 08:28:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:28:46 INFO - Running setup.py install for mozInstall 08:28:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:28:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:28:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:28:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:28:46 INFO - Running setup.py install for mozleak 08:28:47 INFO - Running setup.py install for mozlog 08:28:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:28:47 INFO - Running setup.py install for moznetwork 08:28:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:28:47 INFO - Running setup.py install for mozprocess 08:28:47 INFO - Running setup.py install for mozprofile 08:28:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:28:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:28:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:28:47 INFO - Running setup.py install for mozrunner 08:28:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:28:47 INFO - Running setup.py install for mozscreenshot 08:28:48 INFO - Running setup.py install for moztest 08:28:48 INFO - Running setup.py install for mozversion 08:28:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:28:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:28:48 INFO - Cleaning up... 08:28:48 INFO - Return code: 0 08:28:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:28:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:48 INFO - 'HOME': '/Users/cltbld', 08:28:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:48 INFO - 'LOGNAME': 'cltbld', 08:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:48 INFO - 'MOZ_NO_REMOTE': '1', 08:28:48 INFO - 'NO_EM_RESTART': '1', 08:28:48 INFO - 'PAGER': '/bin/cat', 08:28:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:48 INFO - 'PWD': '/builds/slave/test', 08:28:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:48 INFO - 'SHELL': '/bin/bash', 08:28:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:48 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:48 INFO - 'USER': 'cltbld', 08:28:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:48 INFO - 'XPC_FLAGS': '0x0', 08:28:48 INFO - 'XPC_SERVICE_NAME': '0', 08:28:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:28:48 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-UZGJWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:28:48 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)) 08:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:28:48 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Epql_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:28:48 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-okMt36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ylXPfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-L5tg27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pnvuXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-upWYML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:28:49 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)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-hUa7lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:28:49 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)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-WYzChF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-hyCHIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:28:49 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)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pam68_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-HS58mI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:28:49 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-7BwLCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:28:50 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)) 08:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:28:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-XrOXuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-hZwjlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:28:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-UYderm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:28:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:28:50 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-wrMteM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:28:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:28:50 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:28:50 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:28:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:28:50 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 08:28:50 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 08:28:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:28:50 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 08:28:50 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 08:28:52 INFO - Downloading blessings-1.6.tar.gz 08:28:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:28:53 INFO - Installing collected packages: blessings 08:28:53 INFO - Running setup.py install for blessings 08:28:53 INFO - Successfully installed blessings 08:28:53 INFO - Cleaning up... 08:28:53 INFO - Return code: 0 08:28:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:28:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:28:53 INFO - Reading from file tmpfile_stdout 08:28:53 INFO - Current package versions: 08:28:53 INFO - blessings == 1.6 08:28:53 INFO - blobuploader == 1.2.4 08:28:53 INFO - docopt == 0.6.1 08:28:53 INFO - functools32 == 3.2.3-2 08:28:53 INFO - jsonschema == 2.5.1 08:28:53 INFO - manifestparser == 1.1 08:28:53 INFO - mozInstall == 1.12 08:28:53 INFO - mozcrash == 0.17 08:28:53 INFO - mozdebug == 0.1 08:28:53 INFO - mozdevice == 0.48 08:28:53 INFO - mozfile == 1.2 08:28:53 INFO - mozhttpd == 0.7 08:28:53 INFO - mozinfo == 0.9 08:28:53 INFO - mozleak == 0.1 08:28:53 INFO - mozlog == 3.4 08:28:53 INFO - moznetwork == 0.27 08:28:53 INFO - mozprocess == 0.23 08:28:53 INFO - mozprofile == 0.28 08:28:53 INFO - mozrunner == 6.12 08:28:53 INFO - mozscreenshot == 0.1 08:28:53 INFO - mozsystemmonitor == 0.3 08:28:53 INFO - moztest == 0.7 08:28:53 INFO - mozversion == 1.4 08:28:53 INFO - psutil == 3.1.1 08:28:53 INFO - requests == 1.2.3 08:28:53 INFO - wsgiref == 0.1.2 08:28:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:28:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 08:28:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:28:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:54 INFO - 'HOME': '/Users/cltbld', 08:28:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:54 INFO - 'LOGNAME': 'cltbld', 08:28:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:54 INFO - 'MOZ_NO_REMOTE': '1', 08:28:54 INFO - 'NO_EM_RESTART': '1', 08:28:54 INFO - 'PAGER': '/bin/cat', 08:28:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:54 INFO - 'PWD': '/builds/slave/test', 08:28:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:54 INFO - 'SHELL': '/bin/bash', 08:28:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:54 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:54 INFO - 'USER': 'cltbld', 08:28:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:54 INFO - 'XPC_FLAGS': '0x0', 08:28:54 INFO - 'XPC_SERVICE_NAME': '0', 08:28:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:28:54 INFO - Cleaning up... 08:28:54 INFO - Return code: 0 08:28:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:28:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:28:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:28:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:28:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107ce4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c5a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe684c97b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, '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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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 08:28:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:28:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:28:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:28:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:28:55 INFO - 'HOME': '/Users/cltbld', 08:28:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:28:55 INFO - 'LOGNAME': 'cltbld', 08:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:28:55 INFO - 'MOZ_NO_REMOTE': '1', 08:28:55 INFO - 'NO_EM_RESTART': '1', 08:28:55 INFO - 'PAGER': '/bin/cat', 08:28:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:28:55 INFO - 'PWD': '/builds/slave/test', 08:28:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:28:55 INFO - 'SHELL': '/bin/bash', 08:28:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:28:55 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:28:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:28:55 INFO - 'USER': 'cltbld', 08:28:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:28:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:28:55 INFO - 'XPC_FLAGS': '0x0', 08:28:55 INFO - 'XPC_SERVICE_NAME': '0', 08:28:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:28:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:28:55 INFO - Cleaning up... 08:28:56 INFO - Return code: 0 08:28:56 INFO - Running post-action listener: _resource_record_post_action 08:28:56 INFO - Running post-action listener: _start_resource_monitoring 08:28:56 INFO - Starting resource monitoring. 08:28:56 INFO - [mozharness: 2016-10-28 15:28:56.032546Z] Finished create-virtualenv step (success) 08:28:56 INFO - [mozharness: 2016-10-28 15:28:56.033528Z] Running install step. 08:28:56 INFO - Running pre-action listener: _resource_record_pre_action 08:28:56 INFO - Running main action method: install 08:28:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:28:56 INFO - Reading from file tmpfile_stdout 08:28:56 INFO - Detecting whether we're running mozinstall >=1.0... 08:28:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:28:56 INFO - Reading from file tmpfile_stdout 08:28:56 INFO - Output received: 08:28:56 INFO - Usage: mozinstall [options] installer 08:28:56 INFO - Options: 08:28:56 INFO - -h, --help show this help message and exit 08:28:56 INFO - -d DEST, --destination=DEST 08:28:56 INFO - Directory to install application into. [default: 08:28:56 INFO - "/builds/slave/test"] 08:28:56 INFO - --app=APP Application being installed. [default: firefox] 08:28:56 INFO - mkdir: /builds/slave/test/build/application 08:28:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:28:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:29:30 INFO - Reading from file tmpfile_stdout 08:29:30 INFO - Output received: 08:29:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:29:30 INFO - Running post-action listener: _resource_record_post_action 08:29:30 INFO - [mozharness: 2016-10-28 15:29:30.913063Z] Finished install step (success) 08:29:30 INFO - [mozharness: 2016-10-28 15:29:30.913207Z] Running run-tests step. 08:29:30 INFO - Running pre-action listener: _resource_record_pre_action 08:29:30 INFO - Running main action method: run_tests 08:29:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:29:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:29:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:29:30 INFO - 2016-10-28 08:29:30.925 screenresolution[2392:32672] starting screenresolution argv=screenresolution get 08:29:30 INFO - 2016-10-28 08:29:30.928 screenresolution[2392:32672] Display 0: 1600x1200x32@60 08:29:30 INFO - 2016-10-28 08:29:30.933 screenresolution[2393:32677] starting screenresolution argv=screenresolution list 08:29:30 INFO - Available Modes on Display 0 08:29:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:29:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:29:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:29:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:29:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:29:31 INFO - Intel Iris: 08:29:31 INFO - Chipset Model: Intel Iris 08:29:31 INFO - Type: GPU 08:29:31 INFO - Bus: Built-In 08:29:31 INFO - VRAM (Dynamic, Max): 1536 MB 08:29:31 INFO - Vendor: Intel (0x8086) 08:29:31 INFO - Device ID: 0x0a2e 08:29:31 INFO - Revision ID: 0x0009 08:29:31 INFO - Displays: 08:29:31 INFO - Display: 08:29:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:29:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:29:31 INFO - Display Serial Number: 1600x1200 60 08:29:31 INFO - Main Display: Yes 08:29:31 INFO - Mirror: Off 08:29:31 INFO - Online: Yes 08:29:31 INFO - Rotation: Supported 08:29:31 INFO - Return code: 0 08:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:29:31 INFO - Python 2.7.3 08:29:31 INFO - Return code: 0 08:29:31 INFO - grabbing minidump binary from tooltool 08:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:31 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 0x107af8d30>, '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 0x10791edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107af0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:29:31 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 08:29:31 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 08:29:31 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 08:29:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:29:31 INFO - Return code: 0 08:29:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:29:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:29:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:29:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:29:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:29:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 08:29:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --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 08:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HOOP41Z3Eg/Render', 08:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:29:31 INFO - 'HOME': '/Users/cltbld', 08:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:29:31 INFO - 'LOGNAME': 'cltbld', 08:29:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:29:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:29:31 INFO - 'MOZ_NO_REMOTE': '1', 08:29:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:29:31 INFO - 'NO_EM_RESTART': '1', 08:29:31 INFO - 'PAGER': '/bin/cat', 08:29:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:29:31 INFO - 'PWD': '/builds/slave/test', 08:29:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:29:31 INFO - 'SHELL': '/bin/bash', 08:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p4FWOtnc7m/Listeners', 08:29:31 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 08:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:29:31 INFO - 'USER': 'cltbld', 08:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:29:31 INFO - 'XPC_FLAGS': '0x0', 08:29:31 INFO - 'XPC_SERVICE_NAME': '0', 08:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:29:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 08:29:31 INFO - mozversion application_buildid: 20161028035458 08:29:31 INFO - mozversion application_changeset: 03c8f612cf5a53eb8a0625ba178580e9aeab7935 08:29:31 INFO - mozversion application_display_name: Nightly 08:29:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:29:31 INFO - mozversion application_name: Firefox 08:29:31 INFO - mozversion application_remotingname: firefox 08:29:31 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 08:29:31 INFO - mozversion application_vendor: Mozilla 08:29:31 INFO - mozversion application_version: 52.0a1 08:29:31 INFO - mozversion platform_buildid: 20161028035458 08:29:31 INFO - mozversion platform_changeset: 03c8f612cf5a53eb8a0625ba178580e9aeab7935 08:29:31 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 08:29:31 INFO - mozversion platform_version: 52.0a1 08:29:31 INFO - using testdate: 1477668571 08:29:31 INFO - actual date: 1477668571 08:29:31 INFO - starting webserver on 'localhost:53780' 08:29:31 INFO - SUITE-START | Running 2 tests 08:29:31 INFO - TEST-START | dromaeo_css 08:29:31 INFO - operating with platform_type : mac_ 08:29:31 INFO - Initialising browser for dromaeo_css test... 08:29:31 INFO - TEST-INFO | started process 2403 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmptNwQmg/profile http://localhost:53780/getInfo.html) 08:29:34 INFO - PROCESS | 2403 | 2016-10-28 08:29:34.339 plugin-container[2405:32845] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 08:29:34 INFO - PROCESS | 2403 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:29:34 INFO - PROCESS | 2403 | 2016-10-28 08:29:34.342 plugin-container[2405:32845] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1b, name = 'com.apple.CFPasteboardClient' 08:29:34 INFO - PROCESS | 2403 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:29:34 INFO - PROCESS | 2403 | 2016-10-28 08:29:34.342 plugin-container[2405:32845] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:29:34 INFO - PROCESS | 2403 | __metrics Screen width/height:1600/1200 08:29:34 INFO - PROCESS | 2403 | colorDepth:24 08:29:34 INFO - PROCESS | 2403 | Browser inner width/height: 1024/689 08:29:34 INFO - PROCESS | 2403 | __metrics 08:29:34 ERROR - PROCESS | 2403 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 08:29:35 INFO - TEST-INFO | 2403: exit 0 08:29:35 INFO - Browser initialized. 08:29:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:29:35 INFO - TEST-INFO | started process 2406 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmptNwQmg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:37 INFO - PROCESS | 2406 | 2016-10-28 08:29:37.741 plugin-container[2407:32961] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:29:37 INFO - PROCESS | 2406 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:29:37 INFO - PROCESS | 2406 | 2016-10-28 08:29:37.742 plugin-container[2407:32961] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 08:29:37 INFO - PROCESS | 2406 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:29:37 INFO - PROCESS | 2406 | 2016-10-28 08:29:37.742 plugin-container[2407:32961] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:29:47 INFO - PROCESS | 2406 | RSS: Main: 241455104 08:29:47 INFO - PROCESS | 2406 | 08:30:37 INFO - PROCESS | 2406 | 1477668637074 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:32:50 INFO - PROCESS | 2406 | Cycle 1(1): loaded http://localhost:53780/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53780/tests/dromaeo/cssquery-ext.html) 08:32:51 INFO - PROCESS | 2406 | RSS: Main: 254488576 08:32:51 INFO - PROCESS | 2406 | 08:35:54 INFO - PROCESS | 2406 | Cycle 1(1): loaded http://localhost:53780/tests/dromaeo/cssquery-ext.html (next: http://localhost:53780/tests/dromaeo/cssquery-jquery.html) 08:35:54 INFO - PROCESS | 2406 | RSS: Main: 267497472 08:35:54 INFO - PROCESS | 2406 | 08:38:56 INFO - PROCESS | 2406 | Cycle 1(1): loaded http://localhost:53780/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53780/tests/dromaeo/cssquery-mootools.html) 08:38:57 INFO - PROCESS | 2406 | RSS: Main: 272064512 08:38:57 INFO - PROCESS | 2406 | 08:41:59 INFO - PROCESS | 2406 | Cycle 1(1): loaded http://localhost:53780/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53780/tests/dromaeo/cssquery-prototype.html) 08:41:59 INFO - PROCESS | 2406 | RSS: Main: 272064512 08:41:59 INFO - PROCESS | 2406 | 08:45:02 INFO - PROCESS | 2406 | Cycle 1(1): loaded http://localhost:53780/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53780/tests/dromaeo/cssquery-yui.html) 08:45:03 INFO - PROCESS | 2406 | RSS: Main: 277483520 08:45:03 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | Cycle 1(1): loaded http://localhost:53780/tests/dromaeo/cssquery-yui.html (next: http://localhost:53780/tests/dromaeo/cssquery-dojo.html) 08:48:06 INFO - PROCESS | 2406 | RSS: Main: 280657920 08:48:06 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | __start_tp_report 08:48:06 INFO - PROCESS | 2406 | _x_x_mozilla_page_load 08:48:06 INFO - PROCESS | 2406 | _x_x_mozilla_page_load_details 08:48:06 INFO - PROCESS | 2406 | |i|pagename|runs| 08:48:06 INFO - PROCESS | 2406 | |0;dojo.html;3329.5172200030997;3364.1757769346505;3383.9830800845984;3392.406328892445;3406.097384193191;5508.724563771805;5634.323881134264;5652;5670.858228544288;5722.570807189457;5556.805511807088;5595.7481913313895;5683.204351390808;5727.57043221759;5793.507551858087;5101.438841727407;5190.71451070191;5195.324607800981;5207.375114986207;5290.947090529103;3891.5135608048995;5139.922901156486;5333.4399888011785;5345.545628621572;5369.221462887879;5138.2806407103035;5253.4221235664045;5281.52466278033;5288.841334759925;5296.788128474856;5133.12736834739;5143.897122057538;5160.174372100446;5203.9219411708855;5295.417504074588;5151.484851514856;5182.533571978503;5191.740412979336;5199.688018718889;5205.895882082337;5561.499465048165;5734.5699072569305;5753.194552762617;5755.856103597402;5767.740451679684;6084.330723620398;6100.572959892809;6119.746288422662;6148.569600127993;6176.382361763833;6237.844053898643;6248.468880145193;6254;6261.7182226799905;6265.843353916189;5841.7371218295275;5847.1229315603;5861.794837180678;5874.1314392865215;5902.970485147547;6154.569180157346;6176.258848938156;6179.567430279837;6200.131981522591;6268.0284555892995;6144.907826382608;6146;6159.938400616026;6163.291221509584;6192.8761424771255;6245.843853903689;6253.093301471352;6261.499080073583;6267.592606480564;6292.2134733158355;6063.727132279058;6065.363136870653;6096.345267793795;6138.541671868701;6179.876402471926;6209.410106039919;6215.564910456224;6227.688615569204;6230.127782110508;6281.277653069864;4932.013597280558;4941.579965702884;4947.208446648519;4947.925781113287;4979.6265280104135;3464.497647841049;4369.694121411534;4398.956010439919;4417.050334178103;4446.044100518404;5789.478946894799;5795.855103622359;5809.302883653904;5823.010088285002;5833.88332233353;4904.975475122602;5961.986462301419;6139.201903752483;6273.278572964076;6275.905861412082;5680.403557626472;5699.715014249354;5759.020966435716;5760.481556659921;5843.444872737083;5340;5359.705216213068;5368.516833485005;5429.864253393696;5435.103207970719;6216.533759968021;6241.188645476059;6251.249850018027;6259.029850373199;6283.057541368831;6040.728167232397;6142.1304578115505;6243.719032643633;6265.624062556261;6283.560150789401;6180.134781130555;6206.503479721521;6234.625922444668;6241.188645476241;6293.779717709858;6161.229846269216;6177.011678131477;6192;6204.224471941007;6229.0967809668255;5534.6751029225525;6100.005955098508;6217.440430361289;6225.035119556476;6258.061290806416;5752.597318187687;5763.596548241583;5797.014507533814;5976.103584462192;6119.296280927751;5775.104858746901;5803.245578074823;5806.44838740319;5807.303123625023;5825.475707186373;5616.634918730269;5690.260266165372;5834.824955251349;5912.763489460373;5913.91129133072;6094.481969032682;6146.108814222002;6160.537959653134;6184.536159788124;6196.628202307876;5958.523318134626;5992.460678538952;6024.7288872001745;6035.879282414415;6044.546659000504 08:48:06 INFO - PROCESS | 2406 | |1;ext.html;480.17649730711867;484.9854504364868;498.3173052917506;503.82869824259745;560.126203123128;29643.55534666982;31574.89487867922;38932.80533597332;40224.59775402245;40360;41775.79112104443;41895;42081.789591052;42165.78917105418;42220.15559688812;9371.109744574289;9515.143637072662;9521.476318802463;9537.28470364722;9567.186789122932;8410.621522031523;9243.16811486966;9397.57710903011;9491.098345657152;9494.952525237399;9408.341416100842;9413.435193888354;9464.62141514342;9469.668561600345;9486.620535178585;9369.203617692505;9432.19826314764;9457.054294570557;9457.432554046745;9464.208058622722;9381.906180938171;9454.385464944826;9486.241100711926;9490.90509094907;9492.95253523735;39456.60543394558;39500.407493887615;39717;39743.205135897406;39761.40357894634;3333.2000319923045;3818.8090595470117;4045.6965727570255;4071.75569465833;4188.85339013133;29335.85332073326;30219.546706799414;30247.697523024708;30365.544516832266;30526.931557395386;3123.8906638267554;3265.102096923365;3266.673332666738;3325.0024992502154;3333.8833140840197;470385;474866;476381;476640;478045;56245.71877140588;56322;56397;56611.43388566144;56647.716761415926;46188.307175391725;50173;59639;60050.699746500984;60279.39720602738;14643.707125857636;14705.852941470663;14779.113253204841;14841.257937103102;14864.33110510008;11711;11878.40607969598;11900.285982841056;11924.403779810975;11963.983061439701;14983.550493484994;15061.246937653294;15320.616984575474;15344.693106137815;15462.917595768413;5953.761849526057;6572.504146925289;6664.83337916556;6825.897611535831;6894.896576551356;747.3871425431203;864.4769914201943;963.6868017894212;982.2240430060173;982.2485798364257;21935.451613709785;25363;27086.322841929108;28030;30000.54999175014;974.6783561424854;1003.7189586915675;1005.2561104782409;1034.897833806993;1206.3485717712513;13114.950803935662;13666.385012674453;14608.415663373558;15158.772618410732;15388.61528461775;519334;583283.7552057058;607409;615081;616074.8392516017;55849.44150558442;56771.43228567744;56789.43210567924;56819.715901420226;57109;1321.4978308242805;1347.6024572751062;1373.450619752107;1387.3202130955758;1388.0561218371604;25642.358941026625;29441.705582943898;30250.69749302567;30907;31413.90051348192;47861.84621691963;48323.03353932972;48355.75822120867;48471;48533.27200092072;516572;519642;519841;521004;522493;24676.928306829173;24825.25524234276;25400.74599254058;25734.613980789927;26447;45929.77035114803;45960;46322.768386157855;46849;47540;38224.61775382211;38275.42586861143;38514.229715406094;39461.80269098636;39679.60320396759;37743.62256377401;38061.048473788374;38243.808780955915;38679.80660096794;38854;36777.81611091927;36993.26013479661;37751.05622359353;37847.81076094602;38437;33036.50445243371;33157.66842331546;34017.82991085029;34049;34453.82773086119;25154.24537263885;25379.446877436803;25415.618765718158;25683.743162568135;25838.741612583635 08:48:06 INFO - PROCESS | 2406 | |2;jquery.html;372.8181664124724;374.1824114310232;383.63937898375536;386.4222986634981;405.96883914856267;18524.629507409845;20556.588868222625;21103.050362733695;21164.365069047915;21299.57400851984;19995.600087998268;20760.68858967113;20914.058867350966;20937.267195648154;21226.363209103714;5618.932402843467;5664.716764161786;5725.942740572583;5779.306483222019;5827.184194212814;4075.1238483725992;5660.462256085665;5780.566457515703;5785.681787501686;5802.303723553179;4253.8723838284905;6149;6451.387118223761;6501.609903405811;6565.967170164143;6238.906416403757;6392.488600911939;6470.838229044288;6552.115464412291;6596.076549283104;5727.570432217601;5732.541396688255;5734.111212761987;5734.225879506297;5742.052561327376;25814.483710325687;26345.604815927778;26484.735152648613;27439.862800685867;28764.856175719196;2241.39482339769;2348.9295321140394;2406.446517301031;2664.5203863304596;2700.9594856077174;11647.767044659144;12554.995600352042;13430.328483575782;14450.566483005527;17876;1943.2421355671158;1986.3643634037114;2022.595480903825;2044.601302745965;2067.576146889884;133032;145268;146697.53302466945;147202;147617.78573321408;16536.6692666146;17199.65600687979;21342.039608217667;21391.57216855654;21587;34974.82512587421;35025.64974350275;35162.94511164669;35189;35201.1199720009;8935.821283574292;9800.656977005914;9894.158996485236;10090.192784577217;10158.288919775545;6226;7420.85158296831;7547.132079810783;8049.678012879536;8361.874571881426;8657.874476318164;9086.45481271126;9479;9586.281028922858;9765.658201963039;1567.388718399825;3705.870294539678;3815.6756675682323;3841.2893614681375;3842.3275926712713;826.5330088499978;854.6624083486988;897.2687259883174;931.4364809290417;966.8259713251541;18535.629287414176;19971.500712482306;21469.785302146778;21482.15537426736;21831;917.5916717060977;925.8611208318806;936.9156775890202;942.2556180617372;976.7069879036261;10793.460326983619;10947.178961577914;11317.660470185907;11344.659660210207;11762.235454695418;556631;567594;570068;575577;582181;122607;124385.37807311087;129629.35185324201;131028;136291;966.7873067925045;1004.5379125602159;1023.0281232828843;1024.51335615582;1033.1837848100067;16057.518274451784;19512.902435487733;19592.902035489733;19722.055588881994;19731.901340493205;44086.77956610196;50942.235866462775;54770.90458190894;55361;58313.4168658308;68890.65554672194;75656;78108;78442.6077869607;81395.59302203452;7737.264959828807;7998.880016799636;8248.752537423887;8289.502629841987;8327.333813295043;12286.38568071575;12611.243325400505;12622.936885315514;13001.219926804422;13044.608661740163;11001.22991390595;11230.606928757454;11279.09767218604;11281.153913456688;11553.537858485566;8825.205731484197;9160.35877488596;10862;10916.181286403393;11135.777284454427;11326.716832079264;11362.54549817998;11370.715732106764;11687.707807304885;11823.527058917547;8004.959975200087;8171.428000040178;8192.139825318494;8203;8277.130901255281;8269.29710974574;9447.81104377895;10982.835257471304;11132.944335278271;11417.486213120595 08:48:06 INFO - PROCESS | 2406 | |3;mootools.html;1340.229368113335;1421.8909250784388;1696.480726121709;1798.5144011117006;1917.1660327757427;7796.064472263336;7908.169642187574;8362.70730524431;8940.061293564171;8943.821123577532;7144.214136444987;8524.829503409928;8538.786530336729;8576.95711521443;8586.699465518708;1941.7184508246296;2285.3029825903136;2316.339843144704;2317.119494592061;2327.44141406063;2305.907763689459;2329.312852708447;2344.695189625355;2353.7646235376496;2357.49313897512;1968.9212431502722;2317.316391664463;2323.7792409721133;2346.4720437901506;2353;2311.04091801902;2314.9189778357677;2317.8493397929165;2324.8140148788057;2332.953340933189;2303.37808791627;2332.0904847109473;2335.649652552114;2335.8131349492;2346.9295921122393;8605.354598405145;8655.6537738491;8662.30701543875;8674.739757807276;8740.737777866674;5167.483251674803;5323.121684921984;5324.653897496689;5328.946710532884;5329.120695085307;3440.3807314683345;3505.631908649606;3624.039629498185;3627.2382799612114;3630.4191329387436;1020.454057079459;1057.5558265528496;1059.3590877519143;1074.8548945892283;1079.1798233342638;11321.490532926038;11465.140114491363;11572.07423406134;11587.420628968603;11603.303801771921;4486.439195100613;4556.3393307970155;4730.219513780256;4744.644151688568;4878.999805039958;9778.266630002778;9842.11420972116;9848.753781155385;9874.111329980336;9876.456794876207;5293.073712100367;5325.227841962894;5327.653702509325;5339.46605339463;5374.54316383104;4369.497507786582;4529.456465224128;4837.806487740521;4872.244802055687;4896.478313668464;3905.9218815624095;3936.9015774605864;3993.221321842213;4027.4764280643913;4064.4309796628495;1290.7224946636518;1301.6615679923289;1303.7197002644477;1312.8293321868096;1316.4799904037852;771.3751860992614;928.0997432490608;928.1600151862575;929.8233335666201;950.8240975419569;5566.693831839288;5748.396418376094;5930.911036334458;5963.344032156459;6094.329623741385;426.83993502436584;435.85398891371113;436.87112301871025;985.482621623639;1015.3349556040805;3911.7457365271734;3974.4634474345594;4032.919341613151;4058.939115913264;4097.8975525612095;10019.398836069859;10420.374777513374;10446.791064178673;10451;10458.738531536621;10405.47972601367;10430.061294483532;10438.739031524121;10459.163266938627;10523.26337156407;7114.964425177945;7844.097928738268;8040.718574849852;8137.552434616035;8194.754157375288;5775.306963164279;5786.566007549535;5831.533477321889;5879.794207202727;5886.175935368966;9582.808343832945;9620.086091821266;9783.80432391334;9809.460479673686;9812.705618831447;9846.015398460097;10049.69850904474;10063.69808905734;10087.848682269918;10131.797364052825;6554.541182117397;6574.3754343337305;6764.695588698422;6859.07402500676;7216.170140433917;6952.374286314256;7441.883717442427;8577.699780507652;8583.87124193125;8595.570221488775;6525.249596296487;7427.888581671387;7469.954206410998;7478.813029674302;7520.8871866920945;6709.832254193684;7118.60847653384;7380.18817930038;7397.223291554309;7429.219931907288;6935.89596156048;6958.199807022055;7024.6136462493005;7045.929540704527;7054.224035356004;4216.7259128156575;4872.196087645641;4942.505749425029;4973.776180071978;5098.337216161875;4903.583195428429;5043.193089105725;5067.645264831426;5078.466760990191;5167.22491626259 08:48:06 INFO - PROCESS | 2406 | |4;prototype.html;258.90161738539354;297.57298276972534;301.08920515440786;304.5568697545072;311.1691782939551;4439;5395.920815836834;5625.887482250363;5628.943710562899;5733.455321744433;4960.87597810054;5050.545450909417;5521.917171242425;5589.916151257735;5685.346185188704;3663.6336366363284;3956.406539019156;3992.983543310624;4029.3351596986536;4249.660027197817;4239;4246.4691913510815;4247.474596085257;4255.851045213418;4275.46556680415;4253.2131555662145;4257.893552661193;4266.424032755584;4270.1459708058355;4292.399064131024;3642.3576423576424;4065.024394145413;4164.292070348048;4278.893027674302;4301.8920728842795;3661.4507823826375;3665.541807274091;3665.780053196817;3672.210474747915;3675.7794532328144;5492.395836457987;5492.709213334863;5523.585731070186;5529.612927095105;5532.8462776217575;4137.606927341898;4753.952460475385;4871.561559459666;4908.337374454439;4922.655414121013;4912.533309335643;5849.415058494159;5889.705514724246;5892.526868282929;5901.970490147522;3397.69420752131;3426.7601267911255;3592.8742494012845;4062.593740625943;4063.3295506241434;5750.568707346965;5889.941100588982;6203.914314994903;6224.937750622481;6237.407446292595;4125.773082480432;4144.606262405127;4153.7300075494995;4156.750594964251;4166.250074986471;3496.6328535503912;3909.7654140751647;4209.389638502461;4229.175310814423;4242.4272723181975;3903.941440878389;4898.779554920037;4927.802887884516;4932.309476673268;4950.628702847301;4927.630427717935;5843.620164689281;5945.286565612154;5946.791862284799;6035.124906888476;1368.9931550342185;1416.4334266293565;1436.490046033664;1436.7629341158593;1438.9927051064296;791.9049714034353;815.3029160068174;884.6328773558901;887.5296093070683;894.6892802045036;4751.95248047522;5355;5619.044762390404;5635.436456354331;5638.252141836044;5409.945900540944;5716.513316707245;6022.969885150546;6169.5064394848305;6243.189475052275;4903.970166265124;5535.086710692771;5607.495315427857;5642.943570564242;5671.801486947937;2642.7314888853075;2846.3880265742964;3162.778605497639;3243.5566173053235;3277.6886195811358;5245.554127899171;5945.910811337833;5983.700814959235;6253.874922501524;6364.522660800459;5246.137621144515;5749.125437281359;5866.043193501484;6235;6250.812475625738;5343.65266257692;5523.28197334344;6163.476104531165;6238.500919926395;6248.031869960948;4657.627389808875;4951.851444456672;5330.227117067926;6076.392360764065;6135.355787642234;5300.284461597635;6013.789517366871;6054.7275372607455;6088.360722124113;6102.664353460602;5265.578753699771;5727.770889164387;5981.056156748987;6189.721462534107;6251.7809027239255;4090.7136500444685;4098.077932465171;4114.156597897364;4117.958820411757;4126.814293356866;5226.477352264743;6134.263888333428;6136.2636483622255;6148.631082135086;6159.414855588712;5449.430622941979;5561.5272701820795;5637.88724225505;5648.85343569441;5882.352941176573;4812.735299558558;5346.732663366894;5620.8078849248;5640.294963129609;5847.26909136358;5348.930213957302;5392.627244012622;5442.074922195419;5817.650940943557;5829.650220986585;5572.244742905972;5734.283214598176;5750.108733146369;5910.468057874812;5910.931174089034;5422.620416570802;5525.281713377235;5838.569550459994;5848.736806843617;5881.206037184926 08:48:06 INFO - PROCESS | 2406 | |5;yui.html;627.5763859394908;705.3898377903114;709.1065257775205;774.8024253815277;801.4990630855715;15193.696126077486;16006.519804405858;16062.759058614101;16076.115813630246;16077.839221607795;14464.783028254584;14558.053726507796;14568.781468277984;14596.708065838677;14638.487652932123;677.0893148714971;699.1260923845193;856.3448961544392;859.9312055035582;878.0999475537561;656.7602824968876;690.9101816763814;847.9788005299886;880.0011986395423;880.0495464897926;796.7689370082669;952.4333021851987;954.5513608603945;954.6706386296717;955.5461155950937;939.3330735178032;946.1390134977138;947.8104379124203;948.3219498058886;948.8197242523933;645.2869579115057;693.1889689063808;693.3724978894109;698.6820996446604;708.2421808664731;15291.388344466319;15380.461683841011;15397.999130056622;15398.615034624112;15486.148261845594;14626.565317446864;14765.704685906328;14783.852161478355;14807.407703691839;14859.554213373616;11731.882681173165;11858.466369013415;12061.698457538543;12097.092718046182;12316.692082697915;3729.384651532494;3733.831977561016;3922.137129831461;3983.4224037514687;3985.222881538072;110156.34765478445;112004.4399778004;112840.43579782131;113015;114397.99999999917;11039.116870650329;11065.612703555336;11148.832767508495;11198.104151667847;11412.828807567894;21364;24045.879770601034;24052.639210411857;24074.51850962971;24111.276661699827;7828.569428681477;7925.365970722329;7966.920330796734;8108.471202885931;8193.95903020493;4263.70154089217;4309.439772829513;4323.632491238217;4343.478782546114;4452.265376212888;3676.5036720042986;3804.8097595120685;3811.5045044144085;3819.388897776342;3819.923601527954;603.3152372057658;682.6279677575733;683.54885775388;688.6779365425343;716.7527203114917;574.5145352177403;673.9191297044288;674.2616834566143;685.2051620120636;690.8065741592362;9047.954760226157;9277.859855433166;9343.299252556086;9414.95292523533;9667.903320966841;507.2340765444206;555.4806256150512;617.924810829154;667.7946306916064;703.8240439890028;4499.437570303712;4622.653301002371;4641.6286697064825;4735.573798358164;4803.565122675442;83361.16638833535;84575.577122114;85006.57496712476;85613;86011;71921.92117118248;72367.63816180885;72812;115947.84052159371;123974;107113.46443267734;110712;110970.99999999838;113084;113590;7420.81086079597;9118.088191181094;9191.448513089235;9312.72061838146;9422.62309507612;61515;61708.69145654243;61866;62652.68673656602;62909.37090629035;62749.05876411766;67689.02600088611;87786;95398;99167.50416247873;9757.512124393894;10359.585616575252;10457.738556536147;11780.587679431179;11921.761564768483;11913.45125133727;12666.176698514568;13293.20240785556;13297.160101524996;13563.93218033943;10732.624358147428;10790.698598629777;11899.94050029779;12227.02183825274;12461.252324860538;9874;12266.693332666755;12614;12701;12728.74542509163;11641.126915481542;12027.699307517381;12049.759004820029;12329.321887296284;12408.62774116778;10046.799064018825;10401.739956500845;10672.199585031309;10811.513481893491;10983.39591322485;8993.28053755711;9994.850077248702;10355.171586272929;10722.946385268024;10747.086497647788 08:48:06 INFO - PROCESS | 2406 | __end_tp_report 08:48:06 INFO - PROCESS | 2406 | __start_cc_report 08:48:06 INFO - PROCESS | 2406 | _x_x_mozilla_cycle_collect,365 08:48:06 INFO - PROCESS | 2406 | __end_cc_report 08:48:06 INFO - PROCESS | 2406 | __startTimestamp1477669686602__endTimestamp 08:48:06 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | ------- Summary: start ------- 08:48:06 INFO - PROCESS | 2406 | Number of tests: 6 08:48:06 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | [#0] dojo.html Cycles:165 Average:5698.59 Median:5841.74 stddev:652.74 (11.2%) stddev-sans-first:627.73 08:48:06 INFO - PROCESS | 2406 | Values: 3329.5 3364.2 3384.0 3392.4 3406.1 5508.7 5634.3 5652.0 5670.9 5722.6 5556.8 5595.7 5683.2 5727.6 5793.5 5101.4 5190.7 5195.3 5207.4 5290.9 3891.5 5139.9 5333.4 5345.5 5369.2 5138.3 5253.4 5281.5 5288.8 5296.8 5133.1 5143.9 5160.2 5203.9 5295.4 5151.5 5182.5 5191.7 5199.7 5205.9 5561.5 5734.6 5753.2 5755.9 5767.7 6084.3 6100.6 6119.7 6148.6 6176.4 6237.8 6248.5 6254.0 6261.7 6265.8 5841.7 5847.1 5861.8 5874.1 5903.0 6154.6 6176.3 6179.6 6200.1 6268.0 6144.9 6146.0 6159.9 6163.3 6192.9 6245.8 6253.1 6261.5 6267.6 6292.2 6063.7 6065.4 6096.3 6138.5 6179.9 6209.4 6215.6 6227.7 6230.1 6281.3 4932.0 4941.6 4947.2 4947.9 4979.6 3464.5 4369.7 4399.0 4417.1 4446.0 5789.5 5795.9 5809.3 5823.0 5833.9 4905.0 5962.0 6139.2 6273.3 6275.9 5680.4 5699.7 5759.0 5760.5 5843.4 5340.0 5359.7 5368.5 5429.9 5435.1 6216.5 6241.2 6251.2 6259.0 6283.1 6040.7 6142.1 6243.7 6265.6 6283.6 6180.1 6206.5 6234.6 6241.2 6293.8 6161.2 6177.0 6192.0 6204.2 6229.1 5534.7 6100.0 6217.4 6225.0 6258.1 5752.6 5763.6 5797.0 5976.1 6119.3 5775.1 5803.2 5806.4 5807.3 5825.5 5616.6 5690.3 5834.8 5912.8 5913.9 6094.5 6146.1 6160.5 6184.5 6196.6 5958.5 5992.5 6024.7 6035.9 6044.5 08:48:06 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | [#1] ext.html Cycles:180 Average:65856.24 Median:22202.85 stddev:141489.58 (637.3%) stddev-sans-first:141801.35 08:48:06 INFO - PROCESS | 2406 | Values: 480.2 485.0 498.3 503.8 560.1 29643.6 31574.9 38932.8 40224.6 40360.0 41775.8 41895.0 42081.8 42165.8 42220.2 9371.1 9515.1 9521.5 9537.3 9567.2 8410.6 9243.2 9397.6 9491.1 9495.0 9408.3 9413.4 9464.6 9469.7 9486.6 9369.2 9432.2 9457.1 9457.4 9464.2 9381.9 9454.4 9486.2 9490.9 9493.0 39456.6 39500.4 39717.0 39743.2 39761.4 3333.2 3818.8 4045.7 4071.8 4188.9 29335.9 30219.5 30247.7 30365.5 30526.9 3123.9 3265.1 3266.7 3325.0 3333.9 470385.0 474866.0 476381.0 476640.0 478045.0 56245.7 56322.0 56397.0 56611.4 56647.7 46188.3 50173.0 59639.0 60050.7 60279.4 14643.7 14705.9 14779.1 14841.3 14864.3 11711.0 11878.4 11900.3 11924.4 11964.0 14983.6 15061.2 15320.6 15344.7 15462.9 5953.8 6572.5 6664.8 6825.9 6894.9 747.4 864.5 963.7 982.2 982.2 21935.5 25363.0 27086.3 28030.0 30000.5 974.7 1003.7 1005.3 1034.9 1206.3 13115.0 13666.4 14608.4 15158.8 15388.6 519334.0 583283.8 607409.0 615081.0 616074.8 55849.4 56771.4 56789.4 56819.7 57109.0 1321.5 1347.6 1373.5 1387.3 1388.1 25642.4 29441.7 30250.7 30907.0 31413.9 47861.8 48323.0 48355.8 48471.0 48533.3 516572.0 519642.0 519841.0 521004.0 522493.0 24676.9 24825.3 25400.7 25734.6 26447.0 45929.8 45960.0 46322.8 46849.0 47540.0 38224.6 38275.4 38514.2 39461.8 39679.6 37743.6 38061.0 38243.8 38679.8 38854.0 36777.8 36993.3 37751.1 37847.8 38437.0 33036.5 33157.7 34017.8 34049.0 34453.8 25154.2 25379.4 25415.6 25683.7 25838.7 08:48:06 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | [#2] jquery.html Cycles:180 Average:36093.37 Median:35175.97 stddev:96024.73 (273.0%) stddev-sans-first:96256.64 08:48:06 INFO - PROCESS | 2406 | Values: 372.8 374.2 383.6 386.4 406.0 18524.6 20556.6 21103.1 21164.4 21299.6 19995.6 20760.7 20914.1 20937.3 21226.4 5618.9 5664.7 5725.9 5779.3 5827.2 4075.1 5660.5 5780.6 5785.7 5802.3 4253.9 6149.0 6451.4 6501.6 6566.0 6238.9 6392.5 6470.8 6552.1 6596.1 5727.6 5732.5 5734.1 5734.2 5742.1 25814.5 26345.6 26484.7 27439.9 28764.9 2241.4 2348.9 2406.4 2664.5 2701.0 11647.8 12555.0 13430.3 14450.6 17876.0 1943.2 1986.4 2022.6 2044.6 2067.6 133032.0 145268.0 146697.5 147202.0 147617.8 16536.7 17199.7 21342.0 21391.6 21587.0 34974.8 35025.6 35162.9 35189.0 35201.1 8935.8 9800.7 9894.2 10090.2 10158.3 6226.0 7420.9 7547.1 8049.7 8361.9 8657.9 9086.5 9479.0 9586.3 9765.7 1567.4 3705.9 3815.7 3841.3 3842.3 826.5 854.7 897.3 931.4 966.8 18535.6 19971.5 21469.8 21482.2 21831.0 917.6 925.9 936.9 942.3 976.7 10793.5 10947.2 11317.7 11344.7 11762.2 556631.0 567594.0 570068.0 575577.0 582181.0 122607.0 124385.4 129629.4 131028.0 136291.0 966.8 1004.5 1023.0 1024.5 1033.2 16057.5 19512.9 19592.9 19722.1 19731.9 44086.8 50942.2 54770.9 55361.0 58313.4 68890.7 75656.0 78108.0 78442.6 81395.6 7737.3 7998.9 8248.8 8289.5 8327.3 12286.4 12611.2 12622.9 13001.2 13044.6 11001.2 11230.6 11279.1 11281.2 11553.5 8825.2 9160.4 10862.0 10916.2 11135.8 11326.7 11362.5 11370.7 11687.7 11823.5 8005.0 8171.4 8192.1 8203.0 8277.1 8269.3 9447.8 10982.8 11132.9 11417.5 08:48:06 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | [#3] mootools.html Cycles:180 Average:5600.77 Median:4923.04 stddev:3126.08 (63.5%) stddev-sans-first:3118.45 08:48:06 INFO - PROCESS | 2406 | Values: 1340.2 1421.9 1696.5 1798.5 1917.2 7796.1 7908.2 8362.7 8940.1 8943.8 7144.2 8524.8 8538.8 8577.0 8586.7 1941.7 2285.3 2316.3 2317.1 2327.4 2305.9 2329.3 2344.7 2353.8 2357.5 1968.9 2317.3 2323.8 2346.5 2353.0 2311.0 2314.9 2317.8 2324.8 2333.0 2303.4 2332.1 2335.6 2335.8 2346.9 8605.4 8655.7 8662.3 8674.7 8740.7 5167.5 5323.1 5324.7 5328.9 5329.1 3440.4 3505.6 3624.0 3627.2 3630.4 1020.5 1057.6 1059.4 1074.9 1079.2 11321.5 11465.1 11572.1 11587.4 11603.3 4486.4 4556.3 4730.2 4744.6 4879.0 9778.3 9842.1 9848.8 9874.1 9876.5 5293.1 5325.2 5327.7 5339.5 5374.5 4369.5 4529.5 4837.8 4872.2 4896.5 3905.9 3936.9 3993.2 4027.5 4064.4 1290.7 1301.7 1303.7 1312.8 1316.5 771.4 928.1 928.2 929.8 950.8 5566.7 5748.4 5930.9 5963.3 6094.3 426.8 435.9 436.9 985.5 1015.3 3911.7 3974.5 4032.9 4058.9 4097.9 10019.4 10420.4 10446.8 10451.0 10458.7 10405.5 10430.1 10438.7 10459.2 10523.3 7115.0 7844.1 8040.7 8137.6 8194.8 5775.3 5786.6 5831.5 5879.8 5886.2 9582.8 9620.1 9783.8 9809.5 9812.7 9846.0 10049.7 10063.7 10087.8 10131.8 6554.5 6574.4 6764.7 6859.1 7216.2 6952.4 7441.9 8577.7 8583.9 8595.6 6525.2 7427.9 7470.0 7478.8 7520.9 6709.8 7118.6 7380.2 7397.2 7429.2 6935.9 6958.2 7024.6 7045.9 7054.2 4216.7 4872.2 4942.5 4973.8 5098.3 4903.6 5043.2 5067.6 5078.5 5167.2 08:48:06 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | [#4] prototype.html Cycles:180 Average:4746.01 Median:5394.27 stddev:1464.73 (27.2%) stddev-sans-first:1429.60 08:48:06 INFO - PROCESS | 2406 | Values: 258.9 297.6 301.1 304.6 311.2 4439.0 5395.9 5625.9 5628.9 5733.5 4960.9 5050.5 5521.9 5589.9 5685.3 3663.6 3956.4 3993.0 4029.3 4249.7 4239.0 4246.5 4247.5 4255.9 4275.5 4253.2 4257.9 4266.4 4270.1 4292.4 3642.4 4065.0 4164.3 4278.9 4301.9 3661.5 3665.5 3665.8 3672.2 3675.8 5492.4 5492.7 5523.6 5529.6 5532.8 4137.6 4754.0 4871.6 4908.3 4922.7 4912.5 5849.4 5889.7 5892.5 5902.0 3397.7 3426.8 3592.9 4062.6 4063.3 5750.6 5889.9 6203.9 6224.9 6237.4 4125.8 4144.6 4153.7 4156.8 4166.3 3496.6 3909.8 4209.4 4229.2 4242.4 3903.9 4898.8 4927.8 4932.3 4950.6 4927.6 5843.6 5945.3 5946.8 6035.1 1369.0 1416.4 1436.5 1436.8 1439.0 791.9 815.3 884.6 887.5 894.7 4752.0 5355.0 5619.0 5635.4 5638.3 5409.9 5716.5 6023.0 6169.5 6243.2 4904.0 5535.1 5607.5 5642.9 5671.8 2642.7 2846.4 3162.8 3243.6 3277.7 5245.6 5945.9 5983.7 6253.9 6364.5 5246.1 5749.1 5866.0 6235.0 6250.8 5343.7 5523.3 6163.5 6238.5 6248.0 4657.6 4951.9 5330.2 6076.4 6135.4 5300.3 6013.8 6054.7 6088.4 6102.7 5265.6 5727.8 5981.1 6189.7 6251.8 4090.7 4098.1 4114.2 4118.0 4126.8 5226.5 6134.3 6136.3 6148.6 6159.4 5449.4 5561.5 5637.9 5648.9 5882.4 4812.7 5346.7 5620.8 5640.3 5847.3 5348.9 5392.6 5442.1 5817.7 5829.7 5572.2 5734.3 5750.1 5910.5 5910.9 5422.6 5525.3 5838.6 5848.7 5881.2 08:48:06 INFO - PROCESS | 2406 | 08:48:06 INFO - PROCESS | 2406 | [#5] yui.html Cycles:180 Average:21720.03 Median:4333.56 stddev:32582.99 (751.9%) stddev-sans-first:32635.91 08:48:06 INFO - PROCESS | 2406 | Values: 627.6 705.4 709.1 774.8 801.5 15193.7 16006.5 16062.8 16076.1 16077.8 14464.8 14558.1 14568.8 14596.7 14638.5 677.1 699.1 856.3 859.9 878.1 656.8 690.9 848.0 880.0 880.0 796.8 952.4 954.6 954.7 955.5 939.3 946.1 947.8 948.3 948.8 645.3 693.2 693.4 698.7 708.2 15291.4 15380.5 15398.0 15398.6 15486.1 14626.6 14765.7 14783.9 14807.4 14859.6 11731.9 11858.5 12061.7 12097.1 12316.7 3729.4 3733.8 3922.1 3983.4 3985.2 110156.3 112004.4 112840.4 113015.0 114398.0 11039.1 11065.6 11148.8 11198.1 11412.8 21364.0 24045.9 24052.6 24074.5 24111.3 7828.6 7925.4 7966.9 8108.5 8194.0 4263.7 4309.4 4323.6 4343.5 4452.3 3676.5 3804.8 3811.5 3819.4 3819.9 603.3 682.6 683.5 688.7 716.8 574.5 673.9 674.3 685.2 690.8 9048.0 9277.9 9343.3 9415.0 9667.9 507.2 555.5 617.9 667.8 703.8 4499.4 4622.7 4641.6 4735.6 4803.6 83361.2 84575.6 85006.6 85613.0 86011.0 71921.9 72367.6 72812.0 115947.8 123974.0 107113.5 110712.0 110971.0 113084.0 113590.0 7420.8 9118.1 9191.4 9312.7 9422.6 61515.0 61708.7 61866.0 62652.7 62909.4 62749.1 67689.0 87786.0 95398.0 99167.5 9757.5 10359.6 10457.7 11780.6 11921.8 11913.5 12666.2 13293.2 13297.2 13563.9 10732.6 10790.7 11899.9 12227.0 12461.3 9874.0 12266.7 12614.0 12701.0 12728.7 11641.1 12027.7 12049.8 12329.3 12408.6 10046.8 10401.7 10672.2 10811.5 10983.4 8993.3 9994.9 10355.2 10722.9 10747.1 08:48:06 INFO - PROCESS | 2406 | -------- Summary: end -------- 08:48:06 INFO - PROCESS | 2406 | 08:48:07 INFO - TEST-INFO | 2406: exit 0 08:48:07 INFO - TEST-OK | dromaeo_css | took 1115703ms 08:48:07 INFO - TEST-START | kraken 08:48:07 INFO - operating with platform_type : mac_ 08:48:07 INFO - Initialising browser for kraken test... 08:48:07 INFO - TEST-INFO | started process 2412 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpZJLyDW/profile http://localhost:53780/getInfo.html) 08:48:09 INFO - PROCESS | 2412 | 2016-10-28 08:48:09.855 plugin-container[2413:34861] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 08:48:09 INFO - PROCESS | 2412 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:09 INFO - PROCESS | 2412 | 2016-10-28 08:48:09.858 plugin-container[2413:34861] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:48:09 INFO - PROCESS | 2412 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:09 INFO - PROCESS | 2412 | 2016-10-28 08:48:09.858 plugin-container[2413:34861] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:48:09 INFO - PROCESS | 2412 | __metrics Screen width/height:1600/1200 08:48:09 INFO - PROCESS | 2412 | colorDepth:24 08:48:09 INFO - PROCESS | 2412 | Browser inner width/height: 1024/689 08:48:09 INFO - PROCESS | 2412 | __metrics 08:48:09 ERROR - PROCESS | 2412 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 08:48:10 INFO - TEST-INFO | 2412: exit 0 08:48:10 INFO - Browser initialized. 08:48:10 INFO - Running cycle 1/1 for kraken test... 08:48:10 INFO - TEST-INFO | started process 2414 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpZJLyDW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:13 INFO - PROCESS | 2414 | 2016-10-28 08:48:13.014 plugin-container[2415:34979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 08:48:13 INFO - PROCESS | 2414 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:13 INFO - PROCESS | 2414 | 2016-10-28 08:48:13.015 plugin-container[2415:34979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:48:13 INFO - PROCESS | 2414 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:48:13 INFO - PROCESS | 2414 | 2016-10-28 08:48:13.015 plugin-container[2415:34979] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:48:22 INFO - PROCESS | 2414 | RSS: Main: 241278976 08:48:22 INFO - PROCESS | 2414 | 08:48:25 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53780/tests/kraken/driver.html?testName=audio-beat-detection) 08:48:26 INFO - PROCESS | 2414 | RSS: Main: 252051456 08:48:26 INFO - PROCESS | 2414 | 08:48:29 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53780/tests/kraken/driver.html?testName=audio-dft) 08:48:29 INFO - PROCESS | 2414 | RSS: Main: 259616768 08:48:29 INFO - PROCESS | 2414 | 08:48:33 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53780/tests/kraken/driver.html?testName=audio-fft) 08:48:34 INFO - PROCESS | 2414 | RSS: Main: 260370432 08:48:34 INFO - PROCESS | 2414 | 08:48:36 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53780/tests/kraken/driver.html?testName=audio-oscillator) 08:48:36 INFO - PROCESS | 2414 | RSS: Main: 261742592 08:48:36 INFO - PROCESS | 2414 | 08:48:38 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53780/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:48:39 INFO - PROCESS | 2414 | RSS: Main: 262737920 08:48:39 INFO - PROCESS | 2414 | 08:48:42 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53780/tests/kraken/driver.html?testName=imaging-darkroom) 08:48:43 INFO - PROCESS | 2414 | RSS: Main: 263626752 08:48:43 INFO - PROCESS | 2414 | 08:48:45 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53780/tests/kraken/driver.html?testName=imaging-desaturate) 08:48:46 INFO - PROCESS | 2414 | RSS: Main: 263843840 08:48:46 INFO - PROCESS | 2414 | 08:48:49 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53780/tests/kraken/driver.html?testName=json-parse-financial) 08:48:50 INFO - PROCESS | 2414 | RSS: Main: 263909376 08:48:50 INFO - PROCESS | 2414 | 08:48:51 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53780/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:48:52 INFO - PROCESS | 2414 | RSS: Main: 263684096 08:48:52 INFO - PROCESS | 2414 | 08:48:54 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53780/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:48:54 INFO - PROCESS | 2414 | RSS: Main: 263725056 08:48:54 INFO - PROCESS | 2414 | 08:48:57 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53780/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:48:57 INFO - PROCESS | 2414 | RSS: Main: 263831552 08:48:57 INFO - PROCESS | 2414 | 08:49:00 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53780/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:49:01 INFO - PROCESS | 2414 | RSS: Main: 264138752 08:49:01 INFO - PROCESS | 2414 | 08:49:04 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53780/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:49:05 INFO - PROCESS | 2414 | RSS: Main: 264146944 08:49:05 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | Cycle 1(1): loaded http://localhost:53780/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53780/tests/kraken/driver.html?testName=ai-astar) 08:49:07 INFO - PROCESS | 2414 | RSS: Main: 265891840 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | __start_tp_report 08:49:07 INFO - PROCESS | 2414 | _x_x_mozilla_page_load 08:49:07 INFO - PROCESS | 2414 | _x_x_mozilla_page_load_details 08:49:07 INFO - PROCESS | 2414 | |i|pagename|runs| 08:49:07 INFO - PROCESS | 2414 | |0;ai-astar;103;115;115;116;116;115;117;116;116;119 08:49:07 INFO - PROCESS | 2414 | |1;audio-beat-detection;148;120;141;151;115;114;116;134;179;115 08:49:07 INFO - PROCESS | 2414 | |2;audio-dft;201;202;235;265;269;207;281;275;261;272 08:49:07 INFO - PROCESS | 2414 | |3;audio-fft;84;53;60;110;84;86;61;62;79;69 08:49:07 INFO - PROCESS | 2414 | |4;audio-oscillator;86;70;88;74;70;71;89;72;87;107 08:49:07 INFO - PROCESS | 2414 | |5;imaging-gaussian-blur;90;87;92;122;89;91;137;86;89;86 08:49:07 INFO - PROCESS | 2414 | |6;imaging-darkroom;96;95;96;95;96;96;95;101;95;99 08:49:07 INFO - PROCESS | 2414 | |7;imaging-desaturate;134;129;141;132;87;89;94;87;88;91 08:49:07 INFO - PROCESS | 2414 | |8;json-parse-financial;56;57;58;57;58;59;57;58;58;59 08:49:07 INFO - PROCESS | 2414 | |9;json-stringify-tinderbox;74;76;70;75;86;77;70;73;77;75 08:49:07 INFO - PROCESS | 2414 | |10;stanford-crypto-aes;105;111;107;113;96;88;87;88;87;88 08:49:07 INFO - PROCESS | 2414 | |11;stanford-crypto-ccm;146;156;164;153;151;160;149;167;244;173 08:49:07 INFO - PROCESS | 2414 | |12;stanford-crypto-pbkdf2;172;157;175;223;240;186;233;244;190;174 08:49:07 INFO - PROCESS | 2414 | |13;stanford-crypto-sha256-iterative;69;71;69;69;91;90;90;77;70;72 08:49:07 INFO - PROCESS | 2414 | __end_tp_report 08:49:07 INFO - PROCESS | 2414 | __start_cc_report 08:49:07 INFO - PROCESS | 2414 | _x_x_mozilla_cycle_collect,955 08:49:07 INFO - PROCESS | 2414 | __end_cc_report 08:49:07 INFO - PROCESS | 2414 | __startTimestamp1477669747674__endTimestamp 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | ------- Summary: start ------- 08:49:07 INFO - PROCESS | 2414 | Number of tests: 14 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#0] ai-astar Cycles:10 Average:114.80 Median:116.00 stddev:4.32 (3.7%) stddev-sans-first:1.27 08:49:07 INFO - PROCESS | 2414 | Values: 103.0 115.0 115.0 116.0 116.0 115.0 117.0 116.0 116.0 119.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:137.50 stddev:21.59 (15.7%) stddev-sans-first:22.24 08:49:07 INFO - PROCESS | 2414 | Values: 148.0 120.0 141.0 151.0 115.0 114.0 116.0 134.0 179.0 115.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#2] audio-dft Cycles:10 Average:246.80 Median:267.00 stddev:32.41 (12.1%) stddev-sans-first:29.84 08:49:07 INFO - PROCESS | 2414 | Values: 201.0 202.0 235.0 265.0 269.0 207.0 281.0 275.0 261.0 272.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#3] audio-fft Cycles:10 Average:74.80 Median:74.00 stddev:17.11 (23.1%) stddev-sans-first:17.82 08:49:07 INFO - PROCESS | 2414 | Values: 84.0 53.0 60.0 110.0 84.0 86.0 61.0 62.0 79.0 69.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:80.00 stddev:12.11 (15.1%) stddev-sans-first:12.73 08:49:07 INFO - PROCESS | 2414 | Values: 86.0 70.0 88.0 74.0 70.0 71.0 89.0 72.0 87.0 107.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:89.00 stddev:17.65 (19.8%) stddev-sans-first:18.55 08:49:07 INFO - PROCESS | 2414 | Values: 90.0 87.0 92.0 122.0 89.0 91.0 137.0 86.0 89.0 86.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:96.00 stddev:2.01 (2.1%) stddev-sans-first:2.13 08:49:07 INFO - PROCESS | 2414 | Values: 96.0 95.0 96.0 95.0 96.0 96.0 95.0 101.0 95.0 99.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:87.50 stddev:23.34 (26.7%) stddev-sans-first:22.65 08:49:07 INFO - PROCESS | 2414 | Values: 134.0 129.0 141.0 132.0 87.0 89.0 94.0 87.0 88.0 91.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:58.00 stddev:0.95 (1.6%) stddev-sans-first:0.78 08:49:07 INFO - PROCESS | 2414 | Values: 56.0 57.0 58.0 57.0 58.0 59.0 57.0 58.0 58.0 59.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#9] json-stringify-tinderbox Cycles:10 Average:75.30 Median:75.50 stddev:4.52 (6.0%) stddev-sans-first:4.77 08:49:07 INFO - PROCESS | 2414 | Values: 74.0 76.0 70.0 75.0 86.0 77.0 70.0 73.0 77.0 75.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#10] stanford-crypto-aes Cycles:10 Average:97.00 Median:87.50 stddev:10.85 (12.4%) stddev-sans-first:11.12 08:49:07 INFO - PROCESS | 2414 | Values: 105.0 111.0 107.0 113.0 96.0 88.0 87.0 88.0 87.0 88.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#11] stanford-crypto-ccm Cycles:10 Average:166.30 Median:162.00 stddev:28.59 (17.6%) stddev-sans-first:29.36 08:49:07 INFO - PROCESS | 2414 | Values: 146.0 156.0 164.0 153.0 151.0 160.0 149.0 167.0 244.0 173.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.40 Median:206.50 stddev:32.28 (15.6%) stddev-sans-first:32.68 08:49:07 INFO - PROCESS | 2414 | Values: 172.0 157.0 175.0 223.0 240.0 186.0 233.0 244.0 190.0 174.0 08:49:07 INFO - PROCESS | 2414 | 08:49:07 INFO - PROCESS | 2414 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:74.50 stddev:9.64 (12.9%) stddev-sans-first:9.80 08:49:07 INFO - PROCESS | 2414 | Values: 69.0 71.0 69.0 69.0 91.0 90.0 90.0 77.0 70.0 72.0 08:49:07 INFO - PROCESS | 2414 | -------- Summary: end -------- 08:49:07 INFO - PROCESS | 2414 | 08:49:08 INFO - TEST-INFO | 2414: exit 0 08:49:08 INFO - TEST-OK | kraken | took 61349ms 08:49:08 INFO - SUITE-END | took 1177s 08:49:08 INFO - Completed test suite (00:19:37) 08:49:08 INFO - javascript benchmark 08:49:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7396.670264501375, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.5172200030997, 3364.1757769346505, 3383.9830800845984, 3392.406328892445, 3406.097384193191, 5508.724563771805, 5634.323881134264, 5652.0, 5670.858228544288, 5722.570807189457, 5556.805511807088, 5595.7481913313895, 5683.204351390808, 5727.57043221759, 5793.507551858087, 5101.438841727407, 5190.71451070191, 5195.324607800981, 5207.375114986207, 5290.947090529103, 3891.5135608048995, 5139.922901156486, 5333.4399888011785, 5345.545628621572, 5369.221462887879, 5138.2806407103035, 5253.4221235664045, 5281.52466278033, 5288.841334759925, 5296.788128474856, 5133.12736834739, 5143.897122057538, 5160.174372100446, 5203.9219411708855, 5295.417504074588, 5151.484851514856, 5182.533571978503, 5191.740412979336, 5199.688018718889, 5205.895882082337, 5561.499465048165, 5734.5699072569305, 5753.194552762617, 5755.856103597402, 5767.740451679684, 6084.330723620398, 6100.572959892809, 6119.746288422662, 6148.569600127993, 6176.382361763833, 6237.844053898643, 6248.468880145193, 6254.0, 6261.7182226799905, 6265.843353916189, 5841.7371218295275, 5847.1229315603, 5861.794837180678, 5874.1314392865215, 5902.970485147547, 6154.569180157346, 6176.258848938156, 6179.567430279837, 6200.131981522591, 6268.0284555892995, 6144.907826382608, 6146.0, 6159.938400616026, 6163.291221509584, 6192.8761424771255, 6245.843853903689, 6253.093301471352, 6261.499080073583, 6267.592606480564, 6292.2134733158355, 6063.727132279058, 6065.363136870653, 6096.345267793795, 6138.541671868701, 6179.876402471926, 6209.410106039919, 6215.564910456224, 6227.688615569204, 6230.127782110508, 6281.277653069864, 4932.013597280558, 4941.579965702884, 4947.208446648519, 4947.925781113287, 4979.6265280104135, 3464.497647841049, 4369.694121411534, 4398.956010439919, 4417.050334178103, 4446.044100518404, 5789.478946894799, 5795.855103622359, 5809.302883653904, 5823.010088285002, 5833.88332233353, 4904.975475122602, 5961.986462301419, 6139.201903752483, 6273.278572964076, 6275.905861412082, 5680.403557626472, 5699.715014249354, 5759.020966435716, 5760.481556659921, 5843.444872737083, 5340.0, 5359.705216213068, 5368.516833485005, 5429.864253393696, 5435.103207970719, 6216.533759968021, 6241.188645476059, 6251.249850018027, 6259.029850373199, 6283.057541368831, 6040.728167232397, 6142.1304578115505, 6243.719032643633, 6265.624062556261, 6283.560150789401, 6180.134781130555, 6206.503479721521, 6234.625922444668, 6241.188645476241, 6293.779717709858, 6161.229846269216, 6177.011678131477, 6192.0, 6204.224471941007, 6229.0967809668255, 5534.6751029225525, 6100.005955098508, 6217.440430361289, 6225.035119556476, 6258.061290806416, 5752.597318187687, 5763.596548241583, 5797.014507533814, 5976.103584462192, 6119.296280927751, 5775.104858746901, 5803.245578074823, 5806.44838740319, 5807.303123625023, 5825.475707186373, 5616.634918730269, 5690.260266165372, 5834.824955251349, 5912.763489460373, 5913.91129133072, 6094.481969032682, 6146.108814222002, 6160.537959653134, 6184.536159788124, 6196.628202307876, 5958.523318134626, 5992.460678538952, 6024.7288872001745, 6035.879282414415, 6044.546659000504], "value": 5657.090782915548, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.17649730711867, 484.9854504364868, 498.3173052917506, 503.82869824259745, 560.126203123128, 29643.55534666982, 31574.89487867922, 38932.80533597332, 40224.59775402245, 40360.0, 41775.79112104443, 41895.0, 42081.789591052, 42165.78917105418, 42220.15559688812, 9371.109744574289, 9515.143637072662, 9521.476318802463, 9537.28470364722, 9567.186789122932, 8410.621522031523, 9243.16811486966, 9397.57710903011, 9491.098345657152, 9494.952525237399, 9408.341416100842, 9413.435193888354, 9464.62141514342, 9469.668561600345, 9486.620535178585, 9369.203617692505, 9432.19826314764, 9457.054294570557, 9457.432554046745, 9464.208058622722, 9381.906180938171, 9454.385464944826, 9486.241100711926, 9490.90509094907, 9492.95253523735, 39456.60543394558, 39500.407493887615, 39717.0, 39743.205135897406, 39761.40357894634, 3333.2000319923045, 3818.8090595470117, 4045.6965727570255, 4071.75569465833, 4188.85339013133, 29335.85332073326, 30219.546706799414, 30247.697523024708, 30365.544516832266, 30526.931557395386, 3123.8906638267554, 3265.102096923365, 3266.673332666738, 3325.0024992502154, 3333.8833140840197, 470385.0, 474866.0, 476381.0, 476640.0, 478045.0, 56245.71877140588, 56322.0, 56397.0, 56611.43388566144, 56647.716761415926, 46188.307175391725, 50173.0, 59639.0, 60050.699746500984, 60279.39720602738, 14643.707125857636, 14705.852941470663, 14779.113253204841, 14841.257937103102, 14864.33110510008, 11711.0, 11878.40607969598, 11900.285982841056, 11924.403779810975, 11963.983061439701, 14983.550493484994, 15061.246937653294, 15320.616984575474, 15344.693106137815, 15462.917595768413, 5953.761849526057, 6572.504146925289, 6664.83337916556, 6825.897611535831, 6894.896576551356, 747.3871425431203, 864.4769914201943, 963.6868017894212, 982.2240430060173, 982.2485798364257, 21935.451613709785, 25363.0, 27086.322841929108, 28030.0, 30000.54999175014, 974.6783561424854, 1003.7189586915675, 1005.2561104782409, 1034.897833806993, 1206.3485717712513, 13114.950803935662, 13666.385012674453, 14608.415663373558, 15158.772618410732, 15388.61528461775, 519334.0, 583283.7552057058, 607409.0, 615081.0, 616074.8392516017, 55849.44150558442, 56771.43228567744, 56789.43210567924, 56819.715901420226, 57109.0, 1321.4978308242805, 1347.6024572751062, 1373.450619752107, 1387.3202130955758, 1388.0561218371604, 25642.358941026625, 29441.705582943898, 30250.69749302567, 30907.0, 31413.90051348192, 47861.84621691963, 48323.03353932972, 48355.75822120867, 48471.0, 48533.27200092072, 516572.0, 519642.0, 519841.0, 521004.0, 522493.0, 24676.928306829173, 24825.25524234276, 25400.74599254058, 25734.613980789927, 26447.0, 45929.77035114803, 45960.0, 46322.768386157855, 46849.0, 47540.0, 38224.61775382211, 38275.42586861143, 38514.229715406094, 39461.80269098636, 39679.60320396759, 37743.62256377401, 38061.048473788374, 38243.808780955915, 38679.80660096794, 38854.0, 36777.81611091927, 36993.26013479661, 37751.05622359353, 37847.81076094602, 38437.0, 33036.50445243371, 33157.66842331546, 34017.82991085029, 34049.0, 34453.82773086119, 25154.24537263885, 25379.446877436803, 25415.618765718158, 25683.743162568135, 25838.741612583635], "value": 19193.206978131486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.8181664124724, 374.1824114310232, 383.63937898375536, 386.4222986634981, 405.96883914856267, 18524.629507409845, 20556.588868222625, 21103.050362733695, 21164.365069047915, 21299.57400851984, 19995.600087998268, 20760.68858967113, 20914.058867350966, 20937.267195648154, 21226.363209103714, 5618.932402843467, 5664.716764161786, 5725.942740572583, 5779.306483222019, 5827.184194212814, 4075.1238483725992, 5660.462256085665, 5780.566457515703, 5785.681787501686, 5802.303723553179, 4253.8723838284905, 6149.0, 6451.387118223761, 6501.609903405811, 6565.967170164143, 6238.906416403757, 6392.488600911939, 6470.838229044288, 6552.115464412291, 6596.076549283104, 5727.570432217601, 5732.541396688255, 5734.111212761987, 5734.225879506297, 5742.052561327376, 25814.483710325687, 26345.604815927778, 26484.735152648613, 27439.862800685867, 28764.856175719196, 2241.39482339769, 2348.9295321140394, 2406.446517301031, 2664.5203863304596, 2700.9594856077174, 11647.767044659144, 12554.995600352042, 13430.328483575782, 14450.566483005527, 17876.0, 1943.2421355671158, 1986.3643634037114, 2022.595480903825, 2044.601302745965, 2067.576146889884, 133032.0, 145268.0, 146697.53302466945, 147202.0, 147617.78573321408, 16536.6692666146, 17199.65600687979, 21342.039608217667, 21391.57216855654, 21587.0, 34974.82512587421, 35025.64974350275, 35162.94511164669, 35189.0, 35201.1199720009, 8935.821283574292, 9800.656977005914, 9894.158996485236, 10090.192784577217, 10158.288919775545, 6226.0, 7420.85158296831, 7547.132079810783, 8049.678012879536, 8361.874571881426, 8657.874476318164, 9086.45481271126, 9479.0, 9586.281028922858, 9765.658201963039, 1567.388718399825, 3705.870294539678, 3815.6756675682323, 3841.2893614681375, 3842.3275926712713, 826.5330088499978, 854.6624083486988, 897.2687259883174, 931.4364809290417, 966.8259713251541, 18535.629287414176, 19971.500712482306, 21469.785302146778, 21482.15537426736, 21831.0, 917.5916717060977, 925.8611208318806, 936.9156775890202, 942.2556180617372, 976.7069879036261, 10793.460326983619, 10947.178961577914, 11317.660470185907, 11344.659660210207, 11762.235454695418, 556631.0, 567594.0, 570068.0, 575577.0, 582181.0, 122607.0, 124385.37807311087, 129629.35185324201, 131028.0, 136291.0, 966.7873067925045, 1004.5379125602159, 1023.0281232828843, 1024.51335615582, 1033.1837848100067, 16057.518274451784, 19512.902435487733, 19592.902035489733, 19722.055588881994, 19731.901340493205, 44086.77956610196, 50942.235866462775, 54770.90458190894, 55361.0, 58313.4168658308, 68890.65554672194, 75656.0, 78108.0, 78442.6077869607, 81395.59302203452, 7737.264959828807, 7998.880016799636, 8248.752537423887, 8289.502629841987, 8327.333813295043, 12286.38568071575, 12611.243325400505, 12622.936885315514, 13001.219926804422, 13044.608661740163, 11001.22991390595, 11230.606928757454, 11279.09767218604, 11281.153913456688, 11553.537858485566, 8825.205731484197, 9160.35877488596, 10862.0, 10916.181286403393, 11135.777284454427, 11326.716832079264, 11362.54549817998, 11370.715732106764, 11687.707807304885, 11823.527058917547, 8004.959975200087, 8171.428000040178, 8192.139825318494, 8203.0, 8277.130901255281, 8269.29710974574, 9447.81104377895, 10982.835257471304, 11132.944335278271, 11417.486213120595], "value": 10515.934234712393, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.229368113335, 1421.8909250784388, 1696.480726121709, 1798.5144011117006, 1917.1660327757427, 7796.064472263336, 7908.169642187574, 8362.70730524431, 8940.061293564171, 8943.821123577532, 7144.214136444987, 8524.829503409928, 8538.786530336729, 8576.95711521443, 8586.699465518708, 1941.7184508246296, 2285.3029825903136, 2316.339843144704, 2317.119494592061, 2327.44141406063, 2305.907763689459, 2329.312852708447, 2344.695189625355, 2353.7646235376496, 2357.49313897512, 1968.9212431502722, 2317.316391664463, 2323.7792409721133, 2346.4720437901506, 2353.0, 2311.04091801902, 2314.9189778357677, 2317.8493397929165, 2324.8140148788057, 2332.953340933189, 2303.37808791627, 2332.0904847109473, 2335.649652552114, 2335.8131349492, 2346.9295921122393, 8605.354598405145, 8655.6537738491, 8662.30701543875, 8674.739757807276, 8740.737777866674, 5167.483251674803, 5323.121684921984, 5324.653897496689, 5328.946710532884, 5329.120695085307, 3440.3807314683345, 3505.631908649606, 3624.039629498185, 3627.2382799612114, 3630.4191329387436, 1020.454057079459, 1057.5558265528496, 1059.3590877519143, 1074.8548945892283, 1079.1798233342638, 11321.490532926038, 11465.140114491363, 11572.07423406134, 11587.420628968603, 11603.303801771921, 4486.439195100613, 4556.3393307970155, 4730.219513780256, 4744.644151688568, 4878.999805039958, 9778.266630002778, 9842.11420972116, 9848.753781155385, 9874.111329980336, 9876.456794876207, 5293.073712100367, 5325.227841962894, 5327.653702509325, 5339.46605339463, 5374.54316383104, 4369.497507786582, 4529.456465224128, 4837.806487740521, 4872.244802055687, 4896.478313668464, 3905.9218815624095, 3936.9015774605864, 3993.221321842213, 4027.4764280643913, 4064.4309796628495, 1290.7224946636518, 1301.6615679923289, 1303.7197002644477, 1312.8293321868096, 1316.4799904037852, 771.3751860992614, 928.0997432490608, 928.1600151862575, 929.8233335666201, 950.8240975419569, 5566.693831839288, 5748.396418376094, 5930.911036334458, 5963.344032156459, 6094.329623741385, 426.83993502436584, 435.85398891371113, 436.87112301871025, 985.482621623639, 1015.3349556040805, 3911.7457365271734, 3974.4634474345594, 4032.919341613151, 4058.939115913264, 4097.8975525612095, 10019.398836069859, 10420.374777513374, 10446.791064178673, 10451.0, 10458.738531536621, 10405.47972601367, 10430.061294483532, 10438.739031524121, 10459.163266938627, 10523.26337156407, 7114.964425177945, 7844.097928738268, 8040.718574849852, 8137.552434616035, 8194.754157375288, 5775.306963164279, 5786.566007549535, 5831.533477321889, 5879.794207202727, 5886.175935368966, 9582.808343832945, 9620.086091821266, 9783.80432391334, 9809.460479673686, 9812.705618831447, 9846.015398460097, 10049.69850904474, 10063.69808905734, 10087.848682269918, 10131.797364052825, 6554.541182117397, 6574.3754343337305, 6764.695588698422, 6859.07402500676, 7216.170140433917, 6952.374286314256, 7441.883717442427, 8577.699780507652, 8583.87124193125, 8595.570221488775, 6525.249596296487, 7427.888581671387, 7469.954206410998, 7478.813029674302, 7520.8871866920945, 6709.832254193684, 7118.60847653384, 7380.18817930038, 7397.223291554309, 7429.219931907288, 6935.89596156048, 6958.199807022055, 7024.6136462493005, 7045.929540704527, 7054.224035356004, 4216.7259128156575, 4872.196087645641, 4942.505749425029, 4973.776180071978, 5098.337216161875, 4903.583195428429, 5043.193089105725, 5067.645264831426, 5078.466760990191, 5167.22491626259], "value": 4484.369668591869, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.90161738539354, 297.57298276972534, 301.08920515440786, 304.5568697545072, 311.1691782939551, 4439.0, 5395.920815836834, 5625.887482250363, 5628.943710562899, 5733.455321744433, 4960.87597810054, 5050.545450909417, 5521.917171242425, 5589.916151257735, 5685.346185188704, 3663.6336366363284, 3956.406539019156, 3992.983543310624, 4029.3351596986536, 4249.660027197817, 4239.0, 4246.4691913510815, 4247.474596085257, 4255.851045213418, 4275.46556680415, 4253.2131555662145, 4257.893552661193, 4266.424032755584, 4270.1459708058355, 4292.399064131024, 3642.3576423576424, 4065.024394145413, 4164.292070348048, 4278.893027674302, 4301.8920728842795, 3661.4507823826375, 3665.541807274091, 3665.780053196817, 3672.210474747915, 3675.7794532328144, 5492.395836457987, 5492.709213334863, 5523.585731070186, 5529.612927095105, 5532.8462776217575, 4137.606927341898, 4753.952460475385, 4871.561559459666, 4908.337374454439, 4922.655414121013, 4912.533309335643, 5849.415058494159, 5889.705514724246, 5892.526868282929, 5901.970490147522, 3397.69420752131, 3426.7601267911255, 3592.8742494012845, 4062.593740625943, 4063.3295506241434, 5750.568707346965, 5889.941100588982, 6203.914314994903, 6224.937750622481, 6237.407446292595, 4125.773082480432, 4144.606262405127, 4153.7300075494995, 4156.750594964251, 4166.250074986471, 3496.6328535503912, 3909.7654140751647, 4209.389638502461, 4229.175310814423, 4242.4272723181975, 3903.941440878389, 4898.779554920037, 4927.802887884516, 4932.309476673268, 4950.628702847301, 4927.630427717935, 5843.620164689281, 5945.286565612154, 5946.791862284799, 6035.124906888476, 1368.9931550342185, 1416.4334266293565, 1436.490046033664, 1436.7629341158593, 1438.9927051064296, 791.9049714034353, 815.3029160068174, 884.6328773558901, 887.5296093070683, 894.6892802045036, 4751.95248047522, 5355.0, 5619.044762390404, 5635.436456354331, 5638.252141836044, 5409.945900540944, 5716.513316707245, 6022.969885150546, 6169.5064394848305, 6243.189475052275, 4903.970166265124, 5535.086710692771, 5607.495315427857, 5642.943570564242, 5671.801486947937, 2642.7314888853075, 2846.3880265742964, 3162.778605497639, 3243.5566173053235, 3277.6886195811358, 5245.554127899171, 5945.910811337833, 5983.700814959235, 6253.874922501524, 6364.522660800459, 5246.137621144515, 5749.125437281359, 5866.043193501484, 6235.0, 6250.812475625738, 5343.65266257692, 5523.28197334344, 6163.476104531165, 6238.500919926395, 6248.031869960948, 4657.627389808875, 4951.851444456672, 5330.227117067926, 6076.392360764065, 6135.355787642234, 5300.284461597635, 6013.789517366871, 6054.7275372607455, 6088.360722124113, 6102.664353460602, 5265.578753699771, 5727.770889164387, 5981.056156748987, 6189.721462534107, 6251.7809027239255, 4090.7136500444685, 4098.077932465171, 4114.156597897364, 4117.958820411757, 4126.814293356866, 5226.477352264743, 6134.263888333428, 6136.2636483622255, 6148.631082135086, 6159.414855588712, 5449.430622941979, 5561.5272701820795, 5637.88724225505, 5648.85343569441, 5882.352941176573, 4812.735299558558, 5346.732663366894, 5620.8078849248, 5640.294963129609, 5847.26909136358, 5348.930213957302, 5392.627244012622, 5442.074922195419, 5817.650940943557, 5829.650220986585, 5572.244742905972, 5734.283214598176, 5750.108733146369, 5910.468057874812, 5910.931174089034, 5422.620416570802, 5525.281713377235, 5838.569550459994, 5848.736806843617, 5881.206037184926], "value": 4266.124976110031, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.5763859394908, 705.3898377903114, 709.1065257775205, 774.8024253815277, 801.4990630855715, 15193.696126077486, 16006.519804405858, 16062.759058614101, 16076.115813630246, 16077.839221607795, 14464.783028254584, 14558.053726507796, 14568.781468277984, 14596.708065838677, 14638.487652932123, 677.0893148714971, 699.1260923845193, 856.3448961544392, 859.9312055035582, 878.0999475537561, 656.7602824968876, 690.9101816763814, 847.9788005299886, 880.0011986395423, 880.0495464897926, 796.7689370082669, 952.4333021851987, 954.5513608603945, 954.6706386296717, 955.5461155950937, 939.3330735178032, 946.1390134977138, 947.8104379124203, 948.3219498058886, 948.8197242523933, 645.2869579115057, 693.1889689063808, 693.3724978894109, 698.6820996446604, 708.2421808664731, 15291.388344466319, 15380.461683841011, 15397.999130056622, 15398.615034624112, 15486.148261845594, 14626.565317446864, 14765.704685906328, 14783.852161478355, 14807.407703691839, 14859.554213373616, 11731.882681173165, 11858.466369013415, 12061.698457538543, 12097.092718046182, 12316.692082697915, 3729.384651532494, 3733.831977561016, 3922.137129831461, 3983.4224037514687, 3985.222881538072, 110156.34765478445, 112004.4399778004, 112840.43579782131, 113015.0, 114397.99999999917, 11039.116870650329, 11065.612703555336, 11148.832767508495, 11198.104151667847, 11412.828807567894, 21364.0, 24045.879770601034, 24052.639210411857, 24074.51850962971, 24111.276661699827, 7828.569428681477, 7925.365970722329, 7966.920330796734, 8108.471202885931, 8193.95903020493, 4263.70154089217, 4309.439772829513, 4323.632491238217, 4343.478782546114, 4452.265376212888, 3676.5036720042986, 3804.8097595120685, 3811.5045044144085, 3819.388897776342, 3819.923601527954, 603.3152372057658, 682.6279677575733, 683.54885775388, 688.6779365425343, 716.7527203114917, 574.5145352177403, 673.9191297044288, 674.2616834566143, 685.2051620120636, 690.8065741592362, 9047.954760226157, 9277.859855433166, 9343.299252556086, 9414.95292523533, 9667.903320966841, 507.2340765444206, 555.4806256150512, 617.924810829154, 667.7946306916064, 703.8240439890028, 4499.437570303712, 4622.653301002371, 4641.6286697064825, 4735.573798358164, 4803.565122675442, 83361.16638833535, 84575.577122114, 85006.57496712476, 85613.0, 86011.0, 71921.92117118248, 72367.63816180885, 72812.0, 115947.84052159371, 123974.0, 107113.46443267734, 110712.0, 110970.99999999838, 113084.0, 113590.0, 7420.81086079597, 9118.088191181094, 9191.448513089235, 9312.72061838146, 9422.62309507612, 61515.0, 61708.69145654243, 61866.0, 62652.68673656602, 62909.37090629035, 62749.05876411766, 67689.02600088611, 87786.0, 95398.0, 99167.50416247873, 9757.512124393894, 10359.585616575252, 10457.738556536147, 11780.587679431179, 11921.761564768483, 11913.45125133727, 12666.176698514568, 13293.20240785556, 13297.160101524996, 13563.93218033943, 10732.624358147428, 10790.698598629777, 11899.94050029779, 12227.02183825274, 12461.252324860538, 9874.0, 12266.693332666755, 12614.0, 12701.0, 12728.74542509163, 11641.126915481542, 12027.699307517381, 12049.759004820029, 12329.321887296284, 12408.62774116778, 10046.799064018825, 10401.739956500845, 10672.199585031309, 10811.513481893491, 10983.39591322485, 8993.28053755711, 9994.850077248702, 10355.171586272929, 10722.946385268024, 10747.086497647788], "value": 7496.335923302356, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1624.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 115.0, 116.0, 116.0, 115.0, 117.0, 116.0, 116.0, 119.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 120.0, 141.0, 151.0, 115.0, 114.0, 116.0, 134.0, 179.0, 115.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 202.0, 235.0, 265.0, 269.0, 207.0, 281.0, 275.0, 261.0, 272.0], "value": 246.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 53.0, 60.0, 110.0, 84.0, 86.0, 61.0, 62.0, 79.0, 69.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 88.0, 74.0, 70.0, 71.0, 89.0, 72.0, 87.0, 107.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 92.0, 122.0, 89.0, 91.0, 137.0, 86.0, 89.0, 86.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 101.0, 95.0, 99.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 129.0, 141.0, 132.0, 87.0, 89.0, 94.0, 87.0, 88.0, 91.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 58.0, 57.0, 58.0, 59.0, 57.0, 58.0, 58.0, 59.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 70.0, 75.0, 86.0, 77.0, 70.0, 73.0, 77.0, 75.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 111.0, 107.0, 113.0, 96.0, 88.0, 87.0, 88.0, 87.0, 88.0], "value": 97.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 156.0, 164.0, 153.0, 151.0, 160.0, 149.0, 167.0, 244.0, 173.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 157.0, 175.0, 223.0, 240.0, 186.0, 233.0, 244.0, 190.0, 174.0], "value": 199.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 69.0, 91.0, 90.0, 90.0, 77.0, 70.0, 72.0], "value": 76.8, "unit": "score"}]}]} 08:49:08 INFO - Return code: 0 08:49:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:49:08 ERROR - # TBPL SUCCESS # 08:49:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:49:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:49:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:49:08 INFO - 2016-10-28 08:49:08.966 screenresolution[2419:35361] starting screenresolution argv=screenresolution get 08:49:08 INFO - 2016-10-28 08:49:08.971 screenresolution[2419:35361] Display 0: 1600x1200x32@60 08:49:08 INFO - 2016-10-28 08:49:08.977 screenresolution[2420:35364] starting screenresolution argv=screenresolution list 08:49:08 INFO - Available Modes on Display 0 08:49:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:49:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:49:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:49:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:49:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:49:09 INFO - Intel Iris: 08:49:09 INFO - Chipset Model: Intel Iris 08:49:09 INFO - Type: GPU 08:49:09 INFO - Bus: Built-In 08:49:09 INFO - VRAM (Dynamic, Max): 1536 MB 08:49:09 INFO - Vendor: Intel (0x8086) 08:49:09 INFO - Device ID: 0x0a2e 08:49:09 INFO - Revision ID: 0x0009 08:49:09 INFO - Displays: 08:49:09 INFO - Display: 08:49:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:49:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:49:09 INFO - Display Serial Number: 1600x1200 60 08:49:09 INFO - Main Display: Yes 08:49:09 INFO - Mirror: Off 08:49:09 INFO - Online: Yes 08:49:09 INFO - Rotation: Supported 08:49:09 INFO - Return code: 0 08:49:09 INFO - Running post-action listener: _resource_record_post_action 08:49:09 INFO - [mozharness: 2016-10-28 15:49:09.212026Z] Finished run-tests step (success) 08:49:09 INFO - Running post-run listener: _resource_record_post_run 08:49:09 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1082880; Write bytes: 709073920; Read time: 13; Write time: 2036 08:49:09 INFO - TinderboxPrint: CPU usage
54.4% 08:49:09 INFO - TinderboxPrint: I/O read bytes / time
1,082,880 / 13 08:49:09 INFO - TinderboxPrint: I/O write bytes / time
709,073,920 / 2,036 08:49:09 INFO - TinderboxPrint: CPU idle
2,213.5 (45.6%) 08:49:09 INFO - TinderboxPrint: CPU system
980.9 (20.2%) 08:49:09 INFO - TinderboxPrint: CPU user
1,655.4 (34.1%) 08:49:09 INFO - TinderboxPrint: Swap in / out
405,659,648 / 0 08:49:09 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387043840; Write bytes: 381974528; Read time: 29267; Write time: 816 08:49:09 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1082880; Write bytes: 301044736; Read time: 13; Write time: 1152 08:49:09 INFO - Running post-run listener: _upload_blobber_files 08:49:09 INFO - Blob upload gear active. 08:49:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:49:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:49:09 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:49:09 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:49:10 INFO - (blobuploader) - INFO - Open directory for files ... 08:49:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:49:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:49:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:11 INFO - (blobuploader) - INFO - Done attempting. 08:49:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:49:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:49:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:12 INFO - (blobuploader) - INFO - Done attempting. 08:49:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:49:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:49:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:49:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:49:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:49:14 INFO - (blobuploader) - INFO - Done attempting. 08:49:14 INFO - (blobuploader) - INFO - Iteration through files over. 08:49:14 INFO - Return code: 0 08:49:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:49:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:49:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a72089453f95b783b754fd127f75c434119306393465a26f5d657d9deceb7cc27dc471cdb301b2e9b7c641600cf5a406157e2337d217fb6bc4b6ef4782d417bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f9d08d38480ef514138e430df531f5654df76a1a2f2369593225a454d47c3966e6bf885d31d93fedba292af30c3eaec1511c3911b1143910d31536d62d3a060c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/538cadf80e1d9df518d59ba7ef3b8d1e253d609fb3db56e722246e6499d17349742ef0ac282f1a00b072f7e65a0c66b066d9187f32b125b3b2c6320f4c787d24"} 08:49:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:49:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:49:14 INFO - Contents: 08:49:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a72089453f95b783b754fd127f75c434119306393465a26f5d657d9deceb7cc27dc471cdb301b2e9b7c641600cf5a406157e2337d217fb6bc4b6ef4782d417bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f9d08d38480ef514138e430df531f5654df76a1a2f2369593225a454d47c3966e6bf885d31d93fedba292af30c3eaec1511c3911b1143910d31536d62d3a060c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/538cadf80e1d9df518d59ba7ef3b8d1e253d609fb3db56e722246e6499d17349742ef0ac282f1a00b072f7e65a0c66b066d9187f32b125b3b2c6320f4c787d24"} 08:49:14 INFO - Running post-run listener: copy_logs_to_upload_dir 08:49:14 INFO - Copying logs to upload dir... 08:49:14 INFO - mkdir: /builds/slave/test/build/upload/logs 08:49:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.286593 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-28 08:49:14.589784) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-28 08:49:14.594108) ========= 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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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/Larch/sha512/a72089453f95b783b754fd127f75c434119306393465a26f5d657d9deceb7cc27dc471cdb301b2e9b7c641600cf5a406157e2337d217fb6bc4b6ef4782d417bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f9d08d38480ef514138e430df531f5654df76a1a2f2369593225a454d47c3966e6bf885d31d93fedba292af30c3eaec1511c3911b1143910d31536d62d3a060c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/538cadf80e1d9df518d59ba7ef3b8d1e253d609fb3db56e722246e6499d17349742ef0ac282f1a00b072f7e65a0c66b066d9187f32b125b3b2c6320f4c787d24"} build_url:https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010965 build_url: 'https://queue.taskcluster.net/v1/task/VOnPTvsIR_-xJtSfsbZjYQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a72089453f95b783b754fd127f75c434119306393465a26f5d657d9deceb7cc27dc471cdb301b2e9b7c641600cf5a406157e2337d217fb6bc4b6ef4782d417bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f9d08d38480ef514138e430df531f5654df76a1a2f2369593225a454d47c3966e6bf885d31d93fedba292af30c3eaec1511c3911b1143910d31536d62d3a060c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/538cadf80e1d9df518d59ba7ef3b8d1e253d609fb3db56e722246e6499d17349742ef0ac282f1a00b072f7e65a0c66b066d9187f32b125b3b2c6320f4c787d24"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-28 08:49:14.632938) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:49:14.633302) ========= 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.HOOP41Z3Eg/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.p4FWOtnc7m/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.005226 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-28 08:49:14.682315) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-28 08:49:14.682662) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-28 08:49:14.683033) ========= ========= Total master_lag: 0.20 =========