builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0191 starttime: 1480093938.31 results: success (0) buildid: 20161125072418 builduid: c7836604cd5c49c483df1c89e9f6871c revision: e0cfc97fd8d551186352072bbf2ad735ab77f570 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:18.312853) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:18.313355) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:18.313683) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:18.340707) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:18.341088) ========= 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.tws5KJXHrX/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.j7mOEZb5eW/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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-11-25 09:12:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.7M=0.001s 2016-11-25 09:12:18 (16.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.170791 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:18.538222) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:18.538578) ========= 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.tws5KJXHrX/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.j7mOEZb5eW/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.029136 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:18.591412) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 09:12:18.591983) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e0cfc97fd8d551186352072bbf2ad735ab77f570 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e0cfc97fd8d551186352072bbf2ad735ab77f570 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tws5KJXHrX/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.j7mOEZb5eW/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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-11-25 09:12:18,687 truncating revision to first 12 chars 2016-11-25 09:12:18,687 Setting DEBUG logging. 2016-11-25 09:12:18,687 attempt 1/10 2016-11-25 09:12:18,687 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e0cfc97fd8d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 09:12:19,383 unpacking tar archive at: mozilla-beta-e0cfc97fd8d5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.002417 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 09:12:19.619721) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:19.620073) ========= script_repo_revision: e0cfc97fd8d551186352072bbf2ad735ab77f570 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:19.620475) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:19.620781) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 09:12:19.638085) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-11-25 09:12:19.638517) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tws5KJXHrX/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.j7mOEZb5eW/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:12:19 INFO - MultiFileLogger online at 20161125 09:12:19 in /builds/slave/test 09:12:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:12:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:12:19 INFO - {'append_to_log': False, 09:12:19 INFO - 'base_work_dir': '/builds/slave/test', 09:12:19 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:12:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:12:19 INFO - 'branch': 'Mozilla-Beta', 09:12:19 INFO - 'buildbot_json_path': 'buildprops.json', 09:12:19 INFO - 'config_files': ('talos/mac_config.py',), 09:12:19 INFO - 'default_actions': ('clobber', 09:12:19 INFO - 'read-buildbot-config', 09:12:19 INFO - 'download-and-extract', 09:12:19 INFO - 'populate-webroot', 09:12:19 INFO - 'create-virtualenv', 09:12:19 INFO - 'install', 09:12:19 INFO - 'run-tests'), 09:12:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:12:19 INFO - 'download_minidump_stackwalk': True, 09:12:19 INFO - 'download_symbols': 'ondemand', 09:12:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:12:19 INFO - 'tooltool.py': '/tools/tooltool.py', 09:12:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:12:19 INFO - '/tools/misc-python/virtualenv.py')}, 09:12:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:12:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:12:19 INFO - 'installer_path': 'installer.exe', 09:12:19 INFO - 'log_level': 'info', 09:12:19 INFO - 'log_name': 'talos', 09:12:19 INFO - 'log_to_console': True, 09:12:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:12:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:12:19 INFO - 'opt_config_files': (), 09:12:19 INFO - 'pip_index': False, 09:12:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:12:19 INFO - 'cmd': ('bash', 09:12:19 INFO - '-c', 09:12:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:12:19 INFO - 'enabled': True, 09:12:19 INFO - 'halt_on_failure': False, 09:12:19 INFO - 'name': 'check_screen_resolution'},), 09:12:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:12:19 INFO - 'cmd': ('bash', 09:12:19 INFO - '-c', 09:12:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:12:19 INFO - 'enabled': True, 09:12:19 INFO - 'halt_on_failure': False, 09:12:19 INFO - 'name': 'check_screen_resolution'},), 09:12:19 INFO - 'run_cmd_checks_enabled': True, 09:12:19 INFO - 'sps_profile': False, 09:12:19 INFO - 'sps_profile_interval': 0, 09:12:19 INFO - 'suite': 'dromaeojs', 09:12:19 INFO - 'system_bits': '32', 09:12:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:12:19 INFO - 'title': 't-yosemite-r7-0191', 09:12:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:12:19 INFO - 'use_talos_json': True, 09:12:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:12:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:12:19 INFO - 'work_dir': 'build'} 09:12:19 INFO - [mozharness: 2016-11-25 17:12:19.833855Z] Running clobber step. 09:12:19 INFO - Running pre-action listener: _resource_record_pre_action 09:12:19 INFO - Running main action method: clobber 09:12:19 INFO - rmtree: /builds/slave/test/build 09:12:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:12:22 INFO - Running post-action listener: _resource_record_post_action 09:12:22 INFO - [mozharness: 2016-11-25 17:12:22.315623Z] Finished clobber step (success) 09:12:22 INFO - [mozharness: 2016-11-25 17:12:22.315788Z] Running read-buildbot-config step. 09:12:22 INFO - Running pre-action listener: _resource_record_pre_action 09:12:22 INFO - Running main action method: read_buildbot_config 09:12:22 INFO - Using buildbot properties: 09:12:22 INFO - { 09:12:22 INFO - "project": "", 09:12:22 INFO - "product": "firefox", 09:12:22 INFO - "script_repo_revision": "production", 09:12:22 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:12:22 INFO - "repository": "", 09:12:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:12:22 INFO - "buildid": "20161125072418", 09:12:22 INFO - "pgo_build": "False", 09:12:22 INFO - "basedir": "/builds/slave/test", 09:12:22 INFO - "buildnumber": 7, 09:12:22 INFO - "slavename": "t-yosemite-r7-0191", 09:12:22 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:12:22 INFO - "platform": "yosemite_r7", 09:12:22 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:12:22 INFO - "branch": "mozilla-beta", 09:12:22 INFO - "repo_path": "releases/mozilla-beta", 09:12:22 INFO - "revision": "e0cfc97fd8d551186352072bbf2ad735ab77f570", 09:12:22 INFO - "stage_platform": "macosx64", 09:12:22 INFO - "builduid": "c7836604cd5c49c483df1c89e9f6871c", 09:12:22 INFO - "slavebuilddir": "test" 09:12:22 INFO - } 09:12:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 09:12:22 INFO - Running post-action listener: _resource_record_post_action 09:12:22 INFO - [mozharness: 2016-11-25 17:12:22.319121Z] Finished read-buildbot-config step (success) 09:12:22 INFO - [mozharness: 2016-11-25 17:12:22.319242Z] Running download-and-extract step. 09:12:22 INFO - Running pre-action listener: _resource_record_pre_action 09:12:22 INFO - Running main action method: download_and_extract 09:12:22 INFO - mkdir: /builds/slave/test/build/tests 09:12:22 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')]} 09:12:22 INFO - trying https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 09:12:22 INFO - Downloading https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 09:12:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 09:12:23 INFO - Downloaded 1165 bytes. 09:12:23 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 09:12:23 INFO - Using the following test package requirements: 09:12:23 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:12:23 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:12:23 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 09:12:23 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:12:23 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 09:12:23 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:12:23 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:12:23 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 09:12:23 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:12:23 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:12:23 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 09:12:23 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:12:23 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 09:12:23 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:12:23 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 09:12:23 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:12:23 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 09:12:23 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 09:12:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:12:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:12:23 INFO - Fetch https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:12:24 INFO - Expected file size: 16232546 09:12:31 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:12:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:12:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:12:31 INFO - Fetch https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:12:31 INFO - Expected file size: 16232546 09:12:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 09:12:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 09:12:38 INFO - Fetch https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 09:12:38 INFO - Expected file size: 14414089 09:12:39 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')]} 09:12:39 INFO - trying https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:12:39 INFO - Downloading https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 09:12:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 09:12:42 INFO - Downloaded 86050894 bytes. 09:12:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:12:42 INFO - mkdir: /builds/slave/test/properties 09:12:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:42 INFO - Contents: 09:12:42 INFO - build_url:https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:12:42 INFO - Running post-action listener: _resource_record_post_action 09:12:42 INFO - Running post-action listener: set_extra_try_arguments 09:12:42 INFO - [mozharness: 2016-11-25 17:12:42.747935Z] Finished download-and-extract step (success) 09:12:42 INFO - [mozharness: 2016-11-25 17:12:42.748068Z] Running populate-webroot step. 09:12:42 INFO - Running pre-action listener: _resource_record_pre_action 09:12:42 INFO - Running main action method: populate_webroot 09:12:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:42 INFO - '--tppagecycles', 09:12:42 INFO - '7'], 09:12:42 INFO - 'tests': ['tsvgm']}}, 09:12:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:42 INFO - 'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:42 INFO - 'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['damp', 'tps']}, 09:12:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:42 INFO - 'tests': ['damp', 'tps']}, 09:12:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['dromaeo_dom']}, 09:12:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['basic_compositor_video']}, 09:12:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:12:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['a11yr', 09:12:42 INFO - 'ts_paint', 09:12:42 INFO - 'tpaint', 09:12:42 INFO - 'sessionrestore', 09:12:42 INFO - 'sessionrestore_no_auto_restore', 09:12:42 INFO - 'tabpaint']}, 09:12:42 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:42 INFO - 'ts_paint', 09:12:42 INFO - 'tpaint', 09:12:42 INFO - 'sessionrestore', 09:12:42 INFO - 'sessionrestore_no_auto_restore', 09:12:42 INFO - 'tabpaint']}, 09:12:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:42 INFO - 'ts_paint', 09:12:42 INFO - 'tpaint', 09:12:42 INFO - 'sessionrestore', 09:12:42 INFO - 'sessionrestore_no_auto_restore', 09:12:42 INFO - 'tabpaint']}, 09:12:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:42 INFO - 'ts_paint', 09:12:42 INFO - 'tpaint', 09:12:42 INFO - 'sessionrestore', 09:12:42 INFO - 'sessionrestore_no_auto_restore', 09:12:42 INFO - 'tabpaint']}, 09:12:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['a11yr', 09:12:42 INFO - 'ts_paint', 09:12:42 INFO - 'tpaint', 09:12:42 INFO - 'sessionrestore', 09:12:42 INFO - 'sessionrestore_no_auto_restore', 09:12:42 INFO - 'tabpaint']}, 09:12:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['a11yr', 09:12:42 INFO - 'ts_paint', 09:12:42 INFO - 'tpaint', 09:12:42 INFO - 'sessionrestore', 09:12:42 INFO - 'sessionrestore_no_auto_restore', 09:12:42 INFO - 'tabpaint']}, 09:12:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['tsvgx', 09:12:42 INFO - 'tsvgr_opacity', 09:12:42 INFO - 'tart', 09:12:42 INFO - 'tscrollx', 09:12:42 INFO - 'cart']}, 09:12:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:42 INFO - 'tsvgr_opacity', 09:12:42 INFO - 'tart', 09:12:42 INFO - 'tscrollx', 09:12:42 INFO - 'cart']}, 09:12:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:42 INFO - 'talos_options': ['--disable-e10s'], 09:12:42 INFO - 'tests': ['tp5o']}, 09:12:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:42 INFO - 'tests': ['tp5o']}, 09:12:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:42 INFO - 'talos_options': ['--disable-e10s', 09:12:42 INFO - '--xperf_path', 09:12:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:42 INFO - 'tests': ['tp5n']}, 09:12:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:42 INFO - 'talos_options': ['--xperf_path', 09:12:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:42 INFO - 'tests': ['tp5n']}}, 09:12:42 INFO - 'talos.zip': {'path': '', 09:12:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:42 INFO - Running post-action listener: _resource_record_post_action 09:12:42 INFO - [mozharness: 2016-11-25 17:12:42.766389Z] Finished populate-webroot step (success) 09:12:42 INFO - [mozharness: 2016-11-25 17:12:42.766500Z] Running create-virtualenv step. 09:12:42 INFO - Running pre-action listener: _resource_record_pre_action 09:12:42 INFO - Running main action method: create_virtualenv 09:12:42 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12:42 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 09:12:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:42 INFO - Using real prefix '/tools/python27' 09:12:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:12:43 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:46 INFO - Installing pip.................done. 09:12:46 INFO - Return code: 0 09:12:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:12:46 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')]} 09:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:46 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')]} 09:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:46 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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:12:46 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 09:12:46 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 09:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:46 INFO - 'HOME': '/Users/cltbld', 09:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:46 INFO - 'LOGNAME': 'cltbld', 09:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:46 INFO - 'MOZ_NO_REMOTE': '1', 09:12:46 INFO - 'NO_EM_RESTART': '1', 09:12:46 INFO - 'PAGER': '/bin/cat', 09:12:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:46 INFO - 'PWD': '/builds/slave/test', 09:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:46 INFO - 'SHELL': '/bin/bash', 09:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:12:46 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:46 INFO - 'USER': 'cltbld', 09:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:46 INFO - 'XPC_FLAGS': '0x0', 09:12:46 INFO - 'XPC_SERVICE_NAME': '0', 09:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:46 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 09:12:46 INFO - Cleaning up... 09:12:46 INFO - Return code: 0 09:12:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:12:46 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')]} 09:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:46 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')]} 09:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:46 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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:12:46 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 09:12:46 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 09:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:46 INFO - 'HOME': '/Users/cltbld', 09:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:46 INFO - 'LOGNAME': 'cltbld', 09:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:46 INFO - 'MOZ_NO_REMOTE': '1', 09:12:46 INFO - 'NO_EM_RESTART': '1', 09:12:46 INFO - 'PAGER': '/bin/cat', 09:12:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:46 INFO - 'PWD': '/builds/slave/test', 09:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:46 INFO - 'SHELL': '/bin/bash', 09:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:12:46 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:46 INFO - 'USER': 'cltbld', 09:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:46 INFO - 'XPC_FLAGS': '0x0', 09:12:46 INFO - 'XPC_SERVICE_NAME': '0', 09:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:47 INFO - Downloading/unpacking psutil>=3.1.1 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:47 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 09:12:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:50 INFO - Installing collected packages: psutil 09:12:50 INFO - Running setup.py install for psutil 09:12:50 INFO - building 'psutil._psutil_osx' extension 09:12:50 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 09:12:51 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 09:12:51 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 09:12:51 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 09:12:51 INFO - building 'psutil._psutil_posix' extension 09:12:51 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 09:12:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:12:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:12:51 INFO - ^ 09:12:51 INFO - 1 warning generated. 09:12:51 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 09:12:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:51 INFO - Successfully installed psutil 09:12:51 INFO - Cleaning up... 09:12:51 INFO - Return code: 0 09:12:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:12:51 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')]} 09:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:51 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')]} 09:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:12:51 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 09:12:51 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 09:12:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:12:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:51 INFO - 'HOME': '/Users/cltbld', 09:12:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:51 INFO - 'LOGNAME': 'cltbld', 09:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:51 INFO - 'MOZ_NO_REMOTE': '1', 09:12:51 INFO - 'NO_EM_RESTART': '1', 09:12:51 INFO - 'PAGER': '/bin/cat', 09:12:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:51 INFO - 'PWD': '/builds/slave/test', 09:12:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:51 INFO - 'SHELL': '/bin/bash', 09:12:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:12:51 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:12:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:51 INFO - 'USER': 'cltbld', 09:12:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:51 INFO - 'XPC_FLAGS': '0x0', 09:12:51 INFO - 'XPC_SERVICE_NAME': '0', 09:12:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:51 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 09:12:51 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 09:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:12:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:12:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:12:54 INFO - Installing collected packages: mozsystemmonitor 09:12:54 INFO - Running setup.py install for mozsystemmonitor 09:12:54 INFO - Successfully installed mozsystemmonitor 09:12:54 INFO - Cleaning up... 09:12:54 INFO - Return code: 0 09:12:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:54 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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:12:54 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 09:12:54 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 09:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:54 INFO - 'HOME': '/Users/cltbld', 09:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:54 INFO - 'LOGNAME': 'cltbld', 09:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:54 INFO - 'MOZ_NO_REMOTE': '1', 09:12:54 INFO - 'NO_EM_RESTART': '1', 09:12:54 INFO - 'PAGER': '/bin/cat', 09:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:54 INFO - 'PWD': '/builds/slave/test', 09:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:54 INFO - 'SHELL': '/bin/bash', 09:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:12:54 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:54 INFO - 'USER': 'cltbld', 09:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:54 INFO - 'XPC_FLAGS': '0x0', 09:12:54 INFO - 'XPC_SERVICE_NAME': '0', 09:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:55 INFO - Downloading/unpacking jsonschema==2.5.1 09:12:55 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 09:12:55 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 09:12:55 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 09:12:55 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 09:12:55 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 09:12:55 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 09:12:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:12:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:12:57 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 09:12:57 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 09:12:57 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 09:12:57 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 09:12:57 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 09:12:57 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 09:12:57 INFO - Downloading functools32-3.2.3-2.tar.gz 09:12:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:12:58 INFO - warning: no files found matching '*.txt' 09:12:58 INFO - no previously-included directories found matching 'build' 09:12:58 INFO - no previously-included directories found matching 'dist' 09:12:58 INFO - no previously-included directories found matching '.git*' 09:12:58 INFO - Installing collected packages: jsonschema, functools32 09:12:58 INFO - Running setup.py install for functools32 09:12:58 INFO - warning: no files found matching '*.txt' 09:12:58 INFO - no previously-included directories found matching 'build' 09:12:58 INFO - no previously-included directories found matching 'dist' 09:12:58 INFO - no previously-included directories found matching '.git*' 09:12:58 INFO - Successfully installed jsonschema functools32 09:12:58 INFO - Cleaning up... 09:12:58 INFO - Return code: 0 09:12:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:12:58 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')]} 09:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:58 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')]} 09:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:12:58 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 09:12:58 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 09:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:58 INFO - 'HOME': '/Users/cltbld', 09:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:58 INFO - 'LOGNAME': 'cltbld', 09:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:58 INFO - 'MOZ_NO_REMOTE': '1', 09:12:58 INFO - 'NO_EM_RESTART': '1', 09:12:58 INFO - 'PAGER': '/bin/cat', 09:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:58 INFO - 'PWD': '/builds/slave/test', 09:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:58 INFO - 'SHELL': '/bin/bash', 09:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:12:58 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:58 INFO - 'USER': 'cltbld', 09:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:58 INFO - 'XPC_FLAGS': '0x0', 09:12:58 INFO - 'XPC_SERVICE_NAME': '0', 09:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:12:58 INFO - Cleaning up... 09:12:58 INFO - Return code: 0 09:12:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:12:58 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')]} 09:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:58 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')]} 09:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:12:58 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 09:12:58 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 09:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:58 INFO - 'HOME': '/Users/cltbld', 09:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:58 INFO - 'LOGNAME': 'cltbld', 09:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:58 INFO - 'MOZ_NO_REMOTE': '1', 09:12:58 INFO - 'NO_EM_RESTART': '1', 09:12:58 INFO - 'PAGER': '/bin/cat', 09:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:58 INFO - 'PWD': '/builds/slave/test', 09:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:58 INFO - 'SHELL': '/bin/bash', 09:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:12:58 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:58 INFO - 'USER': 'cltbld', 09:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:58 INFO - 'XPC_FLAGS': '0x0', 09:12:58 INFO - 'XPC_SERVICE_NAME': '0', 09:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:58 INFO - Downloading/unpacking blobuploader==1.2.4 09:12:58 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 09:12:58 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 09:12:58 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 09:12:58 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 09:12:58 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 09:12:58 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 09:13:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13:01 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 09:13:01 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 09:13:01 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 09:13:01 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 09:13:01 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 09:13:01 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 09:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13:02 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 09:13:02 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 09:13:02 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 09:13:02 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 09:13:02 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 09:13:02 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 09:13:02 INFO - Downloading docopt-0.6.1.tar.gz 09:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:02 INFO - Running setup.py install for blobuploader 09:13:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:02 INFO - Running setup.py install for requests 09:13:03 INFO - Running setup.py install for docopt 09:13:03 INFO - Successfully installed blobuploader requests docopt 09:13:03 INFO - Cleaning up... 09:13:03 INFO - Return code: 0 09:13:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:03 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')]} 09:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:03 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')]} 09:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:03 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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:13:03 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 09:13:03 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 09:13:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:13:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:03 INFO - 'HOME': '/Users/cltbld', 09:13:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:03 INFO - 'LOGNAME': 'cltbld', 09:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:03 INFO - 'MOZ_NO_REMOTE': '1', 09:13:03 INFO - 'NO_EM_RESTART': '1', 09:13:03 INFO - 'PAGER': '/bin/cat', 09:13:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:03 INFO - 'PWD': '/builds/slave/test', 09:13:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:03 INFO - 'SHELL': '/bin/bash', 09:13:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:13:03 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:13:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:03 INFO - 'USER': 'cltbld', 09:13:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:03 INFO - 'XPC_FLAGS': '0x0', 09:13:03 INFO - 'XPC_SERVICE_NAME': '0', 09:13:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Nrq_sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Ed9zFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-y4l4Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Cn69lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-rWrQJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-XJ7B_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ykcri2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-lPKea7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-lEVWbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:04 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-vJQzWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:05 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-7hiyZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:05 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-eMXXct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:05 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-VPcm1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:05 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Sn79nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:05 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-oRFXQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:05 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-oBHhCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:05 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-_5BZgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:05 INFO - Running setup.py install for manifestparser 09:13:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:05 INFO - Running setup.py install for mozcrash 09:13:06 INFO - Running setup.py install for mozdebug 09:13:06 INFO - Running setup.py install for mozdevice 09:13:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:13:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:06 INFO - Running setup.py install for mozfile 09:13:06 INFO - Running setup.py install for mozhttpd 09:13:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:06 INFO - Running setup.py install for mozinfo 09:13:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:06 INFO - Running setup.py install for mozInstall 09:13:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:13:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:13:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:13:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:13:06 INFO - Running setup.py install for mozleak 09:13:07 INFO - Running setup.py install for mozlog 09:13:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:13:07 INFO - Running setup.py install for moznetwork 09:13:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:13:07 INFO - Running setup.py install for mozprocess 09:13:07 INFO - Running setup.py install for mozprofile 09:13:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:13:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:13:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:13:07 INFO - Running setup.py install for mozrunner 09:13:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:13:07 INFO - Running setup.py install for mozscreenshot 09:13:08 INFO - Running setup.py install for moztest 09:13:08 INFO - Running setup.py install for mozversion 09:13:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:13:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:08 INFO - Cleaning up... 09:13:08 INFO - Return code: 0 09:13:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:08 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')]} 09:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:08 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')]} 09:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:08 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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:13:08 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 09:13:08 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 09:13:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:13:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:08 INFO - 'HOME': '/Users/cltbld', 09:13:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:08 INFO - 'LOGNAME': 'cltbld', 09:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:08 INFO - 'MOZ_NO_REMOTE': '1', 09:13:08 INFO - 'NO_EM_RESTART': '1', 09:13:08 INFO - 'PAGER': '/bin/cat', 09:13:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:08 INFO - 'PWD': '/builds/slave/test', 09:13:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:08 INFO - 'SHELL': '/bin/bash', 09:13:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:13:08 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:13:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:08 INFO - 'USER': 'cltbld', 09:13:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:08 INFO - 'XPC_FLAGS': '0x0', 09:13:08 INFO - 'XPC_SERVICE_NAME': '0', 09:13:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:08 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-DoJ3nE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:08 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)) 09:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:08 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-PXr8BX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:08 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)) 09:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:08 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-CLeGHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:08 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)) 09:13:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:08 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-rgDejD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-JD887m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-XcbQnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-L8r0aU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-urUMqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-1h94uQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-SCg47S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-R0oUNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-NhJUC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:09 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)) 09:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:09 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-1T7qYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:10 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)) 09:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:10 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-8gTYce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:10 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)) 09:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:10 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-PfVVVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:10 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)) 09:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:10 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-XU9rRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:10 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)) 09:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:10 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-GBjoAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:10 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)) 09:13:10 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)) 09:13:10 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)) 09:13:10 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)) 09:13:10 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)) 09:13:10 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)) 09:13:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:10 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 09:13:10 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 09:13:10 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 09:13:10 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 09:13:10 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 09:13:10 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 09:13:13 INFO - Downloading blessings-1.6.tar.gz 09:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:13:13 INFO - Installing collected packages: blessings 09:13:13 INFO - Running setup.py install for blessings 09:13:13 INFO - Successfully installed blessings 09:13:13 INFO - Cleaning up... 09:13:13 INFO - Return code: 0 09:13:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:13:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:13 INFO - Reading from file tmpfile_stdout 09:13:13 INFO - Current package versions: 09:13:13 INFO - blessings == 1.6 09:13:13 INFO - blobuploader == 1.2.4 09:13:13 INFO - docopt == 0.6.1 09:13:13 INFO - functools32 == 3.2.3-2 09:13:13 INFO - jsonschema == 2.5.1 09:13:13 INFO - manifestparser == 1.1 09:13:13 INFO - mozInstall == 1.12 09:13:13 INFO - mozcrash == 0.17 09:13:13 INFO - mozdebug == 0.1 09:13:13 INFO - mozdevice == 0.48 09:13:13 INFO - mozfile == 1.2 09:13:13 INFO - mozhttpd == 0.7 09:13:13 INFO - mozinfo == 0.9 09:13:13 INFO - mozleak == 0.1 09:13:13 INFO - mozlog == 3.4 09:13:13 INFO - moznetwork == 0.27 09:13:13 INFO - mozprocess == 0.23 09:13:13 INFO - mozprofile == 0.28 09:13:13 INFO - mozrunner == 6.12 09:13:13 INFO - mozscreenshot == 0.1 09:13:13 INFO - mozsystemmonitor == 0.3 09:13:13 INFO - moztest == 0.7 09:13:13 INFO - mozversion == 1.4 09:13:13 INFO - psutil == 3.1.1 09:13:13 INFO - requests == 1.2.3 09:13:13 INFO - wsgiref == 0.1.2 09:13:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:13 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')]} 09:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:13 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')]} 09:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:13 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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:13:13 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 09:13:13 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 09:13:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:13:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:13 INFO - 'HOME': '/Users/cltbld', 09:13:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:13 INFO - 'LOGNAME': 'cltbld', 09:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:13 INFO - 'MOZ_NO_REMOTE': '1', 09:13:13 INFO - 'NO_EM_RESTART': '1', 09:13:13 INFO - 'PAGER': '/bin/cat', 09:13:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:13 INFO - 'PWD': '/builds/slave/test', 09:13:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:13 INFO - 'SHELL': '/bin/bash', 09:13:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:13:13 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:13:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:13 INFO - 'USER': 'cltbld', 09:13:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:13 INFO - 'XPC_FLAGS': '0x0', 09:13:13 INFO - 'XPC_SERVICE_NAME': '0', 09:13:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 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)) 09:13:13 INFO - Cleaning up... 09:13:14 INFO - Return code: 0 09:13:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:13:14 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')]} 09:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:14 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')]} 09:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:14 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 0x100f35490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eab570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb4532f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, '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.tws5KJXHrX/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.j7mOEZb5eW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 09:13:14 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 09:13:14 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 09:13:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:13:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:14 INFO - 'HOME': '/Users/cltbld', 09:13:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:14 INFO - 'LOGNAME': 'cltbld', 09:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:14 INFO - 'MOZ_NO_REMOTE': '1', 09:13:14 INFO - 'NO_EM_RESTART': '1', 09:13:14 INFO - 'PAGER': '/bin/cat', 09:13:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:14 INFO - 'PWD': '/builds/slave/test', 09:13:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:14 INFO - 'SHELL': '/bin/bash', 09:13:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:13:14 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:13:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:14 INFO - 'USER': 'cltbld', 09:13:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:14 INFO - 'XPC_FLAGS': '0x0', 09:13:14 INFO - 'XPC_SERVICE_NAME': '0', 09:13:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:13:14 INFO - Cleaning up... 09:13:14 INFO - Return code: 0 09:13:14 INFO - Running post-action listener: _resource_record_post_action 09:13:14 INFO - Running post-action listener: _start_resource_monitoring 09:13:14 INFO - Starting resource monitoring. 09:13:14 INFO - [mozharness: 2016-11-25 17:13:14.296394Z] Finished create-virtualenv step (success) 09:13:14 INFO - [mozharness: 2016-11-25 17:13:14.297392Z] Running install step. 09:13:14 INFO - Running pre-action listener: _resource_record_pre_action 09:13:14 INFO - Running main action method: install 09:13:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:14 INFO - Reading from file tmpfile_stdout 09:13:14 INFO - Detecting whether we're running mozinstall >=1.0... 09:13:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:13:14 INFO - Reading from file tmpfile_stdout 09:13:14 INFO - Output received: 09:13:14 INFO - Usage: mozinstall [options] installer 09:13:14 INFO - Options: 09:13:14 INFO - -h, --help show this help message and exit 09:13:14 INFO - -d DEST, --destination=DEST 09:13:14 INFO - Directory to install application into. [default: 09:13:14 INFO - "/builds/slave/test"] 09:13:14 INFO - --app=APP Application being installed. [default: firefox] 09:13:14 INFO - mkdir: /builds/slave/test/build/application 09:13:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:13:37 INFO - Reading from file tmpfile_stdout 09:13:37 INFO - Output received: 09:13:37 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:13:37 INFO - Running post-action listener: _resource_record_post_action 09:13:37 INFO - [mozharness: 2016-11-25 17:13:37.048361Z] Finished install step (success) 09:13:37 INFO - [mozharness: 2016-11-25 17:13:37.048492Z] Running run-tests step. 09:13:37 INFO - Running pre-action listener: _resource_record_pre_action 09:13:37 INFO - Running main action method: run_tests 09:13:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:13:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:13:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:13:37 INFO - 2016-11-25 09:13:37.062 screenresolution[1852:8006] starting screenresolution argv=screenresolution get 09:13:37 INFO - 2016-11-25 09:13:37.065 screenresolution[1852:8006] Display 0: 1600x1200x32@60 09:13:37 INFO - 2016-11-25 09:13:37.071 screenresolution[1853:8009] starting screenresolution argv=screenresolution list 09:13:37 INFO - Available Modes on Display 0 09:13:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:13:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:13:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:13:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:13:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:13:37 INFO - Intel Iris: 09:13:37 INFO - Chipset Model: Intel Iris 09:13:37 INFO - Type: GPU 09:13:37 INFO - Bus: Built-In 09:13:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:13:37 INFO - Vendor: Intel (0x8086) 09:13:37 INFO - Device ID: 0x0a2e 09:13:37 INFO - Revision ID: 0x0009 09:13:37 INFO - Displays: 09:13:37 INFO - Display: 09:13:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:13:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:13:37 INFO - Display Serial Number: 1600x1200 60 09:13:37 INFO - Main Display: Yes 09:13:37 INFO - Mirror: Off 09:13:37 INFO - Online: Yes 09:13:37 INFO - Rotation: Supported 09:13:37 INFO - Return code: 0 09:13:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:13:37 INFO - Python 2.7.3 09:13:37 INFO - Return code: 0 09:13:37 INFO - grabbing minidump binary from tooltool 09:13:37 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')]} 09:13:37 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 0x100d49d30>, '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 0x100bafdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d41b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:13:37 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 09:13:37 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 09:13:37 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 09:13:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:13:37 INFO - Return code: 0 09:13:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:13:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:13:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:13:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:13:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:13:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0191 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:13:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tws5KJXHrX/Render', 09:13:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:37 INFO - 'HOME': '/Users/cltbld', 09:13:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:37 INFO - 'LOGNAME': 'cltbld', 09:13:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:37 INFO - 'MOZ_NO_REMOTE': '1', 09:13:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:37 INFO - 'NO_EM_RESTART': '1', 09:13:37 INFO - 'PAGER': '/bin/cat', 09:13:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:37 INFO - 'PWD': '/builds/slave/test', 09:13:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:37 INFO - 'SHELL': '/bin/bash', 09:13:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7mOEZb5eW/Listeners', 09:13:37 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 09:13:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:37 INFO - 'USER': 'cltbld', 09:13:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:37 INFO - 'XPC_FLAGS': '0x0', 09:13:37 INFO - 'XPC_SERVICE_NAME': '0', 09:13:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:13:37 INFO - mozversion application_buildid: 20161125072418 09:13:37 INFO - mozversion application_changeset: e0cfc97fd8d551186352072bbf2ad735ab77f570 09:13:37 INFO - mozversion application_display_name: Firefox 09:13:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:13:37 INFO - mozversion application_name: Firefox 09:13:37 INFO - mozversion application_remotingname: firefox 09:13:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:13:37 INFO - mozversion application_vendor: Mozilla 09:13:37 INFO - mozversion application_version: 51.0 09:13:37 INFO - mozversion platform_buildid: 20161125072418 09:13:37 INFO - mozversion platform_changeset: e0cfc97fd8d551186352072bbf2ad735ab77f570 09:13:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:13:37 INFO - mozversion platform_version: 51.0 09:13:37 INFO - using testdate: 1480094017 09:13:37 INFO - actual date: 1480094017 09:13:37 INFO - starting webserver on 'localhost:49246' 09:13:37 INFO - SUITE-START | Running 2 tests 09:13:37 INFO - TEST-START | dromaeo_css 09:13:37 INFO - operating with platform_type : mac_ 09:13:37 INFO - Initialising browser for dromaeo_css test... 09:13:37 INFO - TEST-INFO | started process 1863 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpP1WW47/profile http://localhost:49246/getInfo.html) 09:13:39 INFO - PROCESS | 1863 | __metrics Screen width/height:1600/1200 09:13:39 INFO - PROCESS | 1863 | colorDepth:24 09:13:39 INFO - PROCESS | 1863 | Browser inner width/height: 1024/689 09:13:39 INFO - PROCESS | 1863 | __metrics 09:13:40 INFO - PROCESS | 1863 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:13:40 INFO - PROCESS | 1863 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:13:40 INFO - TEST-INFO | 1863: exit 0 09:13:40 INFO - Browser initialized. 09:13:40 INFO - Running cycle 1/1 for dromaeo_css test... 09:13:40 INFO - TEST-INFO | started process 1866 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpP1WW47/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:52 INFO - PROCESS | 1866 | RSS: Main: 168493056 09:13:52 INFO - PROCESS | 1866 | 09:14:43 INFO - PROCESS | 1866 | 1480094083503 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] 09:16:55 INFO - PROCESS | 1866 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 09:16:56 INFO - PROCESS | 1866 | RSS: Main: 296050688 09:16:56 INFO - PROCESS | 1866 | 09:19:59 INFO - PROCESS | 1866 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 09:20:00 INFO - PROCESS | 1866 | RSS: Main: 302059520 09:20:00 INFO - PROCESS | 1866 | 09:23:02 INFO - PROCESS | 1866 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 09:23:02 INFO - PROCESS | 1866 | RSS: Main: 270475264 09:23:02 INFO - PROCESS | 1866 | 09:26:04 INFO - PROCESS | 1866 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 09:26:05 INFO - PROCESS | 1866 | RSS: Main: 269471744 09:26:05 INFO - PROCESS | 1866 | 09:29:08 INFO - PROCESS | 1866 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 09:29:09 INFO - PROCESS | 1866 | RSS: Main: 269082624 09:29:09 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 09:32:12 INFO - PROCESS | 1866 | RSS: Main: 400986112 09:32:12 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | __start_tp_report 09:32:12 INFO - PROCESS | 1866 | _x_x_mozilla_page_load 09:32:12 INFO - PROCESS | 1866 | _x_x_mozilla_page_load_details 09:32:12 INFO - PROCESS | 1866 | |i|pagename|runs| 09:32:12 INFO - PROCESS | 1866 | |0;dojo.html;3413.5221069050335;3422.3155368926214;3460.1003739027847;3469.583649962005;3520.6127325994134;5693.060644993577;5743.655380677163;5744.79893203738;5749.108888122342;5781.710914454282;5660.886782264352;5663.207150998864;5762.106873434614;5763.106718458635;5779.335376431701;5194.896102077965;5242.868928276805;5270.5520030797425;5270.9736451317685;5292.655977361478;5192.428832828386;5198.532132108109;5211.062008838408;5324.094903866352;5343.305370301855;5294.735263236842;5307.2039194121;5309.601779866506;5325.813596524123;5328.040952628526;5236.528712415863;5292.761825717852;5315.867103322409;5316.760745766411;5317.9202311965155;5117.795288188468;5246.737663116867;5255.448177941299;5260.684358938476;5301.496357845998;5788.942110578924;5811.476967072984;5812.709364531799;5819.068948968187;5828.708564571754;6144.662043587601;6146.58628515444;6178.41305076017;6209.534284928648;6224.564280500367;6214.5649804513705;6234.220722409699;6239.344868788802;6254.124422580842;6257.186565746424;5783.306003279633;5787.971060144714;5792.362840087587;5834.620749651259;5844.503217226541;6189.566730328834;6238.656873871981;6245.843853903689;6282.842928926813;6289;5962.493188079062;5986.670733109634;6073.18012068374;6106.236720409949;6109.327973922865;6213.316535181127;6235.688215589203;6246.625202487865;6259.436650701458;6268.279147897959;6097.07100518335;6146.8935591593045;6184.257889053252;6198.783042593578;6205;6176.505879529627;6233.158523599256;6245.906311405332;6293.181886354744;6299.149614802035;4990.90018199634;4994.750262486934;4995.100881841231;5007.799688012438;5014.548690617862;4438.4451943507065;4440.023194897118;4488.865334039984;4523.117991991596;4525.932111018337;5832.096025116114;5842.948269311565;5890.823275301662;5900.793472228537;5966.791162309299;6258.468030217483;6261.499080073583;6264.498840092781;6271.749130034838;6284.811455656338;5923.703814809329;5951.702414879239;5967.343592204854;5968.522518198621;6008.369121242206;5353.330833645794;5372.5433338166695;5422.05114105038;5426.430224826337;5470.781168753205;6260.311365749672;6261.749530018748;6291.716872740829;6318.652474113968;6440.581362211628;6204.627722336494;6217.502599791916;6230.875382492415;6256.31180570146;6263.592866463848;6328.683565821599;6396.360363963567;6422.678866056772;6430.324815894263;6442.742290308335;5082;6105.8168254952425;6131.743695906945;6142.897836592492;6151.615886425698;6293.37066293367;6299.905501417391;6303.149074874833;6308.243010838729;6330.841728956629;6170.167027451236;6189.845253868509;6196.256449225942;6205.131281620486;6223.222097237845;6049.637021778707;6115.694215289307;6181.81454556364;6212.534059945432;6225.937740622717;5885.499732522783;5933.3769954154195;6019.638821670714;6058.212432383762;6069.848253793514;6127.509799216141;6136.64993701385;6141.925163096351;6150.354212807591;6156.907646385218;5820.534357251325;5955.196048533566;5985.730642121028;6033.095035744674;6058.697065146813 09:32:12 INFO - PROCESS | 1866 | |1;ext.html;495.43767823520307;520.7709804860531;537.7795104007358;540.7350398304832;565.1466285908277;32597.837010814943;42142.367864482054;43919.902002449875;44077.77961110194;45686.857828554304;45021;45500.77249613747;45509.63471095864;45552.544474555245;45565.08869822601;9402.576884040234;10156.59373625056;10361.637342693008;10362.171026317876;10365.896341036569;9399.24806015517;12363.072769542276;12665.746685066293;12683;12784.105112642119;12537.874621253808;12610.180338277982;12621;12629.936850315735;12644.304563249018;12522.56171034014;12596.37018149098;12600.81098783519;12619.495220191182;12621.242725436503;12508.061895357885;12602.306873121976;12604.68488287791;12616.495340186382;12629.115961882671;42560;42918.57081429177;42929;42946.785266073784;42985;3450.9827450862836;3487.720982321408;3561.4123669594487;3586.228960773446;3587.013571267896;32306.676933230836;32553.6744632553;32802;32887.17782055444;33424.83287583571;3327.1848397142853;3347.866085356583;3415.573053368329;3462.2036931505645;3514.859405623772;531501;532100;535248;535707.3214633941;537360;35251.4712279316;62875.685621571596;63183.6840815793;63352.68323658352;63360.68319658372;36012.63987360093;36945;37334.066648333464;37339.81330093386;37400.62599373971;15738.9213053934;15891.046537207574;15892.284847181905;15897.443589474313;15924;12159.452824622913;12371.876281237073;13012.934935325262;13199.802002969964;13328.00039997004;7159;7912.564808935564;7999.200079991954;8166.510009399455;8242.917570824335;3435.1068722132427;3438.1060924159397;3449.379111759907;3453.533772940621;3484.1463841358523;966.7051549277397;1035.3270374256792;1043.222799014739;1105.1987309200927;1111.9499622517005;31358.84320578428;31593.526097108563;31617.8419107903;32252.354952900812;32344.35311293808;903.620479398641;1074.104206076276;1125.3135587291745;1133.6769020829024;1260.3005332040623;16045.957012794132;16598.502044938672;16647;16673.08298043595;16716.66566668684;710323;721308;722217;722598;723084;62124;62474.75050498964;62497.68751156306;62760;62876.42808929813;1180.3095189314176;1323.371398585664;1350.8716671916152;1419.4251328211678;1464.0849469081825;30550.08349749511;32690.855820046185;32777.67222327746;33070.50394244136;33406.83296583501;52056.73971630118;52646.736766315924;52680.209796853844;52815;52904;474433;474518;474896;476539;477867;25595.616065758655;26190.34524136912;27198.320041999108;27943.16170514888;27975.440491190468;49264;49805;49933;50140.749296253285;50396.748016259684;39770.2045959085;40830.59169408268;41745.58254417417;42459.787701061294;42545.36181957335;40969;41360.58639413567;42188.36717449302;42461.575384247;42630.78684606557;32496.350072997935;40509.79745101256;40904.590954090076;40962.38556421596;41059.58940410558;34687;36093;36231;36582.451263231604;36676.81661591675;23537.64693529564;26646.333841654116;26833.865830670722;26933.730662693124;27016.32459188536 09:32:12 INFO - PROCESS | 1866 | |2;jquery.html;321.9806811591305;369.6285233340493;380.8495644220534;391.2644228849762;402.2417742555284;20443.28448504301;21098.789012109875;21826.380834299867;21830.017649205784;22032.44918877025;12352.567660131897;12546.874531254685;15559.53321400357;21448.785512144874;22214.222502212426;5397.406285308603;5650.49145576898;5654.547636189116;5666.51834594058;5698.43015698431;5400.91898621521;6087;6249.500039996789;6254.874902501947;6269.498440124779;5264.736763161845;5917;6212.844678883019;6231.127642130105;6232;6184.690765461732;6233.812985610439;6241.21984751906;6246.656433896134;6262.37376262377;5231.633785635007;5423.267858839045;5492.780288788444;5494.835154945358;5495.835124946258;19289.710654340055;24242.636360454606;26234.475310493875;26243.47513049728;26580.601290980652;2310.7111611048617;2365.207655435414;2430.586800243963;2435.914742984005;2666.4400475900084;10583.73540661482;10607.257491975564;10644.946775266151;10802.72993175169;14719.484818031318;1865.486991077451;1911.9808801911943;1917.9328723494611;1949.7757757857887;1987.0064967516241;129186.06220906693;133275;135758.32120839332;139012;142281.99999999895;10754.386841973766;11407.771844563229;11563.595274165364;11788.174827762146;11857.051435885109;13747.518836840663;15061.698766024838;15066.171360575054;15091.01908375952;15091.396344146111;8727.519986400683;8835.911640883638;8866.512341821133;8877.422967507091;9008.504532250658;8842.33682473817;8854.557272136368;8876.112388761072;8996.145366190201;9018.819623607491;4565.452145742533;4577.885552861205;4587.174308624413;4589.5639914208095;4636.281376386665;1906.2279776690464;1915.348781414326;1934.7968463311431;1963.9312624058089;1985.7120717495882;821.34292565947;863.6631713631689;877.8200468903859;938.3759799733238;947.6541062512272;21154.576908461746;21801.454963625718;21850;21969.011394487286;21970.45073873166;904.4347282948157;933.2067742419025;946.3328353510758;998.55065220651;999.0608827701936;8373.455725377831;11304.81299463561;11408.201425900274;11440;11443.313401195956;627294.8635256885;628600;638212;639484;646398;98485.55054261675;130822.03766943504;132547.3490530184;138795;142525;869.8999615044224;973.7419583810296;992.1864071461476;993.2649839119144;997.4563862694994;19183.424497265103;20005.599888001867;20474.897625511778;20493.2827351042;20709.896450517652;51164.97670046653;52725.7363713179;60360.396396035474;62639;63250;65323;85357.71963420429;88765.55617221873;90359;91333;7642.777155655069;7925.762227133196;8087.191280871866;8155.673773049249;8507.361947853811;10282.588696452058;10289.485525723534;13051.934740326238;13215.405306761026;13496;11178.13751018277;11732.882671173178;11852.940735296614;12088.939555302168;12091.57679481214;10499.370037797757;11646.24299420535;11657.88342116602;12045.939770301093;12050.276983381025;11696.766064678828;11818.46816893259;11851.111166662362;12076.03391728642;12247.816282755944;8462.534560599226;8548.957255213683;8570.828583428422;8674.436161649473;8763.079876612865;10902.563897444013;11739.530418783153;11781.823272650745;11918.761624767283;11999.460024299102 09:32:12 INFO - PROCESS | 1866 | |3;mootools.html;1600.4078490958343;1602.8877978541502;1820.8634352423571;1948.3473036532762;1961.6567100757363;8790.208881200691;9351.579178936947;9352.064793520645;9362.719118426441;9371.765705857357;8214.753557393287;8507.36194785392;8834.469931804097;9028.50343231122;9041.276697864172;2412.6742889709885;2440.926772196828;2442.4504486490573;2445.3886528367907;2449.2284930246915;2421.8546887186735;2425.0784701813286;2458.557459657261;2461.7538246175327;2464.75352464753;2437.2200895713377;2450.0934654177977;2452.2152911068465;2462.4336402627405;2467.3584867934287;2410.554047501218;2416.6616673665612;2425.818063645216;2435.9878200608973;2436.731959484473;2420.987895060531;2430.902763889442;2435.0138194031447;2442.46265821519;2446.657467954488;9077.04691007448;9289.581968811419;9291.674791382336;9292.349535532516;9300.767480812967;4670.486246512881;5691.004074287024;5697.259356283656;5702.885942281131;5707.885842283131;3619.2761447710564;3732.720045996561;3803.353429916893;3851.7496362736333;3867.361885288924;1092.3773449133996;1118.3792994887833;1118.457548089176;1144.7710457908452;1145.4387350198426;12261.957733592655;12275.69310767229;12279.93860030703;12309.569165079269;12362.38188090592;4791.1854984652;4837.298591704183;4911.87720306988;4983.953369792312;5010.49895010496;10401.27191096616;10425.9478702606;10426.426546540013;10432.73918152037;10437.112845408225;5648.152777083388;5651.858703532444;5654.547636189095;5661;5664.49019588239;4635.3278774578175;4796.232602783538;4803.135435621622;5093.388793344822;5144.794208231704;4084.959150408458;4107.732997355162;4164.5210800758305;4210.663146948237;4213.431186789805;1304.4651692806094;1307.8626744191722;1313.2055106660332;1324.8807607315196;1326.316946772413;919.7332773495611;943.6461327002374;948.8102379523984;963.1043129889131;988.2637435110888;5814.3313518945015;5974.701264936736;5992;6107.480864126599;6177.166082578884;378.44179834743534;378.5419642232929;429.81303133137186;432.0796703022542;452.5564946352593;4093.406456063854;4108.116754897711;4122.216778812016;4167.6874234432535;4190.916181676411;10874.619388321371;11163.441827908735;11309.773804524028;11356.26184298018;11383;11190.552377904793;11244.662660120046;11277.887221127849;11286.097112231002;11292.717682058013;8318.292945099734;8412.242898139075;8502.2773064289;8511.616977236039;8602.397832151815;5852.356240813422;5877.177195192591;5880.117982302689;5956.136360227656;6038.9396106038375;10051.547680354255;10338.224633152393;10355.378677279386;10377.27359084887;10531.894681053398;10594.417307048187;10726.463676815973;10727.946360268148;10730.785384292427;10747.408892510986;6713.362230588086;7144.892826607709;7579;7900.920990790249;8086.555239461651;8671.566421678765;8876.86684699717;8892.73321800347;8896.777580560538;8904.73285801427;7798.805029874298;7852.528848269122;7908.288254057163;7915.366770658448;8059.83880322402;7686.461947663834;7788.143304236416;7796.181400952818;7826.647800848861;7843.686252550062;7214.855702886018;7346.632668366454;7347.2652734728235;7350.228226036189;7365.410767138723;4599.356090147449;5018.723970181675;5053.620978426707;5128.435872053997;5436.157395603688;5059.970200527573;5078.339815823919;5240.266362709148;5270.183121616155;5285.973570132125 09:32:12 INFO - PROCESS | 1866 | |4;prototype.html;306.2176139962396;311.58403531285734;314.460699899672;316.9841507924603;321.0576956632283;4968.8571628525415;5198.350206224227;5494.175873618954;5521.337439507255;5565.6660600364;4708.835190768324;4788.616910647149;5014.373203349582;5092.872678183047;5188.558972487333;3455.51622772812;3932.941005884914;4088.2641124597562;4113.25961326961;4113.444684967536;3473.687368136867;3865.6327648873453;4091;4157.438745769313;4196.874093777176;3789.3747531657245;3870.3807390817474;4120.505539335284;4169.833206671729;4192.24539582875;4156.646685031776;4161.500619925629;4169.791510424482;4200.22295875261;4209.410682504452;3513.332466831294;3613.819309034564;3635;3640.5085313482605;3657.6342365763476;4365.908522869283;4601.539846015372;5272.314599102092;5277.56957977314;5350.598705097094;3999.2601368746873;4279.636230920375;4602.240630295997;4753.928691069636;4781.258904869716;5206.713630750307;5397.084035167538;5591.832245032656;5680.3467601225975;5764.394738552475;3378.3918894598964;3955.564887862333;3965.662918651918;3969.2260009298197;3993.28120938231;5354.571634269249;5885.352611212763;6006.969965150146;6088;6101.450869421729;3098.395812816524;4026.2350153470748;4031.4154447604933;4040.6363427291685;4065.57311482296;4133.689973252018;4150.522689890641;4151.169766046803;4162.937555936664;4167.437395951508;4506.842260520866;4600.930986035212;4753.619710423158;4786.736729479842;4792.496787837296;4916.311716359713;5695.117256825199;5697.0090702381585;5762.394948530427;5797.4782269595935;1326.2824642088326;1384.9861501384858;1386.8821150202143;1399.307342865288;1402.8737413632823;754.8827734952301;811.022717625272;856.905740368559;882.2016075451727;882.4043770455046;3889.436031775433;3989.68082553395;4040.4141399496907;4283.400323954649;4573.862784116482;4465.642748580106;4576.352513095283;4636.420447444069;4661.040239447907;5010.073136469765;3999.3800960851113;4069.7761623111014;4528.411306530196;4536.092781443725;4575.382323386367;2373.525294940984;2531.443162455114;2557.9256712180927;2611.817172797924;2632.8147637461243;4187.838182172441;4538.909221815611;4832.230859929261;4871.952530206023;5017.37282839645;4255.872323830228;4492.528284530143;4619.445666520039;4788.401449818773;5096.745162741848;4201.34879093741;4304.74171549708;4648.651351148677;5036.84889453317;5197.532222100029;3920.9215815684097;4156.376543518496;4163.625273725379;4433.273536774925;4806.9330188019485;4685.718856868599;4697.034765847423;4803.807847686193;4829.937413768966;4896.92654610174;5029.723365214802;5275.129603615514;5657.086600400879;6002.969985150046;6032.777056060862;4074.3684728865887;4077.571854955187;4080.6735461162443;4084.2852500812833;4086.1010577672864;5758.568107392046;5964.329987603478;5965.28416590012;6006.789762358471;6013.9097913532205;5395.379531353945;5520.834374968757;5720.771169153354;5722.82682050187;5837.6497410155525;5503.944960550343;5720.74256658443;5721.542276617944;5737.139429085671;5742.626729262584;5223.582113430993;5299.33758280215;5499.807506737245;5635.408282130317;5637.830865074055;4813.566778989768;5029.59763218949;5060.797568097235;5732.4267573244015;5773.971130144322;4363.389125522366;4937.987942238702;5669.603127781016;5690.231818704588;5708.686022268649 09:32:12 INFO - PROCESS | 1866 | |5;yui.html;734.8089496730852;772.3280745751198;806.3428305930667;862.5859587398047;886.7162507997443;16189.919050404746;16630.83369166308;17194.05432701201;17427.390041348557;17637.382691605795;15666.295016724247;15869.285882135302;15876.603084922883;15906.52280431589;15909.761353579677;732.6702983657349;752.4657493179849;903.345074820753;904.1365495951351;914.8078903430288;922.8385032619299;933.7572231219898;934.1499235695518;934.7055677461611;936.0078316983985;988.3773222869582;988.5502096546054;989.9653512127077;990.7126933189367;990.9256805739562;979.3193730357405;984.44378925907;984.990150098497;985.3398223190479;985.6895078050427;721.3579913876654;721.8808896532066;723.2911746488418;723.3200791256203;726.2954933714288;16468.49672158171;16652.417165399154;16839;16853.904496207706;16895;12117.27296362221;12731.85413312797;13564.93217533916;14061.5078472254;14675.559733207914;12481.188722732993;13194.406251718696;13800;14070.577882663536;14084.788728169086;4445.799939002753;4711.7879695413785;4742.644301677388;4749.833755818529;4763.118823017753;129263;138492;138673.3066334672;140442.29778851144;141701;10861.456927153611;11464.31214127155;11506.59726909571;11598.246114002563;11632.185746997799;22336;22360.55278894413;22367;22370.888145559493;22490.32529024132;6587.769428070091;8069.798255043671;8150.470219435718;8211.630476628567;8258;4559.931601025987;4568.33759104928;4599.5400459953735;4660.906781864343;4731.550502702238;3547.5033495310677;3663.0109870335395;3666.944995825065;3667.6882464990776;3671.889843304705;663.6947004377944;677.2279601254573;685.9211190713032;691.0843132848995;695.7738734911073;592.8073376152768;639.9072134540465;645.686841881687;650.180772226988;653.2748648999607;8929;9059.184673379295;9212.965507592538;9421.528923553795;9618.134367906921;529.4625954656027;560.4927540575785;652.3509108437136;652.8988006858945;669.9832504187434;4330.133973205371;4649.488556258809;4998.239358035228;5221.582273418117;5523.538657286067;161831;167411;172179.13910430617;172974;176584;115465;115890;116788.41605791917;135385;138616;112230;115001.4249928745;116195.41902290603;116379.41810291064;121038;9969.800603988024;9987.650432234837;10033.785809959689;10763.94618026905;10795.73010674708;51694.22458663048;58655.53361165834;64949.67525162344;66105.67788644297;67931;90868.54565727129;103279;103912;106168;110136;13917.095388799695;14016.369263382969;14157.292135393394;14157.292135393394;14430.278486075444;14956.476523321631;14988.175650339335;15665.765013525035;15711.607209819389;17041.48875533736;14112;14219.715605687621;14678.77981830252;15066.472673456376;15130.319135638703;14515;14519.92740036293;14845.257737113317;14885.627859303604;15079.170645614158;13349.265790381622;13723.862761372658;13986.930065349608;14018.088824226392;14541.98206125561;11428;11582.94208528952;11726.179167458537;11795.646130616095;12188.268703877795;11848.881511184778;12109.69725756863;12231.26612403223;12285.38573071325;12337.321447320484 09:32:12 INFO - PROCESS | 1866 | __end_tp_report 09:32:12 INFO - PROCESS | 1866 | __start_cc_report 09:32:12 INFO - PROCESS | 1866 | _x_x_mozilla_cycle_collect,1271 09:32:12 INFO - PROCESS | 1866 | __end_cc_report 09:32:12 INFO - PROCESS | 1866 | __startTimestamp1480095132545__endTimestamp 09:32:12 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | ------- Summary: start ------- 09:32:12 INFO - PROCESS | 1866 | Number of tests: 6 09:32:12 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | [#0] dojo.html Cycles:165 Average:5785.87 Median:6008.37 stddev:619.90 (10.3%) stddev-sans-first:593.21 09:32:12 INFO - PROCESS | 1866 | Values: 3413.5 3422.3 3460.1 3469.6 3520.6 5693.1 5743.7 5744.8 5749.1 5781.7 5660.9 5663.2 5762.1 5763.1 5779.3 5194.9 5242.9 5270.6 5271.0 5292.7 5192.4 5198.5 5211.1 5324.1 5343.3 5294.7 5307.2 5309.6 5325.8 5328.0 5236.5 5292.8 5315.9 5316.8 5317.9 5117.8 5246.7 5255.4 5260.7 5301.5 5788.9 5811.5 5812.7 5819.1 5828.7 6144.7 6146.6 6178.4 6209.5 6224.6 6214.6 6234.2 6239.3 6254.1 6257.2 5783.3 5788.0 5792.4 5834.6 5844.5 6189.6 6238.7 6245.8 6282.8 6289.0 5962.5 5986.7 6073.2 6106.2 6109.3 6213.3 6235.7 6246.6 6259.4 6268.3 6097.1 6146.9 6184.3 6198.8 6205.0 6176.5 6233.2 6245.9 6293.2 6299.1 4990.9 4994.8 4995.1 5007.8 5014.5 4438.4 4440.0 4488.9 4523.1 4525.9 5832.1 5842.9 5890.8 5900.8 5966.8 6258.5 6261.5 6264.5 6271.7 6284.8 5923.7 5951.7 5967.3 5968.5 6008.4 5353.3 5372.5 5422.1 5426.4 5470.8 6260.3 6261.7 6291.7 6318.7 6440.6 6204.6 6217.5 6230.9 6256.3 6263.6 6328.7 6396.4 6422.7 6430.3 6442.7 5082.0 6105.8 6131.7 6142.9 6151.6 6293.4 6299.9 6303.1 6308.2 6330.8 6170.2 6189.8 6196.3 6205.1 6223.2 6049.6 6115.7 6181.8 6212.5 6225.9 5885.5 5933.4 6019.6 6058.2 6069.8 6127.5 6136.6 6141.9 6150.4 6156.9 5820.5 5955.2 5985.7 6033.1 6058.7 09:32:12 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | [#1] ext.html Cycles:180 Average:70999.78 Median:19383.17 stddev:156843.95 (809.2%) stddev-sans-first:157194.61 09:32:12 INFO - PROCESS | 1866 | Values: 495.4 520.8 537.8 540.7 565.1 32597.8 42142.4 43919.9 44077.8 45686.9 45021.0 45500.8 45509.6 45552.5 45565.1 9402.6 10156.6 10361.6 10362.2 10365.9 9399.2 12363.1 12665.7 12683.0 12784.1 12537.9 12610.2 12621.0 12629.9 12644.3 12522.6 12596.4 12600.8 12619.5 12621.2 12508.1 12602.3 12604.7 12616.5 12629.1 42560.0 42918.6 42929.0 42946.8 42985.0 3451.0 3487.7 3561.4 3586.2 3587.0 32306.7 32553.7 32802.0 32887.2 33424.8 3327.2 3347.9 3415.6 3462.2 3514.9 531501.0 532100.0 535248.0 535707.3 537360.0 35251.5 62875.7 63183.7 63352.7 63360.7 36012.6 36945.0 37334.1 37339.8 37400.6 15738.9 15891.0 15892.3 15897.4 15924.0 12159.5 12371.9 13012.9 13199.8 13328.0 7159.0 7912.6 7999.2 8166.5 8242.9 3435.1 3438.1 3449.4 3453.5 3484.1 966.7 1035.3 1043.2 1105.2 1111.9 31358.8 31593.5 31617.8 32252.4 32344.4 903.6 1074.1 1125.3 1133.7 1260.3 16046.0 16598.5 16647.0 16673.1 16716.7 710323.0 721308.0 722217.0 722598.0 723084.0 62124.0 62474.8 62497.7 62760.0 62876.4 1180.3 1323.4 1350.9 1419.4 1464.1 30550.1 32690.9 32777.7 33070.5 33406.8 52056.7 52646.7 52680.2 52815.0 52904.0 474433.0 474518.0 474896.0 476539.0 477867.0 25595.6 26190.3 27198.3 27943.2 27975.4 49264.0 49805.0 49933.0 50140.7 50396.7 39770.2 40830.6 41745.6 42459.8 42545.4 40969.0 41360.6 42188.4 42461.6 42630.8 32496.4 40509.8 40904.6 40962.4 41059.6 34687.0 36093.0 36231.0 36582.5 36676.8 23537.6 26646.3 26833.9 26933.7 27016.3 09:32:12 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | [#2] jquery.html Cycles:180 Average:37025.80 Median:26412.04 stddev:106377.07 (402.8%) stddev-sans-first:106639.78 09:32:12 INFO - PROCESS | 1866 | Values: 322.0 369.6 380.8 391.3 402.2 20443.3 21098.8 21826.4 21830.0 22032.4 12352.6 12546.9 15559.5 21448.8 22214.2 5397.4 5650.5 5654.5 5666.5 5698.4 5400.9 6087.0 6249.5 6254.9 6269.5 5264.7 5917.0 6212.8 6231.1 6232.0 6184.7 6233.8 6241.2 6246.7 6262.4 5231.6 5423.3 5492.8 5494.8 5495.8 19289.7 24242.6 26234.5 26243.5 26580.6 2310.7 2365.2 2430.6 2435.9 2666.4 10583.7 10607.3 10644.9 10802.7 14719.5 1865.5 1912.0 1917.9 1949.8 1987.0 129186.1 133275.0 135758.3 139012.0 142282.0 10754.4 11407.8 11563.6 11788.2 11857.1 13747.5 15061.7 15066.2 15091.0 15091.4 8727.5 8835.9 8866.5 8877.4 9008.5 8842.3 8854.6 8876.1 8996.1 9018.8 4565.5 4577.9 4587.2 4589.6 4636.3 1906.2 1915.3 1934.8 1963.9 1985.7 821.3 863.7 877.8 938.4 947.7 21154.6 21801.5 21850.0 21969.0 21970.5 904.4 933.2 946.3 998.6 999.1 8373.5 11304.8 11408.2 11440.0 11443.3 627294.9 628600.0 638212.0 639484.0 646398.0 98485.6 130822.0 132547.3 138795.0 142525.0 869.9 973.7 992.2 993.3 997.5 19183.4 20005.6 20474.9 20493.3 20709.9 51165.0 52725.7 60360.4 62639.0 63250.0 65323.0 85357.7 88765.6 90359.0 91333.0 7642.8 7925.8 8087.2 8155.7 8507.4 10282.6 10289.5 13051.9 13215.4 13496.0 11178.1 11732.9 11852.9 12088.9 12091.6 10499.4 11646.2 11657.9 12045.9 12050.3 11696.8 11818.5 11851.1 12076.0 12247.8 8462.5 8549.0 8570.8 8674.4 8763.1 10902.6 11739.5 11781.8 11918.8 11999.5 09:32:12 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | [#3] mootools.html Cycles:180 Average:5951.61 Median:4617.34 stddev:3380.19 (73.2%) stddev-sans-first:3373.86 09:32:12 INFO - PROCESS | 1866 | Values: 1600.4 1602.9 1820.9 1948.3 1961.7 8790.2 9351.6 9352.1 9362.7 9371.8 8214.8 8507.4 8834.5 9028.5 9041.3 2412.7 2440.9 2442.5 2445.4 2449.2 2421.9 2425.1 2458.6 2461.8 2464.8 2437.2 2450.1 2452.2 2462.4 2467.4 2410.6 2416.7 2425.8 2436.0 2436.7 2421.0 2430.9 2435.0 2442.5 2446.7 9077.0 9289.6 9291.7 9292.3 9300.8 4670.5 5691.0 5697.3 5702.9 5707.9 3619.3 3732.7 3803.4 3851.7 3867.4 1092.4 1118.4 1118.5 1144.8 1145.4 12262.0 12275.7 12279.9 12309.6 12362.4 4791.2 4837.3 4911.9 4984.0 5010.5 10401.3 10425.9 10426.4 10432.7 10437.1 5648.2 5651.9 5654.5 5661.0 5664.5 4635.3 4796.2 4803.1 5093.4 5144.8 4085.0 4107.7 4164.5 4210.7 4213.4 1304.5 1307.9 1313.2 1324.9 1326.3 919.7 943.6 948.8 963.1 988.3 5814.3 5974.7 5992.0 6107.5 6177.2 378.4 378.5 429.8 432.1 452.6 4093.4 4108.1 4122.2 4167.7 4190.9 10874.6 11163.4 11309.8 11356.3 11383.0 11190.6 11244.7 11277.9 11286.1 11292.7 8318.3 8412.2 8502.3 8511.6 8602.4 5852.4 5877.2 5880.1 5956.1 6038.9 10051.5 10338.2 10355.4 10377.3 10531.9 10594.4 10726.5 10727.9 10730.8 10747.4 6713.4 7144.9 7579.0 7900.9 8086.6 8671.6 8876.9 8892.7 8896.8 8904.7 7798.8 7852.5 7908.3 7915.4 8059.8 7686.5 7788.1 7796.2 7826.6 7843.7 7214.9 7346.6 7347.3 7350.2 7365.4 4599.4 5018.7 5053.6 5128.4 5436.2 5060.0 5078.3 5240.3 5270.2 5286.0 09:32:12 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | [#4] prototype.html Cycles:180 Average:4369.92 Median:4627.93 stddev:1324.41 (28.6%) stddev-sans-first:1292.53 09:32:12 INFO - PROCESS | 1866 | Values: 306.2 311.6 314.5 317.0 321.1 4968.9 5198.4 5494.2 5521.3 5565.7 4708.8 4788.6 5014.4 5092.9 5188.6 3455.5 3932.9 4088.3 4113.3 4113.4 3473.7 3865.6 4091.0 4157.4 4196.9 3789.4 3870.4 4120.5 4169.8 4192.2 4156.6 4161.5 4169.8 4200.2 4209.4 3513.3 3613.8 3635.0 3640.5 3657.6 4365.9 4601.5 5272.3 5277.6 5350.6 3999.3 4279.6 4602.2 4753.9 4781.3 5206.7 5397.1 5591.8 5680.3 5764.4 3378.4 3955.6 3965.7 3969.2 3993.3 5354.6 5885.4 6007.0 6088.0 6101.5 3098.4 4026.2 4031.4 4040.6 4065.6 4133.7 4150.5 4151.2 4162.9 4167.4 4506.8 4600.9 4753.6 4786.7 4792.5 4916.3 5695.1 5697.0 5762.4 5797.5 1326.3 1385.0 1386.9 1399.3 1402.9 754.9 811.0 856.9 882.2 882.4 3889.4 3989.7 4040.4 4283.4 4573.9 4465.6 4576.4 4636.4 4661.0 5010.1 3999.4 4069.8 4528.4 4536.1 4575.4 2373.5 2531.4 2557.9 2611.8 2632.8 4187.8 4538.9 4832.2 4872.0 5017.4 4255.9 4492.5 4619.4 4788.4 5096.7 4201.3 4304.7 4648.7 5036.8 5197.5 3920.9 4156.4 4163.6 4433.3 4806.9 4685.7 4697.0 4803.8 4829.9 4896.9 5029.7 5275.1 5657.1 6003.0 6032.8 4074.4 4077.6 4080.7 4084.3 4086.1 5758.6 5964.3 5965.3 6006.8 6013.9 5395.4 5520.8 5720.8 5722.8 5837.6 5503.9 5720.7 5721.5 5737.1 5742.6 5223.6 5299.3 5499.8 5635.4 5637.8 4813.6 5029.6 5060.8 5732.4 5774.0 4363.4 4938.0 5669.6 5690.2 5708.7 09:32:12 INFO - PROCESS | 1866 | 09:32:12 INFO - PROCESS | 1866 | [#5] yui.html Cycles:180 Average:27032.74 Median:97110.69 stddev:43827.67 (45.1%) stddev-sans-first:43906.14 09:32:12 INFO - PROCESS | 1866 | Values: 734.8 772.3 806.3 862.6 886.7 16189.9 16630.8 17194.1 17427.4 17637.4 15666.3 15869.3 15876.6 15906.5 15909.8 732.7 752.5 903.3 904.1 914.8 922.8 933.8 934.1 934.7 936.0 988.4 988.6 990.0 990.7 990.9 979.3 984.4 985.0 985.3 985.7 721.4 721.9 723.3 723.3 726.3 16468.5 16652.4 16839.0 16853.9 16895.0 12117.3 12731.9 13564.9 14061.5 14675.6 12481.2 13194.4 13800.0 14070.6 14084.8 4445.8 4711.8 4742.6 4749.8 4763.1 129263.0 138492.0 138673.3 140442.3 141701.0 10861.5 11464.3 11506.6 11598.2 11632.2 22336.0 22360.6 22367.0 22370.9 22490.3 6587.8 8069.8 8150.5 8211.6 8258.0 4559.9 4568.3 4599.5 4660.9 4731.6 3547.5 3663.0 3666.9 3667.7 3671.9 663.7 677.2 685.9 691.1 695.8 592.8 639.9 645.7 650.2 653.3 8929.0 9059.2 9213.0 9421.5 9618.1 529.5 560.5 652.4 652.9 670.0 4330.1 4649.5 4998.2 5221.6 5523.5 161831.0 167411.0 172179.1 172974.0 176584.0 115465.0 115890.0 116788.4 135385.0 138616.0 112230.0 115001.4 116195.4 116379.4 121038.0 9969.8 9987.7 10033.8 10763.9 10795.7 51694.2 58655.5 64949.7 66105.7 67931.0 90868.5 103279.0 103912.0 106168.0 110136.0 13917.1 14016.4 14157.3 14157.3 14430.3 14956.5 14988.2 15665.8 15711.6 17041.5 14112.0 14219.7 14678.8 15066.5 15130.3 14515.0 14519.9 14845.3 14885.6 15079.2 13349.3 13723.9 13986.9 14018.1 14542.0 11428.0 11582.9 11726.2 11795.6 12188.3 11848.9 12109.7 12231.3 12285.4 12337.3 09:32:12 INFO - PROCESS | 1866 | -------- Summary: end -------- 09:32:12 INFO - PROCESS | 1866 | 09:32:13 INFO - TEST-INFO | 1866: exit 0 09:32:13 INFO - TEST-OK | dromaeo_css | took 1115478ms 09:32:13 INFO - TEST-START | kraken 09:32:13 INFO - operating with platform_type : mac_ 09:32:13 INFO - Initialising browser for kraken test... 09:32:13 INFO - TEST-INFO | started process 1874 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpP4kWfp/profile http://localhost:49246/getInfo.html) 09:32:15 INFO - PROCESS | 1874 | __metrics Screen width/height:1600/1200 09:32:15 INFO - PROCESS | 1874 | colorDepth:24 09:32:15 INFO - PROCESS | 1874 | Browser inner width/height: 1024/689 09:32:15 INFO - PROCESS | 1874 | __metrics 09:32:15 INFO - PROCESS | 1874 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:32:15 INFO - PROCESS | 1874 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:32:16 INFO - TEST-INFO | 1874: exit 0 09:32:16 INFO - Browser initialized. 09:32:16 INFO - Running cycle 1/1 for kraken test... 09:32:16 INFO - TEST-INFO | started process 1876 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpP4kWfp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:28 INFO - PROCESS | 1876 | RSS: Main: 168013824 09:32:28 INFO - PROCESS | 1876 | 09:32:31 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:31 INFO - PROCESS | 1876 | RSS: Main: 219033600 09:32:31 INFO - PROCESS | 1876 | 09:32:34 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 09:32:35 INFO - PROCESS | 1876 | RSS: Main: 231088128 09:32:35 INFO - PROCESS | 1876 | 09:32:38 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 09:32:38 INFO - PROCESS | 1876 | RSS: Main: 327761920 09:32:38 INFO - PROCESS | 1876 | 09:32:40 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 09:32:41 INFO - PROCESS | 1876 | RSS: Main: 229949440 09:32:41 INFO - PROCESS | 1876 | 09:32:43 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:32:43 INFO - PROCESS | 1876 | RSS: Main: 230879232 09:32:43 INFO - PROCESS | 1876 | 09:32:46 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 09:32:47 INFO - PROCESS | 1876 | RSS: Main: 267382784 09:32:47 INFO - PROCESS | 1876 | 09:32:50 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 09:32:50 INFO - PROCESS | 1876 | RSS: Main: 267427840 09:32:50 INFO - PROCESS | 1876 | 09:32:53 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 09:32:54 INFO - PROCESS | 1876 | RSS: Main: 264388608 09:32:54 INFO - PROCESS | 1876 | 09:32:55 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:32:56 INFO - PROCESS | 1876 | RSS: Main: 240586752 09:32:56 INFO - PROCESS | 1876 | 09:32:58 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:32:58 INFO - PROCESS | 1876 | RSS: Main: 243744768 09:32:58 INFO - PROCESS | 1876 | 09:33:00 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:01 INFO - PROCESS | 1876 | RSS: Main: 254701568 09:33:01 INFO - PROCESS | 1876 | 09:33:04 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:05 INFO - PROCESS | 1876 | RSS: Main: 265719808 09:33:05 INFO - PROCESS | 1876 | 09:33:07 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:08 INFO - PROCESS | 1876 | RSS: Main: 260034560 09:33:08 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 09:33:10 INFO - PROCESS | 1876 | RSS: Main: 303783936 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | __start_tp_report 09:33:10 INFO - PROCESS | 1876 | _x_x_mozilla_page_load 09:33:10 INFO - PROCESS | 1876 | _x_x_mozilla_page_load_details 09:33:10 INFO - PROCESS | 1876 | |i|pagename|runs| 09:33:10 INFO - PROCESS | 1876 | |0;ai-astar;115;114;114;109;113;115;113;116;114;114 09:33:10 INFO - PROCESS | 1876 | |1;audio-beat-detection;142;107;107;139;106;112;135;131;143;106 09:33:10 INFO - PROCESS | 1876 | |2;audio-dft;183;185;182;184;184;192;257;184;180;183 09:33:10 INFO - PROCESS | 1876 | |3;audio-fft;81;59;58;87;62;58;58;57;81;57 09:33:10 INFO - PROCESS | 1876 | |4;audio-oscillator;86;70;92;70;68;69;92;68;69;89 09:33:10 INFO - PROCESS | 1876 | |5;imaging-gaussian-blur;89;86;90;87;89;87;90;87;89;85 09:33:10 INFO - PROCESS | 1876 | |6;imaging-darkroom;140;94;93;95;94;94;95;94;95;93 09:33:10 INFO - PROCESS | 1876 | |7;imaging-desaturate;84;83;86;84;84;87;84;85;84;83 09:33:10 INFO - PROCESS | 1876 | |8;json-parse-financial;56;53;53;50;54;55;55;54;55;55 09:33:10 INFO - PROCESS | 1876 | |9;json-stringify-tinderbox;63;72;77;74;71;67;68;69;68;69 09:33:10 INFO - PROCESS | 1876 | |10;stanford-crypto-aes;81;83;82;84;80;78;79;85;88;81 09:33:10 INFO - PROCESS | 1876 | |11;stanford-crypto-ccm;133;143;145;133;129;134;139;163;187;167 09:33:10 INFO - PROCESS | 1876 | |12;stanford-crypto-pbkdf2;162;159;161;159;161;156;159;158;158;157 09:33:10 INFO - PROCESS | 1876 | |13;stanford-crypto-sha256-iterative;64;66;65;65;65;60;75;83;83;79 09:33:10 INFO - PROCESS | 1876 | __end_tp_report 09:33:10 INFO - PROCESS | 1876 | __start_cc_report 09:33:10 INFO - PROCESS | 1876 | _x_x_mozilla_cycle_collect,1922 09:33:10 INFO - PROCESS | 1876 | __end_cc_report 09:33:10 INFO - PROCESS | 1876 | __startTimestamp1480095190386__endTimestamp 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | ------- Summary: start ------- 09:33:10 INFO - PROCESS | 1876 | Number of tests: 14 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:1.89 (1.7%) stddev-sans-first:1.94 09:33:10 INFO - PROCESS | 1876 | Values: 115.0 114.0 114.0 109.0 113.0 115.0 113.0 116.0 114.0 114.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:133.00 stddev:16.45 (12.4%) stddev-sans-first:15.91 09:33:10 INFO - PROCESS | 1876 | Values: 142.0 107.0 107.0 139.0 106.0 112.0 135.0 131.0 143.0 106.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#2] audio-dft Cycles:10 Average:191.40 Median:184.00 stddev:23.26 (12.6%) stddev-sans-first:24.47 09:33:10 INFO - PROCESS | 1876 | Values: 183.0 185.0 182.0 184.0 184.0 192.0 257.0 184.0 180.0 183.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#3] audio-fft Cycles:10 Average:65.80 Median:60.50 stddev:12.06 (19.9%) stddev-sans-first:11.47 09:33:10 INFO - PROCESS | 1876 | Values: 81.0 59.0 58.0 87.0 62.0 58.0 58.0 57.0 81.0 57.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:78.00 stddev:10.86 (13.9%) stddev-sans-first:11.06 09:33:10 INFO - PROCESS | 1876 | Values: 86.0 70.0 92.0 70.0 68.0 69.0 92.0 68.0 69.0 89.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.73 (1.9%) stddev-sans-first:1.79 09:33:10 INFO - PROCESS | 1876 | Values: 89.0 86.0 90.0 87.0 89.0 87.0 90.0 87.0 89.0 85.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:94.00 stddev:14.53 (15.5%) stddev-sans-first:0.78 09:33:10 INFO - PROCESS | 1876 | Values: 140.0 94.0 93.0 95.0 94.0 94.0 95.0 94.0 95.0 93.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.00 stddev:1.26 (1.5%) stddev-sans-first:1.33 09:33:10 INFO - PROCESS | 1876 | Values: 84.0 83.0 86.0 84.0 84.0 87.0 84.0 85.0 84.0 83.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:55.00 stddev:1.70 (3.1%) stddev-sans-first:1.64 09:33:10 INFO - PROCESS | 1876 | Values: 56.0 53.0 53.0 50.0 54.0 55.0 55.0 54.0 55.0 55.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:3.91 (5.6%) stddev-sans-first:3.28 09:33:10 INFO - PROCESS | 1876 | Values: 63.0 72.0 77.0 74.0 71.0 67.0 68.0 69.0 68.0 69.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.50 stddev:3.00 (3.6%) stddev-sans-first:3.15 09:33:10 INFO - PROCESS | 1876 | Values: 81.0 83.0 82.0 84.0 80.0 78.0 79.0 85.0 88.0 81.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:144.00 stddev:18.93 (13.1%) stddev-sans-first:19.35 09:33:10 INFO - PROCESS | 1876 | Values: 133.0 143.0 145.0 133.0 129.0 134.0 139.0 163.0 187.0 167.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.00 Median:159.00 stddev:1.89 (1.2%) stddev-sans-first:1.66 09:33:10 INFO - PROCESS | 1876 | Values: 162.0 159.0 161.0 159.0 161.0 156.0 159.0 158.0 158.0 157.0 09:33:10 INFO - PROCESS | 1876 | 09:33:10 INFO - PROCESS | 1876 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:70.50 stddev:8.62 (12.2%) stddev-sans-first:8.81 09:33:10 INFO - PROCESS | 1876 | Values: 64.0 66.0 65.0 65.0 65.0 60.0 75.0 83.0 83.0 79.0 09:33:10 INFO - PROCESS | 1876 | -------- Summary: end -------- 09:33:10 INFO - PROCESS | 1876 | 09:33:11 INFO - TEST-INFO | 1876: exit 0 09:33:11 INFO - TEST-OK | kraken | took 58057ms 09:33:11 INFO - SUITE-END | took 1173s 09:33:11 INFO - Completed test suite (00:19:33) 09:33:11 INFO - javascript benchmark 09:33:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3413.5221069050335, 3422.3155368926214, 3460.1003739027847, 3469.583649962005, 3520.6127325994134, 5693.060644993577, 5743.655380677163, 5744.79893203738, 5749.108888122342, 5781.710914454282, 5660.886782264352, 5663.207150998864, 5762.106873434614, 5763.106718458635, 5779.335376431701, 5194.896102077965, 5242.868928276805, 5270.5520030797425, 5270.9736451317685, 5292.655977361478, 5192.428832828386, 5198.532132108109, 5211.062008838408, 5324.094903866352, 5343.305370301855, 5294.735263236842, 5307.2039194121, 5309.601779866506, 5325.813596524123, 5328.040952628526, 5236.528712415863, 5292.761825717852, 5315.867103322409, 5316.760745766411, 5317.9202311965155, 5117.795288188468, 5246.737663116867, 5255.448177941299, 5260.684358938476, 5301.496357845998, 5788.942110578924, 5811.476967072984, 5812.709364531799, 5819.068948968187, 5828.708564571754, 6144.662043587601, 6146.58628515444, 6178.41305076017, 6209.534284928648, 6224.564280500367, 6214.5649804513705, 6234.220722409699, 6239.344868788802, 6254.124422580842, 6257.186565746424, 5783.306003279633, 5787.971060144714, 5792.362840087587, 5834.620749651259, 5844.503217226541, 6189.566730328834, 6238.656873871981, 6245.843853903689, 6282.842928926813, 6289.0, 5962.493188079062, 5986.670733109634, 6073.18012068374, 6106.236720409949, 6109.327973922865, 6213.316535181127, 6235.688215589203, 6246.625202487865, 6259.436650701458, 6268.279147897959, 6097.07100518335, 6146.8935591593045, 6184.257889053252, 6198.783042593578, 6205.0, 6176.505879529627, 6233.158523599256, 6245.906311405332, 6293.181886354744, 6299.149614802035, 4990.90018199634, 4994.750262486934, 4995.100881841231, 5007.799688012438, 5014.548690617862, 4438.4451943507065, 4440.023194897118, 4488.865334039984, 4523.117991991596, 4525.932111018337, 5832.096025116114, 5842.948269311565, 5890.823275301662, 5900.793472228537, 5966.791162309299, 6258.468030217483, 6261.499080073583, 6264.498840092781, 6271.749130034838, 6284.811455656338, 5923.703814809329, 5951.702414879239, 5967.343592204854, 5968.522518198621, 6008.369121242206, 5353.330833645794, 5372.5433338166695, 5422.05114105038, 5426.430224826337, 5470.781168753205, 6260.311365749672, 6261.749530018748, 6291.716872740829, 6318.652474113968, 6440.581362211628, 6204.627722336494, 6217.502599791916, 6230.875382492415, 6256.31180570146, 6263.592866463848, 6328.683565821599, 6396.360363963567, 6422.678866056772, 6430.324815894263, 6442.742290308335, 5082.0, 6105.8168254952425, 6131.743695906945, 6142.897836592492, 6151.615886425698, 6293.37066293367, 6299.905501417391, 6303.149074874833, 6308.243010838729, 6330.841728956629, 6170.167027451236, 6189.845253868509, 6196.256449225942, 6205.131281620486, 6223.222097237845, 6049.637021778707, 6115.694215289307, 6181.81454556364, 6212.534059945432, 6225.937740622717, 5885.499732522783, 5933.3769954154195, 6019.638821670714, 6058.212432383762, 6069.848253793514, 6127.509799216141, 6136.64993701385, 6141.925163096351, 6150.354212807591, 6156.907646385218, 5820.534357251325, 5955.196048533566, 5985.730642121028, 6033.095035744674, 6058.697065146813], "value": 5747.292311393311, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.43767823520307, 520.7709804860531, 537.7795104007358, 540.7350398304832, 565.1466285908277, 32597.837010814943, 42142.367864482054, 43919.902002449875, 44077.77961110194, 45686.857828554304, 45021.0, 45500.77249613747, 45509.63471095864, 45552.544474555245, 45565.08869822601, 9402.576884040234, 10156.59373625056, 10361.637342693008, 10362.171026317876, 10365.896341036569, 9399.24806015517, 12363.072769542276, 12665.746685066293, 12683.0, 12784.105112642119, 12537.874621253808, 12610.180338277982, 12621.0, 12629.936850315735, 12644.304563249018, 12522.56171034014, 12596.37018149098, 12600.81098783519, 12619.495220191182, 12621.242725436503, 12508.061895357885, 12602.306873121976, 12604.68488287791, 12616.495340186382, 12629.115961882671, 42560.0, 42918.57081429177, 42929.0, 42946.785266073784, 42985.0, 3450.9827450862836, 3487.720982321408, 3561.4123669594487, 3586.228960773446, 3587.013571267896, 32306.676933230836, 32553.6744632553, 32802.0, 32887.17782055444, 33424.83287583571, 3327.1848397142853, 3347.866085356583, 3415.573053368329, 3462.2036931505645, 3514.859405623772, 531501.0, 532100.0, 535248.0, 535707.3214633941, 537360.0, 35251.4712279316, 62875.685621571596, 63183.6840815793, 63352.68323658352, 63360.68319658372, 36012.63987360093, 36945.0, 37334.066648333464, 37339.81330093386, 37400.62599373971, 15738.9213053934, 15891.046537207574, 15892.284847181905, 15897.443589474313, 15924.0, 12159.452824622913, 12371.876281237073, 13012.934935325262, 13199.802002969964, 13328.00039997004, 7159.0, 7912.564808935564, 7999.200079991954, 8166.510009399455, 8242.917570824335, 3435.1068722132427, 3438.1060924159397, 3449.379111759907, 3453.533772940621, 3484.1463841358523, 966.7051549277397, 1035.3270374256792, 1043.222799014739, 1105.1987309200927, 1111.9499622517005, 31358.84320578428, 31593.526097108563, 31617.8419107903, 32252.354952900812, 32344.35311293808, 903.620479398641, 1074.104206076276, 1125.3135587291745, 1133.6769020829024, 1260.3005332040623, 16045.957012794132, 16598.502044938672, 16647.0, 16673.08298043595, 16716.66566668684, 710323.0, 721308.0, 722217.0, 722598.0, 723084.0, 62124.0, 62474.75050498964, 62497.68751156306, 62760.0, 62876.42808929813, 1180.3095189314176, 1323.371398585664, 1350.8716671916152, 1419.4251328211678, 1464.0849469081825, 30550.08349749511, 32690.855820046185, 32777.67222327746, 33070.50394244136, 33406.83296583501, 52056.73971630118, 52646.736766315924, 52680.209796853844, 52815.0, 52904.0, 474433.0, 474518.0, 474896.0, 476539.0, 477867.0, 25595.616065758655, 26190.34524136912, 27198.320041999108, 27943.16170514888, 27975.440491190468, 49264.0, 49805.0, 49933.0, 50140.749296253285, 50396.748016259684, 39770.2045959085, 40830.59169408268, 41745.58254417417, 42459.787701061294, 42545.36181957335, 40969.0, 41360.58639413567, 42188.36717449302, 42461.575384247, 42630.78684606557, 32496.350072997935, 40509.79745101256, 40904.590954090076, 40962.38556421596, 41059.58940410558, 34687.0, 36093.0, 36231.0, 36582.451263231604, 36676.81661591675, 23537.64693529564, 26646.333841654116, 26833.865830670722, 26933.730662693124, 27016.32459188536], "value": 19998.236302355694, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.9806811591305, 369.6285233340493, 380.8495644220534, 391.2644228849762, 402.2417742555284, 20443.28448504301, 21098.789012109875, 21826.380834299867, 21830.017649205784, 22032.44918877025, 12352.567660131897, 12546.874531254685, 15559.53321400357, 21448.785512144874, 22214.222502212426, 5397.406285308603, 5650.49145576898, 5654.547636189116, 5666.51834594058, 5698.43015698431, 5400.91898621521, 6087.0, 6249.500039996789, 6254.874902501947, 6269.498440124779, 5264.736763161845, 5917.0, 6212.844678883019, 6231.127642130105, 6232.0, 6184.690765461732, 6233.812985610439, 6241.21984751906, 6246.656433896134, 6262.37376262377, 5231.633785635007, 5423.267858839045, 5492.780288788444, 5494.835154945358, 5495.835124946258, 19289.710654340055, 24242.636360454606, 26234.475310493875, 26243.47513049728, 26580.601290980652, 2310.7111611048617, 2365.207655435414, 2430.586800243963, 2435.914742984005, 2666.4400475900084, 10583.73540661482, 10607.257491975564, 10644.946775266151, 10802.72993175169, 14719.484818031318, 1865.486991077451, 1911.9808801911943, 1917.9328723494611, 1949.7757757857887, 1987.0064967516241, 129186.06220906693, 133275.0, 135758.32120839332, 139012.0, 142281.99999999895, 10754.386841973766, 11407.771844563229, 11563.595274165364, 11788.174827762146, 11857.051435885109, 13747.518836840663, 15061.698766024838, 15066.171360575054, 15091.01908375952, 15091.396344146111, 8727.519986400683, 8835.911640883638, 8866.512341821133, 8877.422967507091, 9008.504532250658, 8842.33682473817, 8854.557272136368, 8876.112388761072, 8996.145366190201, 9018.819623607491, 4565.452145742533, 4577.885552861205, 4587.174308624413, 4589.5639914208095, 4636.281376386665, 1906.2279776690464, 1915.348781414326, 1934.7968463311431, 1963.9312624058089, 1985.7120717495882, 821.34292565947, 863.6631713631689, 877.8200468903859, 938.3759799733238, 947.6541062512272, 21154.576908461746, 21801.454963625718, 21850.0, 21969.011394487286, 21970.45073873166, 904.4347282948157, 933.2067742419025, 946.3328353510758, 998.55065220651, 999.0608827701936, 8373.455725377831, 11304.81299463561, 11408.201425900274, 11440.0, 11443.313401195956, 627294.8635256885, 628600.0, 638212.0, 639484.0, 646398.0, 98485.55054261675, 130822.03766943504, 132547.3490530184, 138795.0, 142525.0, 869.8999615044224, 973.7419583810296, 992.1864071461476, 993.2649839119144, 997.4563862694994, 19183.424497265103, 20005.599888001867, 20474.897625511778, 20493.2827351042, 20709.896450517652, 51164.97670046653, 52725.7363713179, 60360.396396035474, 62639.0, 63250.0, 65323.0, 85357.71963420429, 88765.55617221873, 90359.0, 91333.0, 7642.777155655069, 7925.762227133196, 8087.191280871866, 8155.673773049249, 8507.361947853811, 10282.588696452058, 10289.485525723534, 13051.934740326238, 13215.405306761026, 13496.0, 11178.13751018277, 11732.882671173178, 11852.940735296614, 12088.939555302168, 12091.57679481214, 10499.370037797757, 11646.24299420535, 11657.88342116602, 12045.939770301093, 12050.276983381025, 11696.766064678828, 11818.46816893259, 11851.111166662362, 12076.03391728642, 12247.816282755944, 8462.534560599226, 8548.957255213683, 8570.828583428422, 8674.436161649473, 8763.079876612865, 10902.563897444013, 11739.530418783153, 11781.823272650745, 11918.761624767283, 11999.460024299102], "value": 9807.01241819317, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1600.4078490958343, 1602.8877978541502, 1820.8634352423571, 1948.3473036532762, 1961.6567100757363, 8790.208881200691, 9351.579178936947, 9352.064793520645, 9362.719118426441, 9371.765705857357, 8214.753557393287, 8507.36194785392, 8834.469931804097, 9028.50343231122, 9041.276697864172, 2412.6742889709885, 2440.926772196828, 2442.4504486490573, 2445.3886528367907, 2449.2284930246915, 2421.8546887186735, 2425.0784701813286, 2458.557459657261, 2461.7538246175327, 2464.75352464753, 2437.2200895713377, 2450.0934654177977, 2452.2152911068465, 2462.4336402627405, 2467.3584867934287, 2410.554047501218, 2416.6616673665612, 2425.818063645216, 2435.9878200608973, 2436.731959484473, 2420.987895060531, 2430.902763889442, 2435.0138194031447, 2442.46265821519, 2446.657467954488, 9077.04691007448, 9289.581968811419, 9291.674791382336, 9292.349535532516, 9300.767480812967, 4670.486246512881, 5691.004074287024, 5697.259356283656, 5702.885942281131, 5707.885842283131, 3619.2761447710564, 3732.720045996561, 3803.353429916893, 3851.7496362736333, 3867.361885288924, 1092.3773449133996, 1118.3792994887833, 1118.457548089176, 1144.7710457908452, 1145.4387350198426, 12261.957733592655, 12275.69310767229, 12279.93860030703, 12309.569165079269, 12362.38188090592, 4791.1854984652, 4837.298591704183, 4911.87720306988, 4983.953369792312, 5010.49895010496, 10401.27191096616, 10425.9478702606, 10426.426546540013, 10432.73918152037, 10437.112845408225, 5648.152777083388, 5651.858703532444, 5654.547636189095, 5661.0, 5664.49019588239, 4635.3278774578175, 4796.232602783538, 4803.135435621622, 5093.388793344822, 5144.794208231704, 4084.959150408458, 4107.732997355162, 4164.5210800758305, 4210.663146948237, 4213.431186789805, 1304.4651692806094, 1307.8626744191722, 1313.2055106660332, 1324.8807607315196, 1326.316946772413, 919.7332773495611, 943.6461327002374, 948.8102379523984, 963.1043129889131, 988.2637435110888, 5814.3313518945015, 5974.701264936736, 5992.0, 6107.480864126599, 6177.166082578884, 378.44179834743534, 378.5419642232929, 429.81303133137186, 432.0796703022542, 452.5564946352593, 4093.406456063854, 4108.116754897711, 4122.216778812016, 4167.6874234432535, 4190.916181676411, 10874.619388321371, 11163.441827908735, 11309.773804524028, 11356.26184298018, 11383.0, 11190.552377904793, 11244.662660120046, 11277.887221127849, 11286.097112231002, 11292.717682058013, 8318.292945099734, 8412.242898139075, 8502.2773064289, 8511.616977236039, 8602.397832151815, 5852.356240813422, 5877.177195192591, 5880.117982302689, 5956.136360227656, 6038.9396106038375, 10051.547680354255, 10338.224633152393, 10355.378677279386, 10377.27359084887, 10531.894681053398, 10594.417307048187, 10726.463676815973, 10727.946360268148, 10730.785384292427, 10747.408892510986, 6713.362230588086, 7144.892826607709, 7579.0, 7900.920990790249, 8086.555239461651, 8671.566421678765, 8876.86684699717, 8892.73321800347, 8896.777580560538, 8904.73285801427, 7798.805029874298, 7852.528848269122, 7908.288254057163, 7915.366770658448, 8059.83880322402, 7686.461947663834, 7788.143304236416, 7796.181400952818, 7826.647800848861, 7843.686252550062, 7214.855702886018, 7346.632668366454, 7347.2652734728235, 7350.228226036189, 7365.410767138723, 4599.356090147449, 5018.723970181675, 5053.620978426707, 5128.435872053997, 5436.157395603688, 5059.970200527573, 5078.339815823919, 5240.266362709148, 5270.183121616155, 5285.973570132125], "value": 4683.387862552144, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.2176139962396, 311.58403531285734, 314.460699899672, 316.9841507924603, 321.0576956632283, 4968.8571628525415, 5198.350206224227, 5494.175873618954, 5521.337439507255, 5565.6660600364, 4708.835190768324, 4788.616910647149, 5014.373203349582, 5092.872678183047, 5188.558972487333, 3455.51622772812, 3932.941005884914, 4088.2641124597562, 4113.25961326961, 4113.444684967536, 3473.687368136867, 3865.6327648873453, 4091.0, 4157.438745769313, 4196.874093777176, 3789.3747531657245, 3870.3807390817474, 4120.505539335284, 4169.833206671729, 4192.24539582875, 4156.646685031776, 4161.500619925629, 4169.791510424482, 4200.22295875261, 4209.410682504452, 3513.332466831294, 3613.819309034564, 3635.0, 3640.5085313482605, 3657.6342365763476, 4365.908522869283, 4601.539846015372, 5272.314599102092, 5277.56957977314, 5350.598705097094, 3999.2601368746873, 4279.636230920375, 4602.240630295997, 4753.928691069636, 4781.258904869716, 5206.713630750307, 5397.084035167538, 5591.832245032656, 5680.3467601225975, 5764.394738552475, 3378.3918894598964, 3955.564887862333, 3965.662918651918, 3969.2260009298197, 3993.28120938231, 5354.571634269249, 5885.352611212763, 6006.969965150146, 6088.0, 6101.450869421729, 3098.395812816524, 4026.2350153470748, 4031.4154447604933, 4040.6363427291685, 4065.57311482296, 4133.689973252018, 4150.522689890641, 4151.169766046803, 4162.937555936664, 4167.437395951508, 4506.842260520866, 4600.930986035212, 4753.619710423158, 4786.736729479842, 4792.496787837296, 4916.311716359713, 5695.117256825199, 5697.0090702381585, 5762.394948530427, 5797.4782269595935, 1326.2824642088326, 1384.9861501384858, 1386.8821150202143, 1399.307342865288, 1402.8737413632823, 754.8827734952301, 811.022717625272, 856.905740368559, 882.2016075451727, 882.4043770455046, 3889.436031775433, 3989.68082553395, 4040.4141399496907, 4283.400323954649, 4573.862784116482, 4465.642748580106, 4576.352513095283, 4636.420447444069, 4661.040239447907, 5010.073136469765, 3999.3800960851113, 4069.7761623111014, 4528.411306530196, 4536.092781443725, 4575.382323386367, 2373.525294940984, 2531.443162455114, 2557.9256712180927, 2611.817172797924, 2632.8147637461243, 4187.838182172441, 4538.909221815611, 4832.230859929261, 4871.952530206023, 5017.37282839645, 4255.872323830228, 4492.528284530143, 4619.445666520039, 4788.401449818773, 5096.745162741848, 4201.34879093741, 4304.74171549708, 4648.651351148677, 5036.84889453317, 5197.532222100029, 3920.9215815684097, 4156.376543518496, 4163.625273725379, 4433.273536774925, 4806.9330188019485, 4685.718856868599, 4697.034765847423, 4803.807847686193, 4829.937413768966, 4896.92654610174, 5029.723365214802, 5275.129603615514, 5657.086600400879, 6002.969985150046, 6032.777056060862, 4074.3684728865887, 4077.571854955187, 4080.6735461162443, 4084.2852500812833, 4086.1010577672864, 5758.568107392046, 5964.329987603478, 5965.28416590012, 6006.789762358471, 6013.9097913532205, 5395.379531353945, 5520.834374968757, 5720.771169153354, 5722.82682050187, 5837.6497410155525, 5503.944960550343, 5720.74256658443, 5721.542276617944, 5737.139429085671, 5742.626729262584, 5223.582113430993, 5299.33758280215, 5499.807506737245, 5635.408282130317, 5637.830865074055, 4813.566778989768, 5029.59763218949, 5060.797568097235, 5732.4267573244015, 5773.971130144322, 4363.389125522366, 4937.987942238702, 5669.603127781016, 5690.231818704588, 5708.686022268649], "value": 3956.3644575673566, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.8089496730852, 772.3280745751198, 806.3428305930667, 862.5859587398047, 886.7162507997443, 16189.919050404746, 16630.83369166308, 17194.05432701201, 17427.390041348557, 17637.382691605795, 15666.295016724247, 15869.285882135302, 15876.603084922883, 15906.52280431589, 15909.761353579677, 732.6702983657349, 752.4657493179849, 903.345074820753, 904.1365495951351, 914.8078903430288, 922.8385032619299, 933.7572231219898, 934.1499235695518, 934.7055677461611, 936.0078316983985, 988.3773222869582, 988.5502096546054, 989.9653512127077, 990.7126933189367, 990.9256805739562, 979.3193730357405, 984.44378925907, 984.990150098497, 985.3398223190479, 985.6895078050427, 721.3579913876654, 721.8808896532066, 723.2911746488418, 723.3200791256203, 726.2954933714288, 16468.49672158171, 16652.417165399154, 16839.0, 16853.904496207706, 16895.0, 12117.27296362221, 12731.85413312797, 13564.93217533916, 14061.5078472254, 14675.559733207914, 12481.188722732993, 13194.406251718696, 13800.0, 14070.577882663536, 14084.788728169086, 4445.799939002753, 4711.7879695413785, 4742.644301677388, 4749.833755818529, 4763.118823017753, 129263.0, 138492.0, 138673.3066334672, 140442.29778851144, 141701.0, 10861.456927153611, 11464.31214127155, 11506.59726909571, 11598.246114002563, 11632.185746997799, 22336.0, 22360.55278894413, 22367.0, 22370.888145559493, 22490.32529024132, 6587.769428070091, 8069.798255043671, 8150.470219435718, 8211.630476628567, 8258.0, 4559.931601025987, 4568.33759104928, 4599.5400459953735, 4660.906781864343, 4731.550502702238, 3547.5033495310677, 3663.0109870335395, 3666.944995825065, 3667.6882464990776, 3671.889843304705, 663.6947004377944, 677.2279601254573, 685.9211190713032, 691.0843132848995, 695.7738734911073, 592.8073376152768, 639.9072134540465, 645.686841881687, 650.180772226988, 653.2748648999607, 8929.0, 9059.184673379295, 9212.965507592538, 9421.528923553795, 9618.134367906921, 529.4625954656027, 560.4927540575785, 652.3509108437136, 652.8988006858945, 669.9832504187434, 4330.133973205371, 4649.488556258809, 4998.239358035228, 5221.582273418117, 5523.538657286067, 161831.0, 167411.0, 172179.13910430617, 172974.0, 176584.0, 115465.0, 115890.0, 116788.41605791917, 135385.0, 138616.0, 112230.0, 115001.4249928745, 116195.41902290603, 116379.41810291064, 121038.0, 9969.800603988024, 9987.650432234837, 10033.785809959689, 10763.94618026905, 10795.73010674708, 51694.22458663048, 58655.53361165834, 64949.67525162344, 66105.67788644297, 67931.0, 90868.54565727129, 103279.0, 103912.0, 106168.0, 110136.0, 13917.095388799695, 14016.369263382969, 14157.292135393394, 14157.292135393394, 14430.278486075444, 14956.476523321631, 14988.175650339335, 15665.765013525035, 15711.607209819389, 17041.48875533736, 14112.0, 14219.715605687621, 14678.77981830252, 15066.472673456376, 15130.319135638703, 14515.0, 14519.92740036293, 14845.257737113317, 14885.627859303604, 15079.170645614158, 13349.265790381622, 13723.862761372658, 13986.930065349608, 14018.088824226392, 14541.98206125561, 11428.0, 11582.94208528952, 11726.179167458537, 11795.646130616095, 12188.268703877795, 11848.881511184778, 12109.69725756863, 12231.26612403223, 12285.38573071325, 12337.321447320484], "value": 8350.3233284234, "unit": "score"}], "name": "dromaeo_css", "value": 7474.80405244242, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 109.0, 113.0, 115.0, 113.0, 116.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 107.0, 139.0, 106.0, 112.0, 135.0, 131.0, 143.0, 106.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 182.0, 184.0, 184.0, 192.0, 257.0, 184.0, 180.0, 183.0], "value": 191.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 87.0, 62.0, 58.0, 58.0, 57.0, 81.0, 57.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 92.0, 70.0, 68.0, 69.0, 92.0, 68.0, 69.0, 89.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 89.0, 87.0, 90.0, 87.0, 89.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 94.0, 93.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 93.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 86.0, 84.0, 84.0, 87.0, 84.0, 85.0, 84.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 50.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 77.0, 74.0, 71.0, 67.0, 68.0, 69.0, 68.0, 69.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 84.0, 80.0, 78.0, 79.0, 85.0, 88.0, 81.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 143.0, 145.0, 133.0, 129.0, 134.0, 139.0, 163.0, 187.0, 167.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 159.0, 161.0, 156.0, 159.0, 158.0, 158.0, 157.0], "value": 159.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 65.0, 65.0, 65.0, 60.0, 75.0, 83.0, 83.0, 79.0], "value": 70.5, "unit": "score"}], "name": "kraken", "value": 1424.6999999999998, "alertThreshold": 2.0}]} 09:33:11 INFO - Return code: 0 09:33:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:33:11 INFO - # TBPL SUCCESS # 09:33:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:33:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:33:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:33:11 INFO - 2016-11-25 09:33:11.696 screenresolution[1880:10300] starting screenresolution argv=screenresolution get 09:33:11 INFO - 2016-11-25 09:33:11.699 screenresolution[1880:10300] Display 0: 1600x1200x32@60 09:33:11 INFO - 2016-11-25 09:33:11.704 screenresolution[1881:10303] starting screenresolution argv=screenresolution list 09:33:11 INFO - Available Modes on Display 0 09:33:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:33:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:33:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:33:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:33:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:33:11 INFO - Intel Iris: 09:33:11 INFO - Chipset Model: Intel Iris 09:33:11 INFO - Type: GPU 09:33:11 INFO - Bus: Built-In 09:33:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:33:11 INFO - Vendor: Intel (0x8086) 09:33:11 INFO - Device ID: 0x0a2e 09:33:11 INFO - Revision ID: 0x0009 09:33:11 INFO - Displays: 09:33:11 INFO - Display: 09:33:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:33:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:33:11 INFO - Display Serial Number: 1600x1200 60 09:33:11 INFO - Main Display: Yes 09:33:11 INFO - Mirror: Off 09:33:11 INFO - Online: Yes 09:33:11 INFO - Rotation: Supported 09:33:11 INFO - Return code: 0 09:33:11 INFO - Running post-action listener: _resource_record_post_action 09:33:11 INFO - [mozharness: 2016-11-25 17:33:11.937822Z] Finished run-tests step (success) 09:33:11 INFO - Running post-run listener: _resource_record_post_run 09:33:11 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 38257664; Write bytes: 496755200; Read time: 376; Write time: 1979 09:33:11 INFO - TinderboxPrint: CPU usage
53.5% 09:33:11 INFO - TinderboxPrint: I/O read bytes / time
38,257,664 / 376 09:33:11 INFO - TinderboxPrint: I/O write bytes / time
496,755,200 / 1,979 09:33:11 INFO - TinderboxPrint: CPU idle
2,226.9 (46.5%) 09:33:11 INFO - TinderboxPrint: CPU system
959.2 (20.0%) 09:33:11 INFO - TinderboxPrint: CPU user
1,603.5 (33.5%) 09:33:11 INFO - TinderboxPrint: Swap in / out
233,598,976 / 0 09:33:12 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 192473600; Write bytes: 189503488; Read time: 17347; Write time: 433 09:33:12 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 32001024; Write bytes: 297089536; Read time: 298; Write time: 1491 09:33:12 INFO - Running post-run listener: _upload_blobber_files 09:33:12 INFO - Blob upload gear active. 09:33:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:33:12 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 09:33:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:33:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:33:12 INFO - (blobuploader) - INFO - Open directory for files ... 09:33:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:33:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:33:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:14 INFO - (blobuploader) - INFO - Done attempting. 09:33:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:33:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:33:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:15 INFO - (blobuploader) - INFO - Done attempting. 09:33:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:33:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:33:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:17 INFO - (blobuploader) - INFO - Done attempting. 09:33:17 INFO - (blobuploader) - INFO - Iteration through files over. 09:33:17 INFO - Return code: 0 09:33:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:33:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:33:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/79ce1d807273ed1a0f19fc3517775a24a4d1dffbcf3fbd572f0df807a7adc39e565bb2fb1a1a0648d9cb4e7a806564f4370f9a5d412a1fd10d08defac481138c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/124669b3e624bd2717f569d1daac7702e07494a14491652de5e11c89263f43cd3e863f869caf87b6947cfc8cf38741b40942a8fb34789edc89d0e33136a89095", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e75c6b498599bc0991ad17f19b18d1366025f7f4223f202ae7f1a066e97f5636c5c99f8a638a9302a0802275b0a18d1b639ddec083eeeadbbb69ec215f47db29"} 09:33:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:33:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:33:17 INFO - Contents: 09:33:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/79ce1d807273ed1a0f19fc3517775a24a4d1dffbcf3fbd572f0df807a7adc39e565bb2fb1a1a0648d9cb4e7a806564f4370f9a5d412a1fd10d08defac481138c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/124669b3e624bd2717f569d1daac7702e07494a14491652de5e11c89263f43cd3e863f869caf87b6947cfc8cf38741b40942a8fb34789edc89d0e33136a89095", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e75c6b498599bc0991ad17f19b18d1366025f7f4223f202ae7f1a066e97f5636c5c99f8a638a9302a0802275b0a18d1b639ddec083eeeadbbb69ec215f47db29"} 09:33:17 INFO - Running post-run listener: copy_logs_to_upload_dir 09:33:17 INFO - Copying logs to upload dir... 09:33:17 INFO - mkdir: /builds/slave/test/build/upload/logs 09:33:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.239975 ========= master_lag: 2.67 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-11-25 09:33:20.544845) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-11-25 09:33:20.551862) ========= 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.tws5KJXHrX/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.j7mOEZb5eW/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/79ce1d807273ed1a0f19fc3517775a24a4d1dffbcf3fbd572f0df807a7adc39e565bb2fb1a1a0648d9cb4e7a806564f4370f9a5d412a1fd10d08defac481138c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/124669b3e624bd2717f569d1daac7702e07494a14491652de5e11c89263f43cd3e863f869caf87b6947cfc8cf38741b40942a8fb34789edc89d0e33136a89095", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e75c6b498599bc0991ad17f19b18d1366025f7f4223f202ae7f1a066e97f5636c5c99f8a638a9302a0802275b0a18d1b639ddec083eeeadbbb69ec215f47db29"} build_url:https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011096 build_url: 'https://queue.taskcluster.net/v1/task/PNt9W6muREqqAT06zBl00A/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/79ce1d807273ed1a0f19fc3517775a24a4d1dffbcf3fbd572f0df807a7adc39e565bb2fb1a1a0648d9cb4e7a806564f4370f9a5d412a1fd10d08defac481138c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/124669b3e624bd2717f569d1daac7702e07494a14491652de5e11c89263f43cd3e863f869caf87b6947cfc8cf38741b40942a8fb34789edc89d0e33136a89095", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e75c6b498599bc0991ad17f19b18d1366025f7f4223f202ae7f1a066e97f5636c5c99f8a638a9302a0802275b0a18d1b639ddec083eeeadbbb69ec215f47db29"}' ========= master_lag: 1.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-11-25 09:33:21.603944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:33:21.604271) ========= 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.tws5KJXHrX/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.j7mOEZb5eW/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.005338 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 09:33:21.635077) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 09:33:21.635459) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 09:33:21.635820) ========= ========= Total master_lag: 3.81 =========