builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0241 starttime: 1482363721.1 results: success (0) buildid: 20161221132752 builduid: c499ee514c5c40edb3298fd1726cdde8 revision: 839579d7d875c968a0403a0cee1a7fad67c6e228 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:01.097052) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:01.097459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:01.097754) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:01.123828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:01.124126) ========= 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.HMo8oF7S94/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.q5sjfeTkam/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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-12-21 15:42:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 211M=0s 2016-12-21 15:42:01 (211 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125353 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:01.271436) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:01.271772) ========= 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.HMo8oF7S94/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.q5sjfeTkam/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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.025313 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:01.319646) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 15:42:01.319964) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 839579d7d875c968a0403a0cee1a7fad67c6e228 --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 839579d7d875c968a0403a0cee1a7fad67c6e228 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HMo8oF7S94/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.q5sjfeTkam/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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-12-21 15:42:01,414 truncating revision to first 12 chars 2016-12-21 15:42:01,414 Setting DEBUG logging. 2016-12-21 15:42:01,414 attempt 1/10 2016-12-21 15:42:01,414 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/839579d7d875?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 15:42:02,892 unpacking tar archive at: mozilla-beta-839579d7d875/testing/mozharness/ program finished with exit code 0 elapsedTime=1.779176 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 15:42:03.122327) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:03.122653) ========= script_repo_revision: 839579d7d875c968a0403a0cee1a7fad67c6e228 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:03.123050) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:03.123320) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 15:42:03.143458) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-12-21 15:42:03.143854) ========= /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.HMo8oF7S94/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.q5sjfeTkam/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:03 INFO - MultiFileLogger online at 20161221 15:42:03 in /builds/slave/test 15:42:03 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 15:42:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:42:03 INFO - {'append_to_log': False, 15:42:03 INFO - 'base_work_dir': '/builds/slave/test', 15:42:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:42:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:42:03 INFO - 'branch': 'Mozilla-Beta', 15:42:03 INFO - 'buildbot_json_path': 'buildprops.json', 15:42:03 INFO - 'config_files': ('talos/mac_config.py',), 15:42:03 INFO - 'default_actions': ('clobber', 15:42:03 INFO - 'read-buildbot-config', 15:42:03 INFO - 'download-and-extract', 15:42:03 INFO - 'populate-webroot', 15:42:03 INFO - 'create-virtualenv', 15:42:03 INFO - 'install', 15:42:03 INFO - 'run-tests'), 15:42:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:42:03 INFO - 'download_minidump_stackwalk': True, 15:42:03 INFO - 'download_symbols': 'ondemand', 15:42:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:42:03 INFO - 'tooltool.py': '/tools/tooltool.py', 15:42:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:42:03 INFO - '/tools/misc-python/virtualenv.py')}, 15:42:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:42:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:42:03 INFO - 'installer_path': 'installer.exe', 15:42:03 INFO - 'log_level': 'info', 15:42:03 INFO - 'log_name': 'talos', 15:42:03 INFO - 'log_to_console': True, 15:42:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:42:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:42:03 INFO - 'opt_config_files': (), 15:42:03 INFO - 'pip_index': False, 15:42:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:42:03 INFO - 'cmd': ('bash', 15:42:03 INFO - '-c', 15:42:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:42:03 INFO - 'enabled': True, 15:42:03 INFO - 'halt_on_failure': False, 15:42:03 INFO - 'name': 'check_screen_resolution'},), 15:42:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:42:03 INFO - 'cmd': ('bash', 15:42:03 INFO - '-c', 15:42:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:42:03 INFO - 'enabled': True, 15:42:03 INFO - 'halt_on_failure': False, 15:42:03 INFO - 'name': 'check_screen_resolution'},), 15:42:03 INFO - 'run_cmd_checks_enabled': True, 15:42:03 INFO - 'sps_profile': False, 15:42:03 INFO - 'sps_profile_interval': 0, 15:42:03 INFO - 'suite': 'dromaeojs', 15:42:03 INFO - 'system_bits': '32', 15:42:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:42:03 INFO - 'title': 't-yosemite-r7-0241', 15:42:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:42:03 INFO - 'use_talos_json': True, 15:42:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:42:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:42:03 INFO - 'work_dir': 'build'} 15:42:03 INFO - [mozharness: 2016-12-21 23:42:03.339632Z] Running clobber step. 15:42:03 INFO - Running pre-action listener: _resource_record_pre_action 15:42:03 INFO - Running main action method: clobber 15:42:03 INFO - rmtree: /builds/slave/test/build 15:42:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:42:05 INFO - Running post-action listener: _resource_record_post_action 15:42:05 INFO - [mozharness: 2016-12-21 23:42:05.635761Z] Finished clobber step (success) 15:42:05 INFO - [mozharness: 2016-12-21 23:42:05.635888Z] Running read-buildbot-config step. 15:42:05 INFO - Running pre-action listener: _resource_record_pre_action 15:42:05 INFO - Running main action method: read_buildbot_config 15:42:05 INFO - Using buildbot properties: 15:42:05 INFO - { 15:42:05 INFO - "project": "", 15:42:05 INFO - "product": "firefox", 15:42:05 INFO - "script_repo_revision": "production", 15:42:05 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 15:42:05 INFO - "repository": "", 15:42:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 15:42:05 INFO - "buildid": "20161221132752", 15:42:05 INFO - "pgo_build": "False", 15:42:05 INFO - "basedir": "/builds/slave/test", 15:42:05 INFO - "buildnumber": 95, 15:42:05 INFO - "slavename": "t-yosemite-r7-0241", 15:42:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:42:05 INFO - "platform": "yosemite_r7", 15:42:05 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:42:05 INFO - "branch": "mozilla-beta", 15:42:05 INFO - "repo_path": "releases/mozilla-beta", 15:42:05 INFO - "revision": "839579d7d875c968a0403a0cee1a7fad67c6e228", 15:42:05 INFO - "stage_platform": "macosx64", 15:42:05 INFO - "builduid": "c499ee514c5c40edb3298fd1726cdde8", 15:42:05 INFO - "slavebuilddir": "test" 15:42:05 INFO - } 15:42:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 15:42:05 INFO - Running post-action listener: _resource_record_post_action 15:42:05 INFO - [mozharness: 2016-12-21 23:42:05.639208Z] Finished read-buildbot-config step (success) 15:42:05 INFO - [mozharness: 2016-12-21 23:42:05.639336Z] Running download-and-extract step. 15:42:05 INFO - Running pre-action listener: _resource_record_pre_action 15:42:05 INFO - Running main action method: download_and_extract 15:42:05 INFO - mkdir: /builds/slave/test/build/tests 15:42:05 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')]} 15:42:05 INFO - trying https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 15:42:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/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 15:42:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/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 15:42:06 INFO - Downloaded 1165 bytes. 15:42:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 15:42:06 INFO - Using the following test package requirements: 15:42:06 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 15:42:06 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:42:06 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 15:42:06 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:42:06 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 15:42:06 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:42:06 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:42:06 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 15:42:06 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 15:42:06 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:42:06 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 15:42:06 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:42:06 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 15:42:06 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:42:06 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 15:42:07 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 15:42:07 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 15:42:07 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 15:42:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 15:42:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 15:42:07 INFO - Fetch https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 15:42:07 INFO - Expected file size: 16232594 15:42:14 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 15:42:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 15:42:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 15:42:14 INFO - Fetch https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 15:42:15 INFO - Expected file size: 16232594 15:42:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 15:42:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 15:42:22 INFO - Fetch https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 15:42:22 INFO - Expected file size: 14414089 15:42:23 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')]} 15:42:23 INFO - trying https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.dmg 15:42:23 INFO - Downloading https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 15:42:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/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 15:42:25 INFO - Downloaded 86060689 bytes. 15:42:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.dmg 15:42:25 INFO - mkdir: /builds/slave/test/properties 15:42:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:42:25 INFO - Writing to file /builds/slave/test/properties/build_url 15:42:25 INFO - Contents: 15:42:25 INFO - build_url:https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.dmg 15:42:26 INFO - Running post-action listener: _resource_record_post_action 15:42:26 INFO - Running post-action listener: set_extra_try_arguments 15:42:26 INFO - [mozharness: 2016-12-21 23:42:26.521166Z] Finished download-and-extract step (success) 15:42:26 INFO - [mozharness: 2016-12-21 23:42:26.521263Z] Running populate-webroot step. 15:42:26 INFO - Running pre-action listener: _resource_record_pre_action 15:42:26 INFO - Running main action method: populate_webroot 15:42:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:42:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:42:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:42:26 INFO - '--tppagecycles', 15:42:26 INFO - '7'], 15:42:26 INFO - 'tests': ['tsvgm']}}, 15:42:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:42:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:42:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:42:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:42:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:26 INFO - 'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:26 INFO - 'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['damp', 'tps']}, 15:42:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:26 INFO - 'tests': ['damp', 'tps']}, 15:42:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['dromaeo_dom']}, 15:42:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:42:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['basic_compositor_video']}, 15:42:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:42:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['a11yr', 15:42:26 INFO - 'ts_paint', 15:42:26 INFO - 'tpaint', 15:42:26 INFO - 'sessionrestore', 15:42:26 INFO - 'sessionrestore_no_auto_restore', 15:42:26 INFO - 'tabpaint']}, 15:42:26 INFO - 'other-e10s': {'tests': ['a11yr', 15:42:26 INFO - 'ts_paint', 15:42:26 INFO - 'tpaint', 15:42:26 INFO - 'sessionrestore', 15:42:26 INFO - 'sessionrestore_no_auto_restore', 15:42:26 INFO - 'tabpaint']}, 15:42:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:42:26 INFO - 'ts_paint', 15:42:26 INFO - 'tpaint', 15:42:26 INFO - 'sessionrestore', 15:42:26 INFO - 'sessionrestore_no_auto_restore', 15:42:26 INFO - 'tabpaint']}, 15:42:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:42:26 INFO - 'ts_paint', 15:42:26 INFO - 'tpaint', 15:42:26 INFO - 'sessionrestore', 15:42:26 INFO - 'sessionrestore_no_auto_restore', 15:42:26 INFO - 'tabpaint']}, 15:42:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['a11yr', 15:42:26 INFO - 'ts_paint', 15:42:26 INFO - 'tpaint', 15:42:26 INFO - 'sessionrestore', 15:42:26 INFO - 'sessionrestore_no_auto_restore', 15:42:26 INFO - 'tabpaint']}, 15:42:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['a11yr', 15:42:26 INFO - 'ts_paint', 15:42:26 INFO - 'tpaint', 15:42:26 INFO - 'sessionrestore', 15:42:26 INFO - 'sessionrestore_no_auto_restore', 15:42:26 INFO - 'tabpaint']}, 15:42:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['tsvgx', 15:42:26 INFO - 'tsvgr_opacity', 15:42:26 INFO - 'tart', 15:42:26 INFO - 'tscrollx', 15:42:26 INFO - 'cart']}, 15:42:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:42:26 INFO - 'tsvgr_opacity', 15:42:26 INFO - 'tart', 15:42:26 INFO - 'tscrollx', 15:42:26 INFO - 'cart']}, 15:42:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:26 INFO - 'talos_options': ['--disable-e10s'], 15:42:26 INFO - 'tests': ['tp5o']}, 15:42:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:26 INFO - 'tests': ['tp5o']}, 15:42:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:26 INFO - 'talos_options': ['--disable-e10s', 15:42:26 INFO - '--xperf_path', 15:42:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:26 INFO - 'tests': ['tp5n']}, 15:42:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:42:26 INFO - 'talos_options': ['--xperf_path', 15:42:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:26 INFO - 'tests': ['tp5n']}}, 15:42:26 INFO - 'talos.zip': {'path': '', 15:42:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:42:26 INFO - Running post-action listener: _resource_record_post_action 15:42:26 INFO - [mozharness: 2016-12-21 23:42:26.540396Z] Finished populate-webroot step (success) 15:42:26 INFO - [mozharness: 2016-12-21 23:42:26.540475Z] Running create-virtualenv step. 15:42:26 INFO - Running pre-action listener: _resource_record_pre_action 15:42:26 INFO - Running main action method: create_virtualenv 15:42:26 INFO - Creating virtualenv /builds/slave/test/build/venv 15:42:26 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 15:42:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:42:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:42:26 INFO - Using real prefix '/tools/python27' 15:42:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:42:27 INFO - Installing distribute.............................................................................................................................................................................................done. 15:42:30 INFO - Installing pip.................done. 15:42:30 INFO - Return code: 0 15:42:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:42:30 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')]} 15:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:30 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')]} 15:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:30 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 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:30 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 15:42:30 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 15:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:30 INFO - 'HOME': '/Users/cltbld', 15:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:30 INFO - 'LOGNAME': 'cltbld', 15:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:30 INFO - 'MOZ_NO_REMOTE': '1', 15:42:30 INFO - 'NO_EM_RESTART': '1', 15:42:30 INFO - 'PAGER': '/bin/cat', 15:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:30 INFO - 'PWD': '/builds/slave/test', 15:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:30 INFO - 'SHELL': '/bin/bash', 15:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:30 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:30 INFO - 'USER': 'cltbld', 15:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:30 INFO - 'XPC_FLAGS': '0x0', 15:42:30 INFO - 'XPC_SERVICE_NAME': '0', 15:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:30 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 15:42:30 INFO - Cleaning up... 15:42:30 INFO - Return code: 0 15:42:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:42:30 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')]} 15:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:30 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')]} 15:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:30 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 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:30 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 15:42:30 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 15:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:30 INFO - 'HOME': '/Users/cltbld', 15:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:30 INFO - 'LOGNAME': 'cltbld', 15:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:30 INFO - 'MOZ_NO_REMOTE': '1', 15:42:30 INFO - 'NO_EM_RESTART': '1', 15:42:30 INFO - 'PAGER': '/bin/cat', 15:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:30 INFO - 'PWD': '/builds/slave/test', 15:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:30 INFO - 'SHELL': '/bin/bash', 15:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:30 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:30 INFO - 'USER': 'cltbld', 15:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:30 INFO - 'XPC_FLAGS': '0x0', 15:42:30 INFO - 'XPC_SERVICE_NAME': '0', 15:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:30 INFO - Downloading/unpacking psutil>=3.1.1 15:42:30 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 15:42:30 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 15:42:30 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 15:42:30 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 15:42:30 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 15:42:30 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 15:42:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:42:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:34 INFO - Installing collected packages: psutil 15:42:34 INFO - Running setup.py install for psutil 15:42:34 INFO - building 'psutil._psutil_osx' extension 15:42:34 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 15:42:34 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 15:42:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:42:35 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 15:42:35 INFO - building 'psutil._psutil_posix' extension 15:42:35 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 15:42:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:42:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:42:35 INFO - ^ 15:42:35 INFO - 1 warning generated. 15:42:35 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 15:42:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:35 INFO - Successfully installed psutil 15:42:35 INFO - Cleaning up... 15:42:35 INFO - Return code: 0 15:42:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:35 INFO - 'HOME': '/Users/cltbld', 15:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:35 INFO - 'LOGNAME': 'cltbld', 15:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:35 INFO - 'MOZ_NO_REMOTE': '1', 15:42:35 INFO - 'NO_EM_RESTART': '1', 15:42:35 INFO - 'PAGER': '/bin/cat', 15:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:35 INFO - 'PWD': '/builds/slave/test', 15:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:35 INFO - 'SHELL': '/bin/bash', 15:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:35 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:35 INFO - 'USER': 'cltbld', 15:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:35 INFO - 'XPC_FLAGS': '0x0', 15:42:35 INFO - 'XPC_SERVICE_NAME': '0', 15:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:42:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:42:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:42:38 INFO - Installing collected packages: mozsystemmonitor 15:42:38 INFO - Running setup.py install for mozsystemmonitor 15:42:38 INFO - Successfully installed mozsystemmonitor 15:42:38 INFO - Cleaning up... 15:42:38 INFO - Return code: 0 15:42:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:38 INFO - 'HOME': '/Users/cltbld', 15:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:38 INFO - 'LOGNAME': 'cltbld', 15:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:38 INFO - 'MOZ_NO_REMOTE': '1', 15:42:38 INFO - 'NO_EM_RESTART': '1', 15:42:38 INFO - 'PAGER': '/bin/cat', 15:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:38 INFO - 'PWD': '/builds/slave/test', 15:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:38 INFO - 'SHELL': '/bin/bash', 15:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:38 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:38 INFO - 'USER': 'cltbld', 15:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:38 INFO - 'XPC_FLAGS': '0x0', 15:42:38 INFO - 'XPC_SERVICE_NAME': '0', 15:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:38 INFO - Downloading/unpacking jsonschema==2.5.1 15:42:38 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 15:42:38 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 15:42:38 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 15:42:38 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 15:42:38 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 15:42:38 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 15:42:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:42:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:42 INFO - Downloading functools32-3.2.3-2.tar.gz 15:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:42:42 INFO - warning: no files found matching '*.txt' 15:42:42 INFO - no previously-included directories found matching 'build' 15:42:42 INFO - no previously-included directories found matching 'dist' 15:42:42 INFO - no previously-included directories found matching '.git*' 15:42:42 INFO - Installing collected packages: jsonschema, functools32 15:42:42 INFO - Running setup.py install for functools32 15:42:42 INFO - warning: no files found matching '*.txt' 15:42:42 INFO - no previously-included directories found matching 'build' 15:42:42 INFO - no previously-included directories found matching 'dist' 15:42:42 INFO - no previously-included directories found matching '.git*' 15:42:42 INFO - Successfully installed jsonschema functools32 15:42:42 INFO - Cleaning up... 15:42:42 INFO - Return code: 0 15:42:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:42:42 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')]} 15:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:42 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')]} 15:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:42 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 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:42 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 15:42:42 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 15:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:42 INFO - 'HOME': '/Users/cltbld', 15:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:42 INFO - 'LOGNAME': 'cltbld', 15:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:42 INFO - 'MOZ_NO_REMOTE': '1', 15:42:42 INFO - 'NO_EM_RESTART': '1', 15:42:42 INFO - 'PAGER': '/bin/cat', 15:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:42 INFO - 'PWD': '/builds/slave/test', 15:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:42 INFO - 'SHELL': '/bin/bash', 15:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:42 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:42 INFO - 'USER': 'cltbld', 15:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:42 INFO - 'XPC_FLAGS': '0x0', 15:42:42 INFO - 'XPC_SERVICE_NAME': '0', 15:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:42:42 INFO - Cleaning up... 15:42:42 INFO - Return code: 0 15:42:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:42:42 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')]} 15:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:42 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')]} 15:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:42 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 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:42 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 15:42:42 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 15:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:42 INFO - 'HOME': '/Users/cltbld', 15:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:42 INFO - 'LOGNAME': 'cltbld', 15:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:42 INFO - 'MOZ_NO_REMOTE': '1', 15:42:42 INFO - 'NO_EM_RESTART': '1', 15:42:42 INFO - 'PAGER': '/bin/cat', 15:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:42 INFO - 'PWD': '/builds/slave/test', 15:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:42 INFO - 'SHELL': '/bin/bash', 15:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:42 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:42 INFO - 'USER': 'cltbld', 15:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:42 INFO - 'XPC_FLAGS': '0x0', 15:42:42 INFO - 'XPC_SERVICE_NAME': '0', 15:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:42 INFO - Downloading/unpacking blobuploader==1.2.4 15:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:45 INFO - Downloading blobuploader-1.2.4.tar.gz 15:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:42:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:42:45 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 15:42:45 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 15:42:45 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 15:42:45 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 15:42:45 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 15:42:45 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 15:42:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:42:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:42:46 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 15:42:46 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 15:42:46 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 15:42:46 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 15:42:46 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 15:42:46 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 15:42:46 INFO - Downloading docopt-0.6.1.tar.gz 15:42:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:42:47 INFO - Installing collected packages: blobuploader, requests, docopt 15:42:47 INFO - Running setup.py install for blobuploader 15:42:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:42:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:42:47 INFO - Running setup.py install for requests 15:42:47 INFO - Running setup.py install for docopt 15:42:47 INFO - Successfully installed blobuploader requests docopt 15:42:47 INFO - Cleaning up... 15:42:47 INFO - Return code: 0 15:42:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:47 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')]} 15:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:47 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')]} 15:42:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:47 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 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:47 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 15:42:47 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 15:42:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:47 INFO - 'HOME': '/Users/cltbld', 15:42:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:47 INFO - 'LOGNAME': 'cltbld', 15:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:47 INFO - 'MOZ_NO_REMOTE': '1', 15:42:47 INFO - 'NO_EM_RESTART': '1', 15:42:47 INFO - 'PAGER': '/bin/cat', 15:42:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:47 INFO - 'PWD': '/builds/slave/test', 15:42:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:47 INFO - 'SHELL': '/bin/bash', 15:42:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:47 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:47 INFO - 'USER': 'cltbld', 15:42:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:47 INFO - 'XPC_FLAGS': '0x0', 15:42:47 INFO - 'XPC_SERVICE_NAME': '0', 15:42:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-8BmIAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-d1eJo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-MHzXes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Sf5rXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Wdl87z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-1bsjzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-UmEPUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-EX8Xml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:48 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-QjsHID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-HSuau_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-nnjqyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-ETVwMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-dtAAoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-LxZDek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-sCorLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-K8SaIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:49 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-IcJNNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:42:49 INFO - Running setup.py install for manifestparser 15:42:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:42:50 INFO - Running setup.py install for mozcrash 15:42:50 INFO - Running setup.py install for mozdebug 15:42:50 INFO - Running setup.py install for mozdevice 15:42:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:42:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:42:50 INFO - Running setup.py install for mozfile 15:42:50 INFO - Running setup.py install for mozhttpd 15:42:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:42:50 INFO - Running setup.py install for mozinfo 15:42:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:42:50 INFO - Running setup.py install for mozInstall 15:42:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Running setup.py install for mozleak 15:42:51 INFO - Running setup.py install for mozlog 15:42:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Running setup.py install for moznetwork 15:42:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Running setup.py install for mozprocess 15:42:51 INFO - Running setup.py install for mozprofile 15:42:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:42:51 INFO - Running setup.py install for mozrunner 15:42:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:42:52 INFO - Running setup.py install for mozscreenshot 15:42:52 INFO - Running setup.py install for moztest 15:42:52 INFO - Running setup.py install for mozversion 15:42:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:42:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:42:52 INFO - Cleaning up... 15:42:52 INFO - Return code: 0 15:42:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:52 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')]} 15:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:52 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')]} 15:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:52 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 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:52 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 15:42:52 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 15:42:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:52 INFO - 'HOME': '/Users/cltbld', 15:42:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:52 INFO - 'LOGNAME': 'cltbld', 15:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:52 INFO - 'MOZ_NO_REMOTE': '1', 15:42:52 INFO - 'NO_EM_RESTART': '1', 15:42:52 INFO - 'PAGER': '/bin/cat', 15:42:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:52 INFO - 'PWD': '/builds/slave/test', 15:42:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:52 INFO - 'SHELL': '/bin/bash', 15:42:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:52 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:52 INFO - 'USER': 'cltbld', 15:42:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:52 INFO - 'XPC_FLAGS': '0x0', 15:42:52 INFO - 'XPC_SERVICE_NAME': '0', 15:42:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:52 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-iPigPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:52 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)) 15:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:52 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-u_8hvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:52 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)) 15:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:52 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-nsoA1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-k4bmIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-iCCGSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-WWfPaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-7An2OP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-cZDBBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-nbLgA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-BP2OgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-sEAEr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:53 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)) 15:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:53 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-wIbN2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:54 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)) 15:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:54 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-Xhh2H4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:54 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)) 15:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:54 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-VnttzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:54 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)) 15:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:54 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-T0Funw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:54 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)) 15:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:54 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-sK8yoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:54 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)) 15:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:54 INFO - Running setup.py (path:/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/pip-ed5HCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:54 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)) 15:42:54 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)) 15:42:54 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)) 15:42:54 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)) 15:42:54 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)) 15:42:54 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)) 15:42:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:42:54 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 15:42:54 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 15:42:54 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 15:42:54 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 15:42:54 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 15:42:54 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 15:42:57 INFO - Downloading blessings-1.6.tar.gz 15:42:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:42:57 INFO - Installing collected packages: blessings 15:42:57 INFO - Running setup.py install for blessings 15:42:57 INFO - Successfully installed blessings 15:42:57 INFO - Cleaning up... 15:42:57 INFO - Return code: 0 15:42:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:42:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:42:57 INFO - Reading from file tmpfile_stdout 15:42:57 INFO - Current package versions: 15:42:57 INFO - blessings == 1.6 15:42:57 INFO - blobuploader == 1.2.4 15:42:57 INFO - docopt == 0.6.1 15:42:57 INFO - functools32 == 3.2.3-2 15:42:57 INFO - jsonschema == 2.5.1 15:42:57 INFO - manifestparser == 1.1 15:42:57 INFO - mozInstall == 1.12 15:42:57 INFO - mozcrash == 0.17 15:42:57 INFO - mozdebug == 0.1 15:42:57 INFO - mozdevice == 0.48 15:42:57 INFO - mozfile == 1.2 15:42:57 INFO - mozhttpd == 0.7 15:42:57 INFO - mozinfo == 0.9 15:42:57 INFO - mozleak == 0.1 15:42:57 INFO - mozlog == 3.4 15:42:57 INFO - moznetwork == 0.27 15:42:57 INFO - mozprocess == 0.23 15:42:57 INFO - mozprofile == 0.28 15:42:57 INFO - mozrunner == 6.12 15:42:57 INFO - mozscreenshot == 0.1 15:42:57 INFO - mozsystemmonitor == 0.3 15:42:57 INFO - moztest == 0.7 15:42:57 INFO - mozversion == 1.4 15:42:57 INFO - psutil == 3.1.1 15:42:57 INFO - requests == 1.2.3 15:42:57 INFO - wsgiref == 0.1.2 15:42:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:57 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')]} 15:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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')]} 15:42:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42:58 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 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42:58 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 15:42:58 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 15:42:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:58 INFO - 'HOME': '/Users/cltbld', 15:42:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:58 INFO - 'LOGNAME': 'cltbld', 15:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:58 INFO - 'MOZ_NO_REMOTE': '1', 15:42:58 INFO - 'NO_EM_RESTART': '1', 15:42:58 INFO - 'PAGER': '/bin/cat', 15:42:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:58 INFO - 'PWD': '/builds/slave/test', 15:42:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:58 INFO - 'SHELL': '/bin/bash', 15:42:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:58 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:58 INFO - 'USER': 'cltbld', 15:42:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:58 INFO - 'XPC_FLAGS': '0x0', 15:42:58 INFO - 'XPC_SERVICE_NAME': '0', 15:42:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 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)) 15:42:58 INFO - Cleaning up... 15:42:58 INFO - Return code: 0 15:42:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:42: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')]} 15:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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')]} 15:42:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:42: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f84c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8721d96600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, '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.HMo8oF7S94/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.q5sjfeTkam/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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 15:42: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', 'jsonschema'] in /builds/slave/test/build 15:42: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 jsonschema 15:42:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:42:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:58 INFO - 'HOME': '/Users/cltbld', 15:42:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:58 INFO - 'LOGNAME': 'cltbld', 15:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:58 INFO - 'MOZ_NO_REMOTE': '1', 15:42:58 INFO - 'NO_EM_RESTART': '1', 15:42:58 INFO - 'PAGER': '/bin/cat', 15:42:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:58 INFO - 'PWD': '/builds/slave/test', 15:42:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:58 INFO - 'SHELL': '/bin/bash', 15:42:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:42:58 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:42:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:58 INFO - 'USER': 'cltbld', 15:42:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:58 INFO - 'XPC_FLAGS': '0x0', 15:42:58 INFO - 'XPC_SERVICE_NAME': '0', 15:42:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:42:58 INFO - Cleaning up... 15:42:58 INFO - Return code: 0 15:42:58 INFO - Running post-action listener: _resource_record_post_action 15:42:58 INFO - Running post-action listener: _start_resource_monitoring 15:42:58 INFO - Starting resource monitoring. 15:42:58 INFO - [mozharness: 2016-12-21 23:42:58.868166Z] Finished create-virtualenv step (success) 15:42:58 INFO - [mozharness: 2016-12-21 23:42:58.869117Z] Running install step. 15:42:58 INFO - Running pre-action listener: _resource_record_pre_action 15:42:58 INFO - Running main action method: install 15:42:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:42:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:42:59 INFO - Reading from file tmpfile_stdout 15:42:59 INFO - Detecting whether we're running mozinstall >=1.0... 15:42:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:42:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:42:59 INFO - Reading from file tmpfile_stdout 15:42:59 INFO - Output received: 15:42:59 INFO - Usage: mozinstall [options] installer 15:42:59 INFO - Options: 15:42:59 INFO - -h, --help show this help message and exit 15:42:59 INFO - -d DEST, --destination=DEST 15:42:59 INFO - Directory to install application into. [default: 15:42:59 INFO - "/builds/slave/test"] 15:42:59 INFO - --app=APP Application being installed. [default: firefox] 15:42:59 INFO - mkdir: /builds/slave/test/build/application 15:42:59 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'] 15:42:59 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 15:43:21 INFO - Reading from file tmpfile_stdout 15:43:21 INFO - Output received: 15:43:21 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:43:21 INFO - Running post-action listener: _resource_record_post_action 15:43:21 INFO - [mozharness: 2016-12-21 23:43:21.563892Z] Finished install step (success) 15:43:21 INFO - [mozharness: 2016-12-21 23:43:21.564029Z] Running run-tests step. 15:43:21 INFO - Running pre-action listener: _resource_record_pre_action 15:43:21 INFO - Running main action method: run_tests 15:43:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:43:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:43:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:43:21 INFO - 2016-12-21 15:43:21.578 screenresolution[1626:7462] starting screenresolution argv=screenresolution get 15:43:21 INFO - 2016-12-21 15:43:21.581 screenresolution[1626:7462] Display 0: 1600x1200x32@60 15:43:21 INFO - 2016-12-21 15:43:21.586 screenresolution[1627:7464] starting screenresolution argv=screenresolution list 15:43:21 INFO - Available Modes on Display 0 15:43:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:43:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:43:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:43:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:43:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:43:21 INFO - Intel Iris: 15:43:21 INFO - Chipset Model: Intel Iris 15:43:21 INFO - Type: GPU 15:43:21 INFO - Bus: Built-In 15:43:21 INFO - VRAM (Dynamic, Max): 1536 MB 15:43:21 INFO - Vendor: Intel (0x8086) 15:43:21 INFO - Device ID: 0x0a2e 15:43:21 INFO - Revision ID: 0x0009 15:43:21 INFO - Displays: 15:43:21 INFO - Display: 15:43:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:43:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:43:21 INFO - Display Serial Number: 1600x1200 60 15:43:21 INFO - Main Display: Yes 15:43:21 INFO - Mirror: Off 15:43:21 INFO - Online: Yes 15:43:21 INFO - Rotation: Supported 15:43:21 INFO - Return code: 0 15:43:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:43:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:43:21 INFO - Python 2.7.3 15:43:21 INFO - Return code: 0 15:43:21 INFO - grabbing minidump binary from tooltool 15:43:21 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')]} 15:43:21 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 0x10f660d30>, '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 0x10f4c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f658b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:43:21 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 15:43:21 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 15:43:21 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 15:43:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:43:21 INFO - Return code: 0 15:43:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:43:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:43:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:43:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:43:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:43:21 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/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0241', '--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 15:43:21 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/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0241 --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 15:43:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMo8oF7S94/Render', 15:43:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:21 INFO - 'HOME': '/Users/cltbld', 15:43:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:21 INFO - 'LOGNAME': 'cltbld', 15:43:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:43:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:21 INFO - 'MOZ_NO_REMOTE': '1', 15:43:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:43:21 INFO - 'NO_EM_RESTART': '1', 15:43:21 INFO - 'PAGER': '/bin/cat', 15:43:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:21 INFO - 'PWD': '/builds/slave/test', 15:43:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:43:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:21 INFO - 'SHELL': '/bin/bash', 15:43:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q5sjfeTkam/Listeners', 15:43:21 INFO - 'TMPDIR': '/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/', 15:43:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:21 INFO - 'USER': 'cltbld', 15:43:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:21 INFO - 'XPC_FLAGS': '0x0', 15:43:21 INFO - 'XPC_SERVICE_NAME': '0', 15:43:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:21 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/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0241', '--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 15:43:22 INFO - mozversion application_buildid: 20161221132752 15:43:22 INFO - mozversion application_changeset: 839579d7d875c968a0403a0cee1a7fad67c6e228 15:43:22 INFO - mozversion application_display_name: Firefox 15:43:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:43:22 INFO - mozversion application_name: Firefox 15:43:22 INFO - mozversion application_remotingname: firefox 15:43:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:43:22 INFO - mozversion application_vendor: Mozilla 15:43:22 INFO - mozversion application_version: 51.0 15:43:22 INFO - mozversion platform_buildid: 20161221132752 15:43:22 INFO - mozversion platform_changeset: 839579d7d875c968a0403a0cee1a7fad67c6e228 15:43:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:43:22 INFO - mozversion platform_version: 51.0 15:43:22 INFO - using testdate: 1482363802 15:43:22 INFO - actual date: 1482363802 15:43:22 INFO - starting webserver on 'localhost:49241' 15:43:22 INFO - SUITE-START | Running 2 tests 15:43:22 INFO - TEST-START | dromaeo_css 15:43:22 INFO - operating with platform_type : mac_ 15:43:22 INFO - Initialising browser for dromaeo_css test... 15:43:22 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpaxLzjZ/profile http://localhost:49241/getInfo.html) 15:43:24 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 15:43:24 INFO - PROCESS | 1637 | colorDepth:24 15:43:24 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 15:43:24 INFO - PROCESS | 1637 | __metrics 15:43:24 INFO - PROCESS | 1637 | 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" 15:43:24 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:43:25 INFO - TEST-INFO | 1637: exit 0 15:43:25 INFO - Browser initialized. 15:43:25 INFO - Running cycle 1/1 for dromaeo_css test... 15:43:25 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpaxLzjZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:36 INFO - PROCESS | 1640 | RSS: Main: 168284160 15:43:36 INFO - PROCESS | 1640 | 15:44:28 INFO - PROCESS | 1640 | 1482363868642 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] 15:46:39 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 15:46:40 INFO - PROCESS | 1640 | RSS: Main: 293842944 15:46:40 INFO - PROCESS | 1640 | 15:49:43 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 15:49:44 INFO - PROCESS | 1640 | RSS: Main: 300744704 15:49:44 INFO - PROCESS | 1640 | 15:52:46 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 15:52:46 INFO - PROCESS | 1640 | RSS: Main: 301309952 15:52:46 INFO - PROCESS | 1640 | 15:55:48 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 15:55:49 INFO - PROCESS | 1640 | RSS: Main: 280244224 15:55:49 INFO - PROCESS | 1640 | 15:58:52 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 15:58:53 INFO - PROCESS | 1640 | RSS: Main: 263254016 15:58:53 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 16:01:56 INFO - PROCESS | 1640 | RSS: Main: 469417984 16:01:56 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | __start_tp_report 16:01:56 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 16:01:56 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 16:01:56 INFO - PROCESS | 1640 | |i|pagename|runs| 16:01:56 INFO - PROCESS | 1640 | |0;dojo.html;3301.7523685723568;3472.878449254276;3484.6689564491394;3499.6675315844986;3551.543867014523;5660.29246344207;5734.483896449318;5758.827235182941;5770.682612456324;5794.855128621782;5244.662611034181;5245.580353571715;5714.114312281603;5775;5777.537796976242;5198.168293073109;5213.973930130344;5235.86910327242;5271.894562108756;5274.498922602346;5190.636655434121;5192.662476938987;5195;5283.656562323436;5299.867503312409;5193.948060519403;5208.2968799212185;5220.686758794484;5229.058769421503;5232.2674825524455;5221.269022336857;5247.081760691902;5303.6552624079695;5308.542171921309;5319.175527793198;5095.923561146586;5160.612954028463;5222.608304377149;5230.267762513251;5245.895082098375;5681.403452637455;5717.485426311652;5723.5421166307015;5727.742251598688;5775.3935836736955;5775.624584402;6177.96911015442;6309.3690630937;6314.968425157845;6338.049292606146;6000.939990600082;6316.431521163071;6329.430351268362;6356.173697419352;6358.713857876407;5700.059490184115;5755.194272801811;5770.451807078321;5782.566307526952;5838.94161058384;5241.925405291906;6267.62394256346;6303.3066362700065;6306.054091886253;6317.810465686037;6176.867630722972;6203.906941395882;6215.440610345091;6221.657808820558;6224.502039836802;6122.234720659918;6153.323134455206;6260.624362538171;6316.431521163117;6334.1765570475545;6145.310039739107;6169.444749972524;6208.937910620926;6240.906386404207;6296.590721603173;6269.905951410641;6301.684915754194;6304.275008374096;6317.305096439388;6325.9051114233325;4945.060438516671;4985.950140498621;5002.09962206799;5018.096742586296;5026.52248036436;4404.977975110168;4438.955610443919;4453.977730111329;4515.0518391137575;4553.203189441834;5724.8282551523525;5792.4497172768515;5803.593748437569;5817.854553636108;5822.475977162076;5071.518205770446;6179.134921111048;6257.468115210167;6267.185265915402;6296.748130074746;5775.797847075416;5834.27071616048;5848.385919478394;5850.063989761617;5851.941480585225;5413.593980451482;5442.755076021588;5443.074677304868;5445.1015582429245;5448.891022179534;6227.1282020517165;6264.310925798068;6282.685865706696;6304.338044505261;6334.588251763621;5386.488283613207;6219.253689557282;6311.431971122621;6347.96826015867;6356.586821856565;6360.363963603602;6371.331010243857;6395.42441180296;6410;6416.711247993758;5185.559227465707;5774.931637647049;6154.907676384768;6167.969160154171;6316.905246421399;6232.781852635136;6260.060990851409;6269.62382257066;6352.0789485525265;6352.523560732871;6225.782097626561;6233.127362169391;6238.344973777688;6251.281102673251;6275.6862156892885;5165.9483405165465;6025.457708806229;6195.132681424514;6224.097505861534;6227.937720622917;5933.376995415592;5976.372480889444;5993.041113421831;6059.848503787441;6129.632222066691;5668.639526513524;5796.82609521715;6200.255969283715;6214.005759078525;6218.284897236695;6039.305479869871;6073.392660734068;6081.787137450168;6106.45041946227;6108.205933228652 16:01:56 INFO - PROCESS | 1640 | |1;ext.html;490.5928079693853;531.1156923722003;547.5072434808671;559.8880223955207;565.375260337327;32001.839990800072;35120.824395878015;41249.968750781205;42430;44917.32624010643;43812.780936095354;44073.77963110188;44111.55888441115;44172.77913610436;44559.55440445594;9251.722448326527;9969.601215951354;10143.949280253626;10160.796784064314;10166.339187952797;9323.021082786312;12163.148579599432;12431.440585173688;12442.81335779964;12678.87321126786;12328.32194229317;12439.1914525556;12510.249385036926;12531.74936501274;12541;12287.631371058838;12343.321117338543;12348.629541113782;12358.949489293422;12367.443465044094;10743.892561074368;11992.520299188021;12234.75530489394;12338.629841104781;12348.38258087092;40737.18525629471;40996.795016025026;41030.79484602558;41056.56302029415;41140.38289425661;3472.6700963408434;3523.5243242162237;3644.4533320001947;3664.963350366489;4340;31485.37029259425;31762.523562146587;31910.20224494383;32087.679123208934;32238.355232895447;3090.799098058619;3284.720798732111;3309.619393769723;3375.1055970167927;3387.915302117442;522332;523558;524221.3788931069;525228;526444.3677781625;34420;60011;60230.69884650549;60309.095363569584;60409;35219.4717079244;36457.453138202945;36593;36631.450528242094;36647.267054658754;15661.451849185452;15792.36830526766;15832.525024249291;15859.286332115082;15896.841031589767;11104.389258590854;12305.015598752077;12475.251484910934;13343.73312533744;13462.461501540025;7854.1753115922;8009.439339246197;8055.436119471581;8101.473404228706;8124.837503249902;3344.665533446636;3403.7617366784584;3404.5233667286598;3417.8803741868915;3437.656234376542;998.6953979036318;1022.7085280694967;1051.9684409467727;1073.7261998190568;1086.4676308608725;32401.837990810367;32728.836355818068;32771.67228327734;34323;34362.82818585891;958.1807554540907;1036.4247842447514;1074.887136850635;1091.2361347057092;1098.8461615373853;13527.932360338333;15045.924770376078;15676.451324203597;15724;16035.599110022344;634135;638946;639787;644294;645772;58322.41677583255;58702.706486467294;58719.706401468575;58771;58813.117803232984;1305.4973835073565;1343.1806597975228;1372.7940808878748;1401.6355747505722;1406.9929650351683;30071.849640751658;31207;31349.686503134675;31677.20806979844;31900.36199275955;49901.75049124731;50412.747936260086;50435.495645044546;50696.49303506918;50764.49235507598;502091;591849;592480;593463;864468;22961.88519057394;26077.73922260753;26089.347766305993;26493.867530662224;27022.72977270202;45910.77044614756;46008.769956150005;46318.610441686804;46323;46329.768351158025;37769.433458497595;38443.42334865035;38781;39003.41494877522;39722.60277397189;36927.44608830816;36943.445848311756;37589.35247495183;37800;38077.42883856689;37334;37759;38325.42511862268;38389.232215356096;38451.42322865215;32064.839675802406;34266.31467370688;34574;34604.82697586496;34632.65367346294;25477.617835732108;25579.744202557737;25828.741712583385;25839.61240581355;25942.837311916068 16:01:56 INFO - PROCESS | 1640 | |2;jquery.html;383.46123696206837;384.73068851803737;385.310884786133;393.3372267728876;413.5864135864136;14700.779488307657;20384.286549970755;21137.788622113774;21903.780962190394;21946;11877.584284550043;16961.91519042406;20226.29207977721;20452.181912723474;21114.57770844582;5944.286685597713;6265.843353916143;6285.9685701571425;6301.495880329586;6302.55882088254;5974.253218347707;6140.6929653517145;6232.844178895519;6244.281907580618;6246.437820596146;6340.619562826245;6358.459530939876;6364.809055728336;6366.6180029198395;6379.521535884804;6087.391260873921;6245.281792593864;6313.558050936436;6325;6328.019157030668;5935.970320148414;6127.724252408652;6128.601640893327;6154.784582539634;6161.784337548209;13949.372278247401;14087.859121408757;20559.8692071936;26586.33534161642;27468;2332.428555004018;2491.165459571046;2670.9732902670917;3040.528718048684;3042.513197888349;10188.541515631814;10249.385036897735;10676.145908327393;10685.732856678567;10820.891791082146;1934.477691023416;1980.732601098862;2000.29989503674;2004.6892731626622;2007.076744697441;114235;118462.22306665407;118608;119168;119286.99999999913;11398.658040258806;11490.42547872603;11561.051931757349;11599.420028998686;11725.355105469282;12263.93868030654;14805;14808.703825923421;14824.777628335583;14825.92587037058;7808.180141085218;7917.0895347034675;8723.476591404537;8753.212210901049;8844.46933184011;8871.157240062184;8944.552772361356;8949.865752013726;9130.31522635805;9148.954255228815;4521.728696278234;4610.9308360374625;4640.628749700016;4665.043666048452;4702.694324868872;1794.0622765327007;1825.9452216433529;1832.2236487226037;1912.9521761956062;1961.6272908147691;771.9228077192236;859.5530324231364;890.9465432073966;911.6034524981654;974.7514383832078;19831.30590429328;21568.568628627654;21637.3513959102;21713.78286217149;21760.129594816346;951.3245595627044;999.4652860719481;1001.9749506262402;1006.1296978113879;1008.1229330482381;10902.345859248471;11289.686009042409;11396.886031139748;11408.144389170846;11483.023942964774;509885;513367;515445;531211;537530;125046;131305.34347328392;142377;145644;156223;1033.5555711044174;1037.9481025948671;1093.6882988348282;1096.1121491591678;1096.1942971915635;18687;19073.237070517578;19517.902410487855;19561.60876782428;19587;55416;55681.72159139178;57314.7134264326;57465;58368.4163158363;80608;82455.58772206101;82778.17221827705;84508;85182.57408713165;6983.790486285419;7426.405887529093;7498.987636669198;8099.757007289791;8138.511689298661;10697.572097116028;12613.936930315289;12769;12944.15862968904;13184.60446186616;8715.477071375737;10879.619213327496;11139.554417823196;11203.71990700239;11430.028447582048;11152.442377881236;11358.716032099264;11575.479103440197;11613.76772464563;11756.647300580997;11447.542098315975;11624.709382265173;11672.124590655905;11900.047996160114;11967.940160299142;7421.369183619453;7445.664945077593;7603.0496187976505;7671.884921726067;7771.8834217485655;10978.505967231653;11110.50002749862;11140.33158010522;11313.830292545783;11459 16:01:56 INFO - PROCESS | 1640 | |3;mootools.html;1348.8786009259168;1388.9861101388983;1531.1731664900954;1716.7167417376133;1925.4319975607186;8000.119986801447;8644.697435589756;9019.909800901989;9035.141661542159;9202.677906273282;8983.730488085368;9064.592093355799;9066.138716821892;9079.228265597416;9117.997020327759;2419.7459266776955;2453.668754718117;2454.067454367338;2454.325060608338;2457.5699252630807;2390.2470721722602;2423.8909249083745;2431.161249368965;2432.525657496789;2432.7080750309988;2396.4488167721433;2407.6388541718793;2417.528581926525;2423.7818596326415;2426.2046085896486;2376.8811559422134;2395.508920671267;2400.2559206645997;2402.627592723122;2409.265174121892;2345.495718420531;2379.167291447997;2417.697787776528;2427.9878600606885;2429.2347910408166;8050.677972881078;8271.17288271174;9023.142801433853;9137.954310228473;9139.451632902048;5296.523312901857;5463.562914966833;5469.56243500519;5513.724313784295;5517.69652669103;3147.8268695221755;3647.215848592565;3655.4334078217917;3732.5707543632557;3734.9439758403646;1109.234628106612;1112.1492058575197;1121.5121422181296;1122.0350498571304;1132.0547342968632;12084.395780210954;12182.51269949201;12190.756184876253;12200.389980501028;12200.877991220063;4732.881677958078;4768.416671621383;4856.150173719585;4934.629902757308;4940.777665005084;10235.283530152818;10253.74365640865;10263.58945642166;10267.178625709925;10307.093187760289;5500.559955203574;5502.284702988586;5503.532199762985;5504.284443022381;5505.614606977473;4410.206162890647;4953.628477864103;4962;5031.069252188357;5044.218146187347;4046.393041043867;4052.4326594276827;4102.815373308208;4142.316517774533;4155.21051000315;1296.7471343088007;1299.311364976564;1302.9609111726475;1304.4521301053392;1332.6734949937327;795.6777505110324;869.9318040917545;903.823754367892;934.1685899549407;939.8919124300656;5786.88426231473;5830.2420685310635;5938.970305148533;5971.19388882504;6171.444569988723;458.0472616956722;460.74658937584195;468.3115819745037;995.4574756757585;1090.7164137324353;3297.4064668359924;3857.9228415431535;3912.569617342147;3920.313945059603;4032.274190645653;9816.263780216512;11188.216824822348;11190.832137517944;11198.048165905826;11238.100951923827;11133.944330278297;11136.554537818396;11163.218574699693;11165.162612804072;11171.608993685182;7169;8492.660293588187;8513.574321283912;8518.063013068557;8560.657573696983;5137.383513978347;5636.549076073986;5700.885982280414;5796.94203057964;5925.111233315038;10194.949025254826;10206.642767503101;10369.9481502592;10415.479226038517;10415.739606509898;10042.046005629454;10416.687499375032;10453.68638940833;10455;10474.266801323834;7213.819654508679;7352.301531354513;7357.742479013209;7361.337479626859;7380.630968451664;8441.451305665112;8453.365997550085;8480.957595211985;8538.40231183836;8550.187732165434;7583.810404739926;7707.614619269127;7745.186755390828;7767.1456139825705;7875.330596899328;7485.513441626277;7516.210797866145;7582.886256706264;7586.886196707164;7762.340201082971;7053.612051337226;7094.432445404252;7126.109236345457;7198.20819709821;7205.675744591401;5052.090623687772;5084.618653600921;5102.081625307332;5186.611004174777;5204.661696989683;5031.949680503149;5060.2156665716875;5098.107831129463;5194;5220.921686174786 16:01:56 INFO - PROCESS | 1640 | |4;prototype.html;306.3230261122918;307.89069880192545;315.4778841018115;319.4840332862428;322.6612057339793;4059.533153687323;5376.8118115865955;5454.181872719094;5534.889302213954;5574.163875418689;5054.989002199565;5129.871753206172;5196.272521846944;5267.789288428458;5365.195220716894;4101.487314085739;4112.663384400067;4165.375193720944;4178.331466965286;4192.350185721218;4134.746563123105;4150.460440142792;4154.299705191619;4177.749335039892;4212.978935105336;3440.8967730967984;3783.69730421567;4110.835566577348;4154.875353739392;4191.790410479463;4073.8370465181356;4106.77412742299;4114.9712571857035;4132.318167502373;4146.688998325122;3290.654481279479;3415.590129184514;3645.2891686121216;3646.6171052039685;3666.6883314918264;5166.7674954626755;5319.601029922771;5341.1988201769655;5343.492368225013;5358.740695936452;4232.439374178608;4280.785960701953;4759.50025247351;4761.833335833229;4818.434008947072;5698.800541981011;5703;5705.800296989627;5730.598858079936;5735.426457354273;3224.580804495424;3781.5084039074745;4062.7405504293765;4075.2257071156682;4079.2657321682086;5012.897162624216;5619.662820230799;5969.492593129589;6013.939860601382;6048.788292409745;3794.4877441545036;4096.5903409658795;4141.7722025288895;4151.522574903865;4158.4178215049915;3302.5211344355416;4044.656204222615;4207.095474473003;4221.662267018631;4250.596193361625;4523;4664.813407463664;4781.832635857728;4810.6632535722165;4812.494688057704;5040.39515258164;5278.419373868871;5578.884223155385;5621.381648018714;5830.912536311958;1329.674229813702;1344.791557308599;1416.157386355117;1428.5642878922029;1431.7065001674632;852.3592085235896;855.6235256487125;872.2280781508284;891.1801142948503;892.3352102683413;4111.465509483748;4199.811008504625;4259.467566554181;4381.138016343105;4513.864584062483;4321.715496222893;4440.533743956903;4738.015050518152;4750.5249475052215;4994.600431965434;3809.154306065375;4119.361498967664;4157.625813676723;4247.430304665694;4603.838865639686;2208.7349518057936;2259.7401298850514;2588.5470042742822;2610.8903715920655;2631.9473610527684;4667.8330417395655;4686.039361930796;5143.485651434901;5144.276667316491;5175.792968281226;3961.7005889550137;4248.532661407243;4743.817843791894;4983.9252411213865;5075.822346217865;4539.773011349419;5109.821156259513;5126.53861152498;5134.409542902465;5140.56305214061;4387.846425375096;4939;4942.8764280893265;4943.925841112315;5054.59563234933;4106.181251436193;4415.492218394926;4531.371804353533;5259.526642602184;5939.77258528052;5097.898042039064;6182;6194.349593292639;6200.658963757037;6213.2233470816145;4023.4970628671417;4027.134166154291;4052.804422695253;4061.2892743770312;4064.817083231321;5137.922931155961;5671.723862130987;5681.914771278517;5808.21589085485;6116.480099191441;5822.70886455684;5829.504492118217;5832.533397328119;5839.678817665069;5847.239858818497;5545.861353466195;5803.535717142534;5857.297124345106;5875.855312810879;5916.201312822714;5101.7194054327365;5362.356517217959;5431.972840135774;5658.264425624642;5715.657060576379;4687.835925742719;5711.400302968294;5750.613495399362;5850.151727999501;5891.734871930859;5015.724135172601;5749.8275051748515;5752.194692742935;5776.306843178677;5778.335491418541 16:01:56 INFO - PROCESS | 1640 | |5;yui.html;691.8650863081701;742.4505865659412;794.1978601612374;829.7635173975416;832.7626080655889;16310.510684679464;17084.658306833855;17100.74348884766;17102.657946841056;17119.144042797856;15261.93166478347;15317.92341038296;15325.846741532581;15331.156786376772;15339.539813805604;720.8197950512372;739.9149097853762;741.1661880384568;742.754890886009;882.1443200095897;741.2735519191169;744.8100734312769;854.4659587757651;880.7357792662208;884.7699598104475;934.5420743835506;943.3632298198722;946.8153710026547;949.4540639132492;949.5964215208509;941.1247539787994;944.2210176604328;945.3382632157517;945.385499425379;945.7351941456403;698.322627051759;699.0702365853454;699.2902204262678;699.8355386484172;700.1633048507036;15678.372865085383;15705.293261803246;15722.449714259947;15728.92135539315;15756.44852430171;12972.027097967595;13541.729165416735;13688.247146406944;13977.02160848741;14132.999999999896;10952.616658416917;13125.343732813417;13622.659433514142;13818.861811381857;13938.860611393859;3951.209758048402;4076.490438695163;4255.893602659928;4274.252005898955;4279.700420970502;113228;113614;116314;116378.25432618517;119133.40433297865;10782.921707829155;11214.943925280322;11281.492332845042;11415.315081095161;11503.19477636574;22318.55362892733;22623.547529049327;22641.433964151027;22671.20650777215;22672.546549069255;7963.920360796318;8073.19268073188;8246.670133194604;8382.83234335322;8417.284530814826;4437.090396468716;4550.681452298373;4625.398698169284;4702.529747025338;4747.833825816149;3683.9079023024638;3755.4366844972924;3841.865534706272;3848.13416981177;3869.941950870739;693.968771405288;697.1877762406739;703.5884007855462;705.0657878311258;713.9714411423589;638.9170356246118;665.5108495255982;681.7273090763734;694.6665600511783;705.3107806802781;9526.666566670134;9921.702348929544;9944.80110397788;10019.949900250598;10067.395956242503;625.8372823066036;658.4830907737403;666.4901350466897;670.1321788284183;718.0844423360153;4487.125010622963;4800.5199480052415;4850.175470170079;4862.902741945141;4873.122837889214;172345;172809;175210.123949382;176445;178952;102641;125522;149637;151891;153531;123594.14608780875;123840;127089.36455317664;127257.36371318084;128260;9283.350165488351;9394.483303418378;9404.576794044422;9415.905840941503;9430.245580353418;58735.70632146812;64785.7324993623;64833.67583162054;65752.67123664351;66000;65469.345306546325;67753.32246677668;84639.15360846312;89195.55402222948;94314.05685943327;13298.933505332412;13378.866211337761;13757.656058598615;13767.586972390844;13800.585982420544;14772.18752968597;15605.445355095819;16123;16605.3357865684;16624.418145364853;13588.116772409761;13795.931020344835;14382.640433989234;15028.6994260112;15074.924625376803;14522.709545809235;14600.719585566554;14666.926665367031;14975.850241497446;15368.385264589739;11899.82150267764;13435.261060641758;14579.635509112357;15103.773443398139;15119.319630616428;10899.618513351994;11351.14866385008;11505;12067.336296503776;12191.634250972485;10324.48377581103;10448.746150461993;11726.824097638371;11926.463309151282;12230.266184028987 16:01:56 INFO - PROCESS | 1640 | __end_tp_report 16:01:56 INFO - PROCESS | 1640 | __start_cc_report 16:01:56 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1181 16:01:56 INFO - PROCESS | 1640 | __end_cc_report 16:01:56 INFO - PROCESS | 1640 | __startTimestamp1482364916527__endTimestamp 16:01:56 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | ------- Summary: start ------- 16:01:56 INFO - PROCESS | 1640 | Number of tests: 6 16:01:56 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5751.68 Median:5850.06 stddev:637.95 (10.9%) stddev-sans-first:610.27 16:01:56 INFO - PROCESS | 1640 | Values: 3301.8 3472.9 3484.7 3499.7 3551.5 5660.3 5734.5 5758.8 5770.7 5794.9 5244.7 5245.6 5714.1 5775.0 5777.5 5198.2 5214.0 5235.9 5271.9 5274.5 5190.6 5192.7 5195.0 5283.7 5299.9 5193.9 5208.3 5220.7 5229.1 5232.3 5221.3 5247.1 5303.7 5308.5 5319.2 5095.9 5160.6 5222.6 5230.3 5245.9 5681.4 5717.5 5723.5 5727.7 5775.4 5775.6 6178.0 6309.4 6315.0 6338.0 6000.9 6316.4 6329.4 6356.2 6358.7 5700.1 5755.2 5770.5 5782.6 5838.9 5241.9 6267.6 6303.3 6306.1 6317.8 6176.9 6203.9 6215.4 6221.7 6224.5 6122.2 6153.3 6260.6 6316.4 6334.2 6145.3 6169.4 6208.9 6240.9 6296.6 6269.9 6301.7 6304.3 6317.3 6325.9 4945.1 4986.0 5002.1 5018.1 5026.5 4405.0 4439.0 4454.0 4515.1 4553.2 5724.8 5792.4 5803.6 5817.9 5822.5 5071.5 6179.1 6257.5 6267.2 6296.7 5775.8 5834.3 5848.4 5850.1 5851.9 5413.6 5442.8 5443.1 5445.1 5448.9 6227.1 6264.3 6282.7 6304.3 6334.6 5386.5 6219.3 6311.4 6348.0 6356.6 6360.4 6371.3 6395.4 6410.0 6416.7 5185.6 5774.9 6154.9 6168.0 6316.9 6232.8 6260.1 6269.6 6352.1 6352.5 6225.8 6233.1 6238.3 6251.3 6275.7 5165.9 6025.5 6195.1 6224.1 6227.9 5933.4 5976.4 5993.0 6059.8 6129.6 5668.6 5796.8 6200.3 6214.0 6218.3 6039.3 6073.4 6081.8 6106.5 6108.2 16:01:56 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:71818.59 Median:34618.74 stddev:161984.91 (467.9%) stddev-sans-first:162350.79 16:01:56 INFO - PROCESS | 1640 | Values: 490.6 531.1 547.5 559.9 565.4 32001.8 35120.8 41250.0 42430.0 44917.3 43812.8 44073.8 44111.6 44172.8 44559.6 9251.7 9969.6 10143.9 10160.8 10166.3 9323.0 12163.1 12431.4 12442.8 12678.9 12328.3 12439.2 12510.2 12531.7 12541.0 12287.6 12343.3 12348.6 12358.9 12367.4 10743.9 11992.5 12234.8 12338.6 12348.4 40737.2 40996.8 41030.8 41056.6 41140.4 3472.7 3523.5 3644.5 3665.0 4340.0 31485.4 31762.5 31910.2 32087.7 32238.4 3090.8 3284.7 3309.6 3375.1 3387.9 522332.0 523558.0 524221.4 525228.0 526444.4 34420.0 60011.0 60230.7 60309.1 60409.0 35219.5 36457.5 36593.0 36631.5 36647.3 15661.5 15792.4 15832.5 15859.3 15896.8 11104.4 12305.0 12475.3 13343.7 13462.5 7854.2 8009.4 8055.4 8101.5 8124.8 3344.7 3403.8 3404.5 3417.9 3437.7 998.7 1022.7 1052.0 1073.7 1086.5 32401.8 32728.8 32771.7 34323.0 34362.8 958.2 1036.4 1074.9 1091.2 1098.8 13527.9 15045.9 15676.5 15724.0 16035.6 634135.0 638946.0 639787.0 644294.0 645772.0 58322.4 58702.7 58719.7 58771.0 58813.1 1305.5 1343.2 1372.8 1401.6 1407.0 30071.8 31207.0 31349.7 31677.2 31900.4 49901.8 50412.7 50435.5 50696.5 50764.5 502091.0 591849.0 592480.0 593463.0 864468.0 22961.9 26077.7 26089.3 26493.9 27022.7 45910.8 46008.8 46318.6 46323.0 46329.8 37769.4 38443.4 38781.0 39003.4 39722.6 36927.4 36943.4 37589.4 37800.0 38077.4 37334.0 37759.0 38325.4 38389.2 38451.4 32064.8 34266.3 34574.0 34604.8 34632.7 25477.6 25579.7 25828.7 25839.6 25942.8 16:01:56 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:33396.09 Median:21675.57 stddev:88359.15 (407.6%) stddev-sans-first:88572.25 16:01:56 INFO - PROCESS | 1640 | Values: 383.5 384.7 385.3 393.3 413.6 14700.8 20384.3 21137.8 21903.8 21946.0 11877.6 16961.9 20226.3 20452.2 21114.6 5944.3 6265.8 6286.0 6301.5 6302.6 5974.3 6140.7 6232.8 6244.3 6246.4 6340.6 6358.5 6364.8 6366.6 6379.5 6087.4 6245.3 6313.6 6325.0 6328.0 5936.0 6127.7 6128.6 6154.8 6161.8 13949.4 14087.9 20559.9 26586.3 27468.0 2332.4 2491.2 2671.0 3040.5 3042.5 10188.5 10249.4 10676.1 10685.7 10820.9 1934.5 1980.7 2000.3 2004.7 2007.1 114235.0 118462.2 118608.0 119168.0 119287.0 11398.7 11490.4 11561.1 11599.4 11725.4 12263.9 14805.0 14808.7 14824.8 14825.9 7808.2 7917.1 8723.5 8753.2 8844.5 8871.2 8944.6 8949.9 9130.3 9149.0 4521.7 4610.9 4640.6 4665.0 4702.7 1794.1 1825.9 1832.2 1913.0 1961.6 771.9 859.6 890.9 911.6 974.8 19831.3 21568.6 21637.4 21713.8 21760.1 951.3 999.5 1002.0 1006.1 1008.1 10902.3 11289.7 11396.9 11408.1 11483.0 509885.0 513367.0 515445.0 531211.0 537530.0 125046.0 131305.3 142377.0 145644.0 156223.0 1033.6 1037.9 1093.7 1096.1 1096.2 18687.0 19073.2 19517.9 19561.6 19587.0 55416.0 55681.7 57314.7 57465.0 58368.4 80608.0 82455.6 82778.2 84508.0 85182.6 6983.8 7426.4 7499.0 8099.8 8138.5 10697.6 12613.9 12769.0 12944.2 13184.6 8715.5 10879.6 11139.6 11203.7 11430.0 11152.4 11358.7 11575.5 11613.8 11756.6 11447.5 11624.7 11672.1 11900.0 11967.9 7421.4 7445.7 7603.0 7671.9 7771.9 10978.5 11110.5 11140.3 11313.8 11459.0 16:01:56 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5832.73 Median:4812.28 stddev:3311.94 (68.8%) stddev-sans-first:3304.08 16:01:56 INFO - PROCESS | 1640 | Values: 1348.9 1389.0 1531.2 1716.7 1925.4 8000.1 8644.7 9019.9 9035.1 9202.7 8983.7 9064.6 9066.1 9079.2 9118.0 2419.7 2453.7 2454.1 2454.3 2457.6 2390.2 2423.9 2431.2 2432.5 2432.7 2396.4 2407.6 2417.5 2423.8 2426.2 2376.9 2395.5 2400.3 2402.6 2409.3 2345.5 2379.2 2417.7 2428.0 2429.2 8050.7 8271.2 9023.1 9138.0 9139.5 5296.5 5463.6 5469.6 5513.7 5517.7 3147.8 3647.2 3655.4 3732.6 3734.9 1109.2 1112.1 1121.5 1122.0 1132.1 12084.4 12182.5 12190.8 12200.4 12200.9 4732.9 4768.4 4856.2 4934.6 4940.8 10235.3 10253.7 10263.6 10267.2 10307.1 5500.6 5502.3 5503.5 5504.3 5505.6 4410.2 4953.6 4962.0 5031.1 5044.2 4046.4 4052.4 4102.8 4142.3 4155.2 1296.7 1299.3 1303.0 1304.5 1332.7 795.7 869.9 903.8 934.2 939.9 5786.9 5830.2 5939.0 5971.2 6171.4 458.0 460.7 468.3 995.5 1090.7 3297.4 3857.9 3912.6 3920.3 4032.3 9816.3 11188.2 11190.8 11198.0 11238.1 11133.9 11136.6 11163.2 11165.2 11171.6 7169.0 8492.7 8513.6 8518.1 8560.7 5137.4 5636.5 5700.9 5796.9 5925.1 10194.9 10206.6 10369.9 10415.5 10415.7 10042.0 10416.7 10453.7 10455.0 10474.3 7213.8 7352.3 7357.7 7361.3 7380.6 8441.5 8453.4 8481.0 8538.4 8550.2 7583.8 7707.6 7745.2 7767.1 7875.3 7485.5 7516.2 7582.9 7586.9 7762.3 7053.6 7094.4 7126.1 7198.2 7205.7 5052.1 5084.6 5102.1 5186.6 5204.7 5031.9 5060.2 5098.1 5194.0 5220.9 16:01:56 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4449.16 Median:4760.67 stddev:1364.44 (28.7%) stddev-sans-first:1332.37 16:01:56 INFO - PROCESS | 1640 | Values: 306.3 307.9 315.5 319.5 322.7 4059.5 5376.8 5454.2 5534.9 5574.2 5055.0 5129.9 5196.3 5267.8 5365.2 4101.5 4112.7 4165.4 4178.3 4192.4 4134.7 4150.5 4154.3 4177.7 4213.0 3440.9 3783.7 4110.8 4154.9 4191.8 4073.8 4106.8 4115.0 4132.3 4146.7 3290.7 3415.6 3645.3 3646.6 3666.7 5166.8 5319.6 5341.2 5343.5 5358.7 4232.4 4280.8 4759.5 4761.8 4818.4 5698.8 5703.0 5705.8 5730.6 5735.4 3224.6 3781.5 4062.7 4075.2 4079.3 5012.9 5619.7 5969.5 6013.9 6048.8 3794.5 4096.6 4141.8 4151.5 4158.4 3302.5 4044.7 4207.1 4221.7 4250.6 4523.0 4664.8 4781.8 4810.7 4812.5 5040.4 5278.4 5578.9 5621.4 5830.9 1329.7 1344.8 1416.2 1428.6 1431.7 852.4 855.6 872.2 891.2 892.3 4111.5 4199.8 4259.5 4381.1 4513.9 4321.7 4440.5 4738.0 4750.5 4994.6 3809.2 4119.4 4157.6 4247.4 4603.8 2208.7 2259.7 2588.5 2610.9 2631.9 4667.8 4686.0 5143.5 5144.3 5175.8 3961.7 4248.5 4743.8 4983.9 5075.8 4539.8 5109.8 5126.5 5134.4 5140.6 4387.8 4939.0 4942.9 4943.9 5054.6 4106.2 4415.5 4531.4 5259.5 5939.8 5097.9 6182.0 6194.3 6200.7 6213.2 4023.5 4027.1 4052.8 4061.3 4064.8 5137.9 5671.7 5681.9 5808.2 6116.5 5822.7 5829.5 5832.5 5839.7 5847.2 5545.9 5803.5 5857.3 5875.9 5916.2 5101.7 5362.4 5432.0 5658.3 5715.7 4687.8 5711.4 5750.6 5850.2 5891.7 5015.7 5749.8 5752.2 5776.3 5778.3 16:01:56 INFO - PROCESS | 1640 | 16:01:56 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:26498.74 Median:22671.88 stddev:43539.03 (192.0%) stddev-sans-first:43618.05 16:01:56 INFO - PROCESS | 1640 | Values: 691.9 742.5 794.2 829.8 832.8 16310.5 17084.7 17100.7 17102.7 17119.1 15261.9 15317.9 15325.8 15331.2 15339.5 720.8 739.9 741.2 742.8 882.1 741.3 744.8 854.5 880.7 884.8 934.5 943.4 946.8 949.5 949.6 941.1 944.2 945.3 945.4 945.7 698.3 699.1 699.3 699.8 700.2 15678.4 15705.3 15722.4 15728.9 15756.4 12972.0 13541.7 13688.2 13977.0 14133.0 10952.6 13125.3 13622.7 13818.9 13938.9 3951.2 4076.5 4255.9 4274.3 4279.7 113228.0 113614.0 116314.0 116378.3 119133.4 10782.9 11214.9 11281.5 11415.3 11503.2 22318.6 22623.5 22641.4 22671.2 22672.5 7963.9 8073.2 8246.7 8382.8 8417.3 4437.1 4550.7 4625.4 4702.5 4747.8 3683.9 3755.4 3841.9 3848.1 3869.9 694.0 697.2 703.6 705.1 714.0 638.9 665.5 681.7 694.7 705.3 9526.7 9921.7 9944.8 10019.9 10067.4 625.8 658.5 666.5 670.1 718.1 4487.1 4800.5 4850.2 4862.9 4873.1 172345.0 172809.0 175210.1 176445.0 178952.0 102641.0 125522.0 149637.0 151891.0 153531.0 123594.1 123840.0 127089.4 127257.4 128260.0 9283.4 9394.5 9404.6 9415.9 9430.2 58735.7 64785.7 64833.7 65752.7 66000.0 65469.3 67753.3 84639.2 89195.6 94314.1 13298.9 13378.9 13757.7 13767.6 13800.6 14772.2 15605.4 16123.0 16605.3 16624.4 13588.1 13795.9 14382.6 15028.7 15074.9 14522.7 14600.7 14666.9 14975.9 15368.4 11899.8 13435.3 14579.6 15103.8 15119.3 10899.6 11351.1 11505.0 12067.3 12191.6 10324.5 10448.7 11726.8 11926.5 12230.3 16:01:56 INFO - PROCESS | 1640 | -------- Summary: end -------- 16:01:56 INFO - PROCESS | 1640 | 16:01:57 INFO - TEST-INFO | 1640: exit 0 16:01:57 INFO - TEST-OK | dromaeo_css | took 1114912ms 16:01:57 INFO - TEST-START | kraken 16:01:57 INFO - operating with platform_type : mac_ 16:01:57 INFO - Initialising browser for kraken test... 16:01:57 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpUnnGE8/profile http://localhost:49241/getInfo.html) 16:01:59 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 16:01:59 INFO - PROCESS | 1649 | colorDepth:24 16:01:59 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 16:01:59 INFO - PROCESS | 1649 | __metrics 16:01:59 INFO - PROCESS | 1649 | 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" 16:01:59 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:02:00 INFO - TEST-INFO | 1649: exit 0 16:02:00 INFO - Browser initialized. 16:02:00 INFO - Running cycle 1/1 for kraken test... 16:02:00 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/T/tmpUnnGE8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:12 INFO - PROCESS | 1651 | RSS: Main: 168914944 16:02:12 INFO - PROCESS | 1651 | 16:02:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 16:02:15 INFO - PROCESS | 1651 | RSS: Main: 218624000 16:02:15 INFO - PROCESS | 1651 | 16:02:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 16:02:19 INFO - PROCESS | 1651 | RSS: Main: 232542208 16:02:19 INFO - PROCESS | 1651 | 16:02:22 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 16:02:22 INFO - PROCESS | 1651 | RSS: Main: 328638464 16:02:22 INFO - PROCESS | 1651 | 16:02:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 16:02:25 INFO - PROCESS | 1651 | RSS: Main: 228073472 16:02:25 INFO - PROCESS | 1651 | 16:02:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:02:28 INFO - PROCESS | 1651 | RSS: Main: 231157760 16:02:28 INFO - PROCESS | 1651 | 16:02:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 16:02:31 INFO - PROCESS | 1651 | RSS: Main: 266227712 16:02:31 INFO - PROCESS | 1651 | 16:02:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 16:02:34 INFO - PROCESS | 1651 | RSS: Main: 268939264 16:02:34 INFO - PROCESS | 1651 | 16:02:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 16:02:38 INFO - PROCESS | 1651 | RSS: Main: 265883648 16:02:38 INFO - PROCESS | 1651 | 16:02:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:02:40 INFO - PROCESS | 1651 | RSS: Main: 239587328 16:02:40 INFO - PROCESS | 1651 | 16:02:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:02:42 INFO - PROCESS | 1651 | RSS: Main: 245518336 16:02:42 INFO - PROCESS | 1651 | 16:02:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:02:45 INFO - PROCESS | 1651 | RSS: Main: 248770560 16:02:45 INFO - PROCESS | 1651 | 16:02:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:02:49 INFO - PROCESS | 1651 | RSS: Main: 267898880 16:02:49 INFO - PROCESS | 1651 | 16:02:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:02:52 INFO - PROCESS | 1651 | RSS: Main: 261779456 16:02:52 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 16:02:54 INFO - PROCESS | 1651 | RSS: Main: 305860608 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | __start_tp_report 16:02:54 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 16:02:54 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 16:02:54 INFO - PROCESS | 1651 | |i|pagename|runs| 16:02:54 INFO - PROCESS | 1651 | |0;ai-astar;114;113;113;115;114;113;114;113;112;112 16:02:54 INFO - PROCESS | 1651 | |1;audio-beat-detection;146;109;109;140;111;134;136;107;137;106 16:02:54 INFO - PROCESS | 1651 | |2;audio-dft;194;192;196;194;193;195;193;193;193;194 16:02:54 INFO - PROCESS | 1651 | |3;audio-fft;81;60;58;88;59;59;58;59;90;81 16:02:54 INFO - PROCESS | 1651 | |4;audio-oscillator;86;68;89;70;68;69;91;68;69;91 16:02:54 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;96;92;94;91;95;87;93;72;117;129 16:02:54 INFO - PROCESS | 1651 | |6;imaging-darkroom;94;94;95;94;94;94;94;94;97;95 16:02:54 INFO - PROCESS | 1651 | |7;imaging-desaturate;86;86;86;99;134;134;86;83;83;85 16:02:54 INFO - PROCESS | 1651 | |8;json-parse-financial;56;54;52;52;55;57;57;56;56;56 16:02:54 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;63;72;78;72;71;67;66;67;69;67 16:02:54 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;84;81;85;79;70;94;89;100;101;102 16:02:54 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;135;134;135;133;130;132;138;136;165;146 16:02:54 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;161;163;163;161;160;162;160;162;162;160 16:02:54 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;67;66;66;65;65;65;66;66;64 16:02:54 INFO - PROCESS | 1651 | __end_tp_report 16:02:54 INFO - PROCESS | 1651 | __start_cc_report 16:02:54 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1859 16:02:54 INFO - PROCESS | 1651 | __end_cc_report 16:02:54 INFO - PROCESS | 1651 | __startTimestamp1482364974567__endTimestamp 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | ------- Summary: start ------- 16:02:54 INFO - PROCESS | 1651 | Number of tests: 14 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.50 stddev:0.95 (0.8%) stddev-sans-first:0.97 16:02:54 INFO - PROCESS | 1651 | Values: 114.0 113.0 113.0 115.0 114.0 113.0 114.0 113.0 112.0 112.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:135.00 stddev:16.27 (12.1%) stddev-sans-first:15.08 16:02:54 INFO - PROCESS | 1651 | Values: 146.0 109.0 109.0 140.0 111.0 134.0 136.0 107.0 137.0 106.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:193.70 Median:194.00 stddev:1.16 (0.6%) stddev-sans-first:1.22 16:02:54 INFO - PROCESS | 1651 | Values: 194.0 192.0 196.0 194.0 193.0 195.0 193.0 193.0 193.0 194.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:69.30 Median:70.50 stddev:13.79 (19.6%) stddev-sans-first:13.96 16:02:54 INFO - PROCESS | 1651 | Values: 81.0 60.0 58.0 88.0 59.0 59.0 58.0 59.0 90.0 81.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.00 stddev:10.73 (13.8%) stddev-sans-first:10.87 16:02:54 INFO - PROCESS | 1651 | Values: 86.0 68.0 89.0 70.0 68.0 69.0 91.0 68.0 69.0 91.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:92.50 stddev:15.77 (17.0%) stddev-sans-first:16.73 16:02:54 INFO - PROCESS | 1651 | Values: 96.0 92.0 94.0 91.0 95.0 87.0 93.0 72.0 117.0 129.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 16:02:54 INFO - PROCESS | 1651 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 97.0 95.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:86.00 stddev:20.43 (23.8%) stddev-sans-first:21.33 16:02:54 INFO - PROCESS | 1651 | Values: 86.0 86.0 86.0 99.0 134.0 134.0 86.0 83.0 83.0 85.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:1.85 (3.3%) stddev-sans-first:1.94 16:02:54 INFO - PROCESS | 1651 | Values: 56.0 54.0 52.0 52.0 55.0 57.0 57.0 56.0 56.0 56.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:70.00 stddev:4.21 (6.0%) stddev-sans-first:3.82 16:02:54 INFO - PROCESS | 1651 | Values: 63.0 72.0 78.0 72.0 71.0 67.0 66.0 67.0 69.0 67.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:82.50 stddev:10.66 (12.9%) stddev-sans-first:11.18 16:02:54 INFO - PROCESS | 1651 | Values: 84.0 81.0 85.0 79.0 70.0 94.0 89.0 100.0 101.0 102.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:135.50 stddev:10.30 (7.6%) stddev-sans-first:10.85 16:02:54 INFO - PROCESS | 1651 | Values: 135.0 134.0 135.0 133.0 130.0 132.0 138.0 136.0 165.0 146.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.40 Median:162.00 stddev:1.17 (0.7%) stddev-sans-first:1.24 16:02:54 INFO - PROCESS | 1651 | Values: 161.0 163.0 163.0 161.0 160.0 162.0 160.0 162.0 162.0 160.0 16:02:54 INFO - PROCESS | 1651 | 16:02:54 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.84 (1.3%) stddev-sans-first:0.88 16:02:54 INFO - PROCESS | 1651 | Values: 66.0 67.0 66.0 66.0 65.0 65.0 65.0 66.0 66.0 64.0 16:02:54 INFO - PROCESS | 1651 | -------- Summary: end -------- 16:02:54 INFO - PROCESS | 1651 | 16:02:55 INFO - TEST-INFO | 1651: exit 0 16:02:55 INFO - TEST-OK | kraken | took 58311ms 16:02:55 INFO - SUITE-END | took 1173s 16:02:55 INFO - Completed test suite (00:19:33) 16:02:55 INFO - javascript benchmark 16:02:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.7523685723568, 3472.878449254276, 3484.6689564491394, 3499.6675315844986, 3551.543867014523, 5660.29246344207, 5734.483896449318, 5758.827235182941, 5770.682612456324, 5794.855128621782, 5244.662611034181, 5245.580353571715, 5714.114312281603, 5775.0, 5777.537796976242, 5198.168293073109, 5213.973930130344, 5235.86910327242, 5271.894562108756, 5274.498922602346, 5190.636655434121, 5192.662476938987, 5195.0, 5283.656562323436, 5299.867503312409, 5193.948060519403, 5208.2968799212185, 5220.686758794484, 5229.058769421503, 5232.2674825524455, 5221.269022336857, 5247.081760691902, 5303.6552624079695, 5308.542171921309, 5319.175527793198, 5095.923561146586, 5160.612954028463, 5222.608304377149, 5230.267762513251, 5245.895082098375, 5681.403452637455, 5717.485426311652, 5723.5421166307015, 5727.742251598688, 5775.3935836736955, 5775.624584402, 6177.96911015442, 6309.3690630937, 6314.968425157845, 6338.049292606146, 6000.939990600082, 6316.431521163071, 6329.430351268362, 6356.173697419352, 6358.713857876407, 5700.059490184115, 5755.194272801811, 5770.451807078321, 5782.566307526952, 5838.94161058384, 5241.925405291906, 6267.62394256346, 6303.3066362700065, 6306.054091886253, 6317.810465686037, 6176.867630722972, 6203.906941395882, 6215.440610345091, 6221.657808820558, 6224.502039836802, 6122.234720659918, 6153.323134455206, 6260.624362538171, 6316.431521163117, 6334.1765570475545, 6145.310039739107, 6169.444749972524, 6208.937910620926, 6240.906386404207, 6296.590721603173, 6269.905951410641, 6301.684915754194, 6304.275008374096, 6317.305096439388, 6325.9051114233325, 4945.060438516671, 4985.950140498621, 5002.09962206799, 5018.096742586296, 5026.52248036436, 4404.977975110168, 4438.955610443919, 4453.977730111329, 4515.0518391137575, 4553.203189441834, 5724.8282551523525, 5792.4497172768515, 5803.593748437569, 5817.854553636108, 5822.475977162076, 5071.518205770446, 6179.134921111048, 6257.468115210167, 6267.185265915402, 6296.748130074746, 5775.797847075416, 5834.27071616048, 5848.385919478394, 5850.063989761617, 5851.941480585225, 5413.593980451482, 5442.755076021588, 5443.074677304868, 5445.1015582429245, 5448.891022179534, 6227.1282020517165, 6264.310925798068, 6282.685865706696, 6304.338044505261, 6334.588251763621, 5386.488283613207, 6219.253689557282, 6311.431971122621, 6347.96826015867, 6356.586821856565, 6360.363963603602, 6371.331010243857, 6395.42441180296, 6410.0, 6416.711247993758, 5185.559227465707, 5774.931637647049, 6154.907676384768, 6167.969160154171, 6316.905246421399, 6232.781852635136, 6260.060990851409, 6269.62382257066, 6352.0789485525265, 6352.523560732871, 6225.782097626561, 6233.127362169391, 6238.344973777688, 6251.281102673251, 6275.6862156892885, 5165.9483405165465, 6025.457708806229, 6195.132681424514, 6224.097505861534, 6227.937720622917, 5933.376995415592, 5976.372480889444, 5993.041113421831, 6059.848503787441, 6129.632222066691, 5668.639526513524, 5796.82609521715, 6200.255969283715, 6214.005759078525, 6218.284897236695, 6039.305479869871, 6073.392660734068, 6081.787137450168, 6106.45041946227, 6108.205933228652], "value": 5713.575145875748, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.5928079693853, 531.1156923722003, 547.5072434808671, 559.8880223955207, 565.375260337327, 32001.839990800072, 35120.824395878015, 41249.968750781205, 42430.0, 44917.32624010643, 43812.780936095354, 44073.77963110188, 44111.55888441115, 44172.77913610436, 44559.55440445594, 9251.722448326527, 9969.601215951354, 10143.949280253626, 10160.796784064314, 10166.339187952797, 9323.021082786312, 12163.148579599432, 12431.440585173688, 12442.81335779964, 12678.87321126786, 12328.32194229317, 12439.1914525556, 12510.249385036926, 12531.74936501274, 12541.0, 12287.631371058838, 12343.321117338543, 12348.629541113782, 12358.949489293422, 12367.443465044094, 10743.892561074368, 11992.520299188021, 12234.75530489394, 12338.629841104781, 12348.38258087092, 40737.18525629471, 40996.795016025026, 41030.79484602558, 41056.56302029415, 41140.38289425661, 3472.6700963408434, 3523.5243242162237, 3644.4533320001947, 3664.963350366489, 4340.0, 31485.37029259425, 31762.523562146587, 31910.20224494383, 32087.679123208934, 32238.355232895447, 3090.799098058619, 3284.720798732111, 3309.619393769723, 3375.1055970167927, 3387.915302117442, 522332.0, 523558.0, 524221.3788931069, 525228.0, 526444.3677781625, 34420.0, 60011.0, 60230.69884650549, 60309.095363569584, 60409.0, 35219.4717079244, 36457.453138202945, 36593.0, 36631.450528242094, 36647.267054658754, 15661.451849185452, 15792.36830526766, 15832.525024249291, 15859.286332115082, 15896.841031589767, 11104.389258590854, 12305.015598752077, 12475.251484910934, 13343.73312533744, 13462.461501540025, 7854.1753115922, 8009.439339246197, 8055.436119471581, 8101.473404228706, 8124.837503249902, 3344.665533446636, 3403.7617366784584, 3404.5233667286598, 3417.8803741868915, 3437.656234376542, 998.6953979036318, 1022.7085280694967, 1051.9684409467727, 1073.7261998190568, 1086.4676308608725, 32401.837990810367, 32728.836355818068, 32771.67228327734, 34323.0, 34362.82818585891, 958.1807554540907, 1036.4247842447514, 1074.887136850635, 1091.2361347057092, 1098.8461615373853, 13527.932360338333, 15045.924770376078, 15676.451324203597, 15724.0, 16035.599110022344, 634135.0, 638946.0, 639787.0, 644294.0, 645772.0, 58322.41677583255, 58702.706486467294, 58719.706401468575, 58771.0, 58813.117803232984, 1305.4973835073565, 1343.1806597975228, 1372.7940808878748, 1401.6355747505722, 1406.9929650351683, 30071.849640751658, 31207.0, 31349.686503134675, 31677.20806979844, 31900.36199275955, 49901.75049124731, 50412.747936260086, 50435.495645044546, 50696.49303506918, 50764.49235507598, 502091.0, 591849.0, 592480.0, 593463.0, 864468.0, 22961.88519057394, 26077.73922260753, 26089.347766305993, 26493.867530662224, 27022.72977270202, 45910.77044614756, 46008.769956150005, 46318.610441686804, 46323.0, 46329.768351158025, 37769.433458497595, 38443.42334865035, 38781.0, 39003.41494877522, 39722.60277397189, 36927.44608830816, 36943.445848311756, 37589.35247495183, 37800.0, 38077.42883856689, 37334.0, 37759.0, 38325.42511862268, 38389.232215356096, 38451.42322865215, 32064.839675802406, 34266.31467370688, 34574.0, 34604.82697586496, 34632.65367346294, 25477.617835732108, 25579.744202557737, 25828.741712583385, 25839.61240581355, 25942.837311916068], "value": 19543.605173448323, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.46123696206837, 384.73068851803737, 385.310884786133, 393.3372267728876, 413.5864135864136, 14700.779488307657, 20384.286549970755, 21137.788622113774, 21903.780962190394, 21946.0, 11877.584284550043, 16961.91519042406, 20226.29207977721, 20452.181912723474, 21114.57770844582, 5944.286685597713, 6265.843353916143, 6285.9685701571425, 6301.495880329586, 6302.55882088254, 5974.253218347707, 6140.6929653517145, 6232.844178895519, 6244.281907580618, 6246.437820596146, 6340.619562826245, 6358.459530939876, 6364.809055728336, 6366.6180029198395, 6379.521535884804, 6087.391260873921, 6245.281792593864, 6313.558050936436, 6325.0, 6328.019157030668, 5935.970320148414, 6127.724252408652, 6128.601640893327, 6154.784582539634, 6161.784337548209, 13949.372278247401, 14087.859121408757, 20559.8692071936, 26586.33534161642, 27468.0, 2332.428555004018, 2491.165459571046, 2670.9732902670917, 3040.528718048684, 3042.513197888349, 10188.541515631814, 10249.385036897735, 10676.145908327393, 10685.732856678567, 10820.891791082146, 1934.477691023416, 1980.732601098862, 2000.29989503674, 2004.6892731626622, 2007.076744697441, 114235.0, 118462.22306665407, 118608.0, 119168.0, 119286.99999999913, 11398.658040258806, 11490.42547872603, 11561.051931757349, 11599.420028998686, 11725.355105469282, 12263.93868030654, 14805.0, 14808.703825923421, 14824.777628335583, 14825.92587037058, 7808.180141085218, 7917.0895347034675, 8723.476591404537, 8753.212210901049, 8844.46933184011, 8871.157240062184, 8944.552772361356, 8949.865752013726, 9130.31522635805, 9148.954255228815, 4521.728696278234, 4610.9308360374625, 4640.628749700016, 4665.043666048452, 4702.694324868872, 1794.0622765327007, 1825.9452216433529, 1832.2236487226037, 1912.9521761956062, 1961.6272908147691, 771.9228077192236, 859.5530324231364, 890.9465432073966, 911.6034524981654, 974.7514383832078, 19831.30590429328, 21568.568628627654, 21637.3513959102, 21713.78286217149, 21760.129594816346, 951.3245595627044, 999.4652860719481, 1001.9749506262402, 1006.1296978113879, 1008.1229330482381, 10902.345859248471, 11289.686009042409, 11396.886031139748, 11408.144389170846, 11483.023942964774, 509885.0, 513367.0, 515445.0, 531211.0, 537530.0, 125046.0, 131305.34347328392, 142377.0, 145644.0, 156223.0, 1033.5555711044174, 1037.9481025948671, 1093.6882988348282, 1096.1121491591678, 1096.1942971915635, 18687.0, 19073.237070517578, 19517.902410487855, 19561.60876782428, 19587.0, 55416.0, 55681.72159139178, 57314.7134264326, 57465.0, 58368.4163158363, 80608.0, 82455.58772206101, 82778.17221827705, 84508.0, 85182.57408713165, 6983.790486285419, 7426.405887529093, 7498.987636669198, 8099.757007289791, 8138.511689298661, 10697.572097116028, 12613.936930315289, 12769.0, 12944.15862968904, 13184.60446186616, 8715.477071375737, 10879.619213327496, 11139.554417823196, 11203.71990700239, 11430.028447582048, 11152.442377881236, 11358.716032099264, 11575.479103440197, 11613.76772464563, 11756.647300580997, 11447.542098315975, 11624.709382265173, 11672.124590655905, 11900.047996160114, 11967.940160299142, 7421.369183619453, 7445.664945077593, 7603.0496187976505, 7671.884921726067, 7771.8834217485655, 10978.505967231653, 11110.50002749862, 11140.33158010522, 11313.830292545783, 11459.0], "value": 9709.02210410056, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.8786009259168, 1388.9861101388983, 1531.1731664900954, 1716.7167417376133, 1925.4319975607186, 8000.119986801447, 8644.697435589756, 9019.909800901989, 9035.141661542159, 9202.677906273282, 8983.730488085368, 9064.592093355799, 9066.138716821892, 9079.228265597416, 9117.997020327759, 2419.7459266776955, 2453.668754718117, 2454.067454367338, 2454.325060608338, 2457.5699252630807, 2390.2470721722602, 2423.8909249083745, 2431.161249368965, 2432.525657496789, 2432.7080750309988, 2396.4488167721433, 2407.6388541718793, 2417.528581926525, 2423.7818596326415, 2426.2046085896486, 2376.8811559422134, 2395.508920671267, 2400.2559206645997, 2402.627592723122, 2409.265174121892, 2345.495718420531, 2379.167291447997, 2417.697787776528, 2427.9878600606885, 2429.2347910408166, 8050.677972881078, 8271.17288271174, 9023.142801433853, 9137.954310228473, 9139.451632902048, 5296.523312901857, 5463.562914966833, 5469.56243500519, 5513.724313784295, 5517.69652669103, 3147.8268695221755, 3647.215848592565, 3655.4334078217917, 3732.5707543632557, 3734.9439758403646, 1109.234628106612, 1112.1492058575197, 1121.5121422181296, 1122.0350498571304, 1132.0547342968632, 12084.395780210954, 12182.51269949201, 12190.756184876253, 12200.389980501028, 12200.877991220063, 4732.881677958078, 4768.416671621383, 4856.150173719585, 4934.629902757308, 4940.777665005084, 10235.283530152818, 10253.74365640865, 10263.58945642166, 10267.178625709925, 10307.093187760289, 5500.559955203574, 5502.284702988586, 5503.532199762985, 5504.284443022381, 5505.614606977473, 4410.206162890647, 4953.628477864103, 4962.0, 5031.069252188357, 5044.218146187347, 4046.393041043867, 4052.4326594276827, 4102.815373308208, 4142.316517774533, 4155.21051000315, 1296.7471343088007, 1299.311364976564, 1302.9609111726475, 1304.4521301053392, 1332.6734949937327, 795.6777505110324, 869.9318040917545, 903.823754367892, 934.1685899549407, 939.8919124300656, 5786.88426231473, 5830.2420685310635, 5938.970305148533, 5971.19388882504, 6171.444569988723, 458.0472616956722, 460.74658937584195, 468.3115819745037, 995.4574756757585, 1090.7164137324353, 3297.4064668359924, 3857.9228415431535, 3912.569617342147, 3920.313945059603, 4032.274190645653, 9816.263780216512, 11188.216824822348, 11190.832137517944, 11198.048165905826, 11238.100951923827, 11133.944330278297, 11136.554537818396, 11163.218574699693, 11165.162612804072, 11171.608993685182, 7169.0, 8492.660293588187, 8513.574321283912, 8518.063013068557, 8560.657573696983, 5137.383513978347, 5636.549076073986, 5700.885982280414, 5796.94203057964, 5925.111233315038, 10194.949025254826, 10206.642767503101, 10369.9481502592, 10415.479226038517, 10415.739606509898, 10042.046005629454, 10416.687499375032, 10453.68638940833, 10455.0, 10474.266801323834, 7213.819654508679, 7352.301531354513, 7357.742479013209, 7361.337479626859, 7380.630968451664, 8441.451305665112, 8453.365997550085, 8480.957595211985, 8538.40231183836, 8550.187732165434, 7583.810404739926, 7707.614619269127, 7745.186755390828, 7767.1456139825705, 7875.330596899328, 7485.513441626277, 7516.210797866145, 7582.886256706264, 7586.886196707164, 7762.340201082971, 7053.612051337226, 7094.432445404252, 7126.109236345457, 7198.20819709821, 7205.675744591401, 5052.090623687772, 5084.618653600921, 5102.081625307332, 5186.611004174777, 5204.661696989683, 5031.949680503149, 5060.2156665716875, 5098.107831129463, 5194.0, 5220.921686174786], "value": 4641.898333454691, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.3230261122918, 307.89069880192545, 315.4778841018115, 319.4840332862428, 322.6612057339793, 4059.533153687323, 5376.8118115865955, 5454.181872719094, 5534.889302213954, 5574.163875418689, 5054.989002199565, 5129.871753206172, 5196.272521846944, 5267.789288428458, 5365.195220716894, 4101.487314085739, 4112.663384400067, 4165.375193720944, 4178.331466965286, 4192.350185721218, 4134.746563123105, 4150.460440142792, 4154.299705191619, 4177.749335039892, 4212.978935105336, 3440.8967730967984, 3783.69730421567, 4110.835566577348, 4154.875353739392, 4191.790410479463, 4073.8370465181356, 4106.77412742299, 4114.9712571857035, 4132.318167502373, 4146.688998325122, 3290.654481279479, 3415.590129184514, 3645.2891686121216, 3646.6171052039685, 3666.6883314918264, 5166.7674954626755, 5319.601029922771, 5341.1988201769655, 5343.492368225013, 5358.740695936452, 4232.439374178608, 4280.785960701953, 4759.50025247351, 4761.833335833229, 4818.434008947072, 5698.800541981011, 5703.0, 5705.800296989627, 5730.598858079936, 5735.426457354273, 3224.580804495424, 3781.5084039074745, 4062.7405504293765, 4075.2257071156682, 4079.2657321682086, 5012.897162624216, 5619.662820230799, 5969.492593129589, 6013.939860601382, 6048.788292409745, 3794.4877441545036, 4096.5903409658795, 4141.7722025288895, 4151.522574903865, 4158.4178215049915, 3302.5211344355416, 4044.656204222615, 4207.095474473003, 4221.662267018631, 4250.596193361625, 4523.0, 4664.813407463664, 4781.832635857728, 4810.6632535722165, 4812.494688057704, 5040.39515258164, 5278.419373868871, 5578.884223155385, 5621.381648018714, 5830.912536311958, 1329.674229813702, 1344.791557308599, 1416.157386355117, 1428.5642878922029, 1431.7065001674632, 852.3592085235896, 855.6235256487125, 872.2280781508284, 891.1801142948503, 892.3352102683413, 4111.465509483748, 4199.811008504625, 4259.467566554181, 4381.138016343105, 4513.864584062483, 4321.715496222893, 4440.533743956903, 4738.015050518152, 4750.5249475052215, 4994.600431965434, 3809.154306065375, 4119.361498967664, 4157.625813676723, 4247.430304665694, 4603.838865639686, 2208.7349518057936, 2259.7401298850514, 2588.5470042742822, 2610.8903715920655, 2631.9473610527684, 4667.8330417395655, 4686.039361930796, 5143.485651434901, 5144.276667316491, 5175.792968281226, 3961.7005889550137, 4248.532661407243, 4743.817843791894, 4983.9252411213865, 5075.822346217865, 4539.773011349419, 5109.821156259513, 5126.53861152498, 5134.409542902465, 5140.56305214061, 4387.846425375096, 4939.0, 4942.8764280893265, 4943.925841112315, 5054.59563234933, 4106.181251436193, 4415.492218394926, 4531.371804353533, 5259.526642602184, 5939.77258528052, 5097.898042039064, 6182.0, 6194.349593292639, 6200.658963757037, 6213.2233470816145, 4023.4970628671417, 4027.134166154291, 4052.804422695253, 4061.2892743770312, 4064.817083231321, 5137.922931155961, 5671.723862130987, 5681.914771278517, 5808.21589085485, 6116.480099191441, 5822.70886455684, 5829.504492118217, 5832.533397328119, 5839.678817665069, 5847.239858818497, 5545.861353466195, 5803.535717142534, 5857.297124345106, 5875.855312810879, 5916.201312822714, 5101.7194054327365, 5362.356517217959, 5431.972840135774, 5658.264425624642, 5715.657060576379, 4687.835925742719, 5711.400302968294, 5750.613495399362, 5850.151727999501, 5891.734871930859, 5015.724135172601, 5749.8275051748515, 5752.194692742935, 5776.306843178677, 5778.335491418541], "value": 4022.761008606069, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.8650863081701, 742.4505865659412, 794.1978601612374, 829.7635173975416, 832.7626080655889, 16310.510684679464, 17084.658306833855, 17100.74348884766, 17102.657946841056, 17119.144042797856, 15261.93166478347, 15317.92341038296, 15325.846741532581, 15331.156786376772, 15339.539813805604, 720.8197950512372, 739.9149097853762, 741.1661880384568, 742.754890886009, 882.1443200095897, 741.2735519191169, 744.8100734312769, 854.4659587757651, 880.7357792662208, 884.7699598104475, 934.5420743835506, 943.3632298198722, 946.8153710026547, 949.4540639132492, 949.5964215208509, 941.1247539787994, 944.2210176604328, 945.3382632157517, 945.385499425379, 945.7351941456403, 698.322627051759, 699.0702365853454, 699.2902204262678, 699.8355386484172, 700.1633048507036, 15678.372865085383, 15705.293261803246, 15722.449714259947, 15728.92135539315, 15756.44852430171, 12972.027097967595, 13541.729165416735, 13688.247146406944, 13977.02160848741, 14132.999999999896, 10952.616658416917, 13125.343732813417, 13622.659433514142, 13818.861811381857, 13938.860611393859, 3951.209758048402, 4076.490438695163, 4255.893602659928, 4274.252005898955, 4279.700420970502, 113228.0, 113614.0, 116314.0, 116378.25432618517, 119133.40433297865, 10782.921707829155, 11214.943925280322, 11281.492332845042, 11415.315081095161, 11503.19477636574, 22318.55362892733, 22623.547529049327, 22641.433964151027, 22671.20650777215, 22672.546549069255, 7963.920360796318, 8073.19268073188, 8246.670133194604, 8382.83234335322, 8417.284530814826, 4437.090396468716, 4550.681452298373, 4625.398698169284, 4702.529747025338, 4747.833825816149, 3683.9079023024638, 3755.4366844972924, 3841.865534706272, 3848.13416981177, 3869.941950870739, 693.968771405288, 697.1877762406739, 703.5884007855462, 705.0657878311258, 713.9714411423589, 638.9170356246118, 665.5108495255982, 681.7273090763734, 694.6665600511783, 705.3107806802781, 9526.666566670134, 9921.702348929544, 9944.80110397788, 10019.949900250598, 10067.395956242503, 625.8372823066036, 658.4830907737403, 666.4901350466897, 670.1321788284183, 718.0844423360153, 4487.125010622963, 4800.5199480052415, 4850.175470170079, 4862.902741945141, 4873.122837889214, 172345.0, 172809.0, 175210.123949382, 176445.0, 178952.0, 102641.0, 125522.0, 149637.0, 151891.0, 153531.0, 123594.14608780875, 123840.0, 127089.36455317664, 127257.36371318084, 128260.0, 9283.350165488351, 9394.483303418378, 9404.576794044422, 9415.905840941503, 9430.245580353418, 58735.70632146812, 64785.7324993623, 64833.67583162054, 65752.67123664351, 66000.0, 65469.345306546325, 67753.32246677668, 84639.15360846312, 89195.55402222948, 94314.05685943327, 13298.933505332412, 13378.866211337761, 13757.656058598615, 13767.586972390844, 13800.585982420544, 14772.18752968597, 15605.445355095819, 16123.0, 16605.3357865684, 16624.418145364853, 13588.116772409761, 13795.931020344835, 14382.640433989234, 15028.6994260112, 15074.924625376803, 14522.709545809235, 14600.719585566554, 14666.926665367031, 14975.850241497446, 15368.385264589739, 11899.82150267764, 13435.261060641758, 14579.635509112357, 15103.773443398139, 15119.319630616428, 10899.618513351994, 11351.14866385008, 11505.0, 12067.336296503776, 12191.634250972485, 10324.48377581103, 10448.746150461993, 11726.824097638371, 11926.463309151282, 12230.266184028987], "value": 8184.7568207009745, "unit": "score"}], "name": "dromaeo_css", "value": 7411.281022820256, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 115.0, 114.0, 113.0, 114.0, 113.0, 112.0, 112.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 109.0, 140.0, 111.0, 134.0, 136.0, 107.0, 137.0, 106.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 192.0, 196.0, 194.0, 193.0, 195.0, 193.0, 193.0, 193.0, 194.0], "value": 193.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 58.0, 88.0, 59.0, 59.0, 58.0, 59.0, 90.0, 81.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 89.0, 70.0, 68.0, 69.0, 91.0, 68.0, 69.0, 91.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 92.0, 94.0, 91.0, 95.0, 87.0, 93.0, 72.0, 117.0, 129.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 97.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 99.0, 134.0, 134.0, 86.0, 83.0, 83.0, 85.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 52.0, 52.0, 55.0, 57.0, 57.0, 56.0, 56.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 78.0, 72.0, 71.0, 67.0, 66.0, 67.0, 69.0, 67.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 85.0, 79.0, 70.0, 94.0, 89.0, 100.0, 101.0, 102.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 135.0, 133.0, 130.0, 132.0, 138.0, 136.0, 165.0, 146.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 163.0, 161.0, 160.0, 162.0, 160.0, 162.0, 162.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 65.0, 65.0, 65.0, 66.0, 66.0, 64.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1442.2000000000003, "alertThreshold": 2.0}]} 16:02:55 INFO - Return code: 0 16:02:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:02:55 INFO - # TBPL SUCCESS # 16:02:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:02:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:02:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:02:55 INFO - 2016-12-21 16:02:55.913 screenresolution[1655:9808] starting screenresolution argv=screenresolution get 16:02:55 INFO - 2016-12-21 16:02:55.916 screenresolution[1655:9808] Display 0: 1600x1200x32@60 16:02:55 INFO - 2016-12-21 16:02:55.922 screenresolution[1656:9812] starting screenresolution argv=screenresolution list 16:02:55 INFO - Available Modes on Display 0 16:02:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:02:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:02:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:02:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:02:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:02:56 INFO - Intel Iris: 16:02:56 INFO - Chipset Model: Intel Iris 16:02:56 INFO - Type: GPU 16:02:56 INFO - Bus: Built-In 16:02:56 INFO - VRAM (Dynamic, Max): 1536 MB 16:02:56 INFO - Vendor: Intel (0x8086) 16:02:56 INFO - Device ID: 0x0a2e 16:02:56 INFO - Revision ID: 0x0009 16:02:56 INFO - Displays: 16:02:56 INFO - Display: 16:02:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:02:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:02:56 INFO - Display Serial Number: 1600x1200 60 16:02:56 INFO - Main Display: Yes 16:02:56 INFO - Mirror: Off 16:02:56 INFO - Online: Yes 16:02:56 INFO - Rotation: Supported 16:02:56 INFO - Return code: 0 16:02:56 INFO - Running post-action listener: _resource_record_post_action 16:02:56 INFO - [mozharness: 2016-12-22 00:02:56.157140Z] Finished run-tests step (success) 16:02:56 INFO - Running post-run listener: _resource_record_post_run 16:02:56 INFO - Total resource usage - Wall time: 1196s; CPU: 53.0%; Read bytes: 37594112; Write bytes: 492569600; Read time: 241; Write time: 1539 16:02:56 INFO - TinderboxPrint: CPU usage
53.5% 16:02:56 INFO - TinderboxPrint: I/O read bytes / time
37,594,112 / 241 16:02:56 INFO - TinderboxPrint: I/O write bytes / time
492,569,600 / 1,539 16:02:56 INFO - TinderboxPrint: CPU idle
2,227.4 (46.5%) 16:02:56 INFO - TinderboxPrint: CPU system
958.1 (20.0%) 16:02:56 INFO - TinderboxPrint: CPU user
1,600.2 (33.4%) 16:02:56 INFO - TinderboxPrint: Swap in / out
232,812,544 / 0 16:02:56 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 188221952; Write bytes: 185774080; Read time: 17645; Write time: 394 16:02:56 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 36668416; Write bytes: 296674304; Read time: 229; Write time: 1110 16:02:56 INFO - Running post-run listener: _upload_blobber_files 16:02:56 INFO - Blob upload gear active. 16:02:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:02:56 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 16:02:56 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'] 16:02:56 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 16:02:57 INFO - (blobuploader) - INFO - Open directory for files ... 16:02:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:02:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:02:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:58 INFO - (blobuploader) - INFO - Done attempting. 16:02:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:02:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:02:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:59 INFO - (blobuploader) - INFO - Done attempting. 16:02:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:03:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:01 INFO - (blobuploader) - INFO - Done attempting. 16:03:01 INFO - (blobuploader) - INFO - Iteration through files over. 16:03:01 INFO - Return code: 0 16:03:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:03:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:03:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5057697fdcb934c7dfb84425038aabbfe5f6f2446c2ee23b6c363f4cc09d33d843af42044d329779db4bb928c97b188739fe29bc1668ee415e1a0d285a1956b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c4c4ad111605be5826a0306f3f8fa2bbaf185446d888c960c134f21e1f4ccf5cb5db89c5dce30bb678041a3071fca20c7b32793ade2876766f6b5cb6c1076197", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac38ad383ff6f0da6bb02ee308ff300bbd39872f0836d708ed8a646f1f9b7f06f88ce67534c7285ae580813fed28808b9f9c6623d66c02bbe90b5fe495c3740"} 16:03:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:03:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:03:01 INFO - Contents: 16:03:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5057697fdcb934c7dfb84425038aabbfe5f6f2446c2ee23b6c363f4cc09d33d843af42044d329779db4bb928c97b188739fe29bc1668ee415e1a0d285a1956b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c4c4ad111605be5826a0306f3f8fa2bbaf185446d888c960c134f21e1f4ccf5cb5db89c5dce30bb678041a3071fca20c7b32793ade2876766f6b5cb6c1076197", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac38ad383ff6f0da6bb02ee308ff300bbd39872f0836d708ed8a646f1f9b7f06f88ce67534c7285ae580813fed28808b9f9c6623d66c02bbe90b5fe495c3740"} 16:03:01 INFO - Running post-run listener: copy_logs_to_upload_dir 16:03:01 INFO - Copying logs to upload dir... 16:03:01 INFO - mkdir: /builds/slave/test/build/upload/logs 16:03:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.798707 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-12-21 16:03:02.008471) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 16:03:02.011509) ========= 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.HMo8oF7S94/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.q5sjfeTkam/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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/d5057697fdcb934c7dfb84425038aabbfe5f6f2446c2ee23b6c363f4cc09d33d843af42044d329779db4bb928c97b188739fe29bc1668ee415e1a0d285a1956b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c4c4ad111605be5826a0306f3f8fa2bbaf185446d888c960c134f21e1f4ccf5cb5db89c5dce30bb678041a3071fca20c7b32793ade2876766f6b5cb6c1076197", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac38ad383ff6f0da6bb02ee308ff300bbd39872f0836d708ed8a646f1f9b7f06f88ce67534c7285ae580813fed28808b9f9c6623d66c02bbe90b5fe495c3740"} build_url:https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011063 build_url: 'https://queue.taskcluster.net/v1/task/TGwnxtUxR62NfgKbPOkxig/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/d5057697fdcb934c7dfb84425038aabbfe5f6f2446c2ee23b6c363f4cc09d33d843af42044d329779db4bb928c97b188739fe29bc1668ee415e1a0d285a1956b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c4c4ad111605be5826a0306f3f8fa2bbaf185446d888c960c134f21e1f4ccf5cb5db89c5dce30bb678041a3071fca20c7b32793ade2876766f6b5cb6c1076197", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ac38ad383ff6f0da6bb02ee308ff300bbd39872f0836d708ed8a646f1f9b7f06f88ce67534c7285ae580813fed28808b9f9c6623d66c02bbe90b5fe495c3740"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 16:03:02.049832) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-12-21 16:03:02.052162) ========= 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.HMo8oF7S94/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.q5sjfeTkam/Listeners TMPDIR=/var/folders/jg/x01k5sqn6dx7sgpxmxkndj5h00000w/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.005153 ========= master_lag: 2.20 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-12-21 16:03:04.260374) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 16:03:04.260703) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 16:03:04.267049) ========= ========= Total master_lag: 2.36 =========