builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1483551113.27 results: success (0) buildid: 20170104081038 builduid: d0418ba9ed79487a96bcc888613a3c47 revision: b0bc6095804be7340749e6011b8d6043e5b320ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:53.266966) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:53.267456) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:53.267787) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:53.294204) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:53.294645) ========= 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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 09:31:53-- 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% 8.03M=0.001s 2017-01-04 09:31:53 (8.03 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141727 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:53.460731) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:53.461083) ========= 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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.020986 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:53.510562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 09:31:53.510967) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b0bc6095804be7340749e6011b8d6043e5b320ec --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 b0bc6095804be7340749e6011b8d6043e5b320ec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 09:31:53,589 truncating revision to first 12 chars 2017-01-04 09:31:53,589 Setting DEBUG logging. 2017-01-04 09:31:53,589 attempt 1/10 2017-01-04 09:31:53,589 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b0bc6095804b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 09:31:54,827 unpacking tar archive at: mozilla-beta-b0bc6095804b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.517657 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 09:31:55.053003) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:55.053321) ========= script_repo_revision: b0bc6095804be7340749e6011b8d6043e5b320ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:55.053712) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:55.053973) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:31:55.074754) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-04 09:31:55.075097) ========= /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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:31:55 INFO - MultiFileLogger online at 20170104 09:31:55 in /builds/slave/test 09:31:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:31:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:31:55 INFO - {'append_to_log': False, 09:31:55 INFO - 'base_work_dir': '/builds/slave/test', 09:31:55 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:31:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:31:55 INFO - 'branch': 'Mozilla-Beta', 09:31:55 INFO - 'buildbot_json_path': 'buildprops.json', 09:31:55 INFO - 'config_files': ('talos/mac_config.py',), 09:31:55 INFO - 'default_actions': ('clobber', 09:31:55 INFO - 'read-buildbot-config', 09:31:55 INFO - 'download-and-extract', 09:31:55 INFO - 'populate-webroot', 09:31:55 INFO - 'create-virtualenv', 09:31:55 INFO - 'install', 09:31:55 INFO - 'run-tests'), 09:31:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:31:55 INFO - 'download_minidump_stackwalk': True, 09:31:55 INFO - 'download_symbols': 'ondemand', 09:31:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:31:55 INFO - 'tooltool.py': '/tools/tooltool.py', 09:31:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:31:55 INFO - '/tools/misc-python/virtualenv.py')}, 09:31:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:31:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:31:55 INFO - 'installer_path': 'installer.exe', 09:31:55 INFO - 'log_level': 'info', 09:31:55 INFO - 'log_name': 'talos', 09:31:55 INFO - 'log_to_console': True, 09:31:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:31:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:31:55 INFO - 'opt_config_files': (), 09:31:55 INFO - 'pip_index': False, 09:31:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:31:55 INFO - 'cmd': ('bash', 09:31:55 INFO - '-c', 09:31:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:31:55 INFO - 'enabled': True, 09:31:55 INFO - 'halt_on_failure': False, 09:31:55 INFO - 'name': 'check_screen_resolution'},), 09:31:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:31:55 INFO - 'cmd': ('bash', 09:31:55 INFO - '-c', 09:31:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:31:55 INFO - 'enabled': True, 09:31:55 INFO - 'halt_on_failure': False, 09:31:55 INFO - 'name': 'check_screen_resolution'},), 09:31:55 INFO - 'run_cmd_checks_enabled': True, 09:31:55 INFO - 'sps_profile': False, 09:31:55 INFO - 'sps_profile_interval': 0, 09:31:55 INFO - 'suite': 'dromaeojs', 09:31:55 INFO - 'system_bits': '32', 09:31:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:31:55 INFO - 'title': 't-yosemite-r7-0034', 09:31:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:31:55 INFO - 'use_talos_json': True, 09:31:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:31:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:31:55 INFO - 'work_dir': 'build'} 09:31:55 INFO - [mozharness: 2017-01-04 17:31:55.279264Z] Running clobber step. 09:31:55 INFO - Running pre-action listener: _resource_record_pre_action 09:31:55 INFO - Running main action method: clobber 09:31:55 INFO - rmtree: /builds/slave/test/build 09:31:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:31:57 INFO - Running post-action listener: _resource_record_post_action 09:31:57 INFO - [mozharness: 2017-01-04 17:31:57.416325Z] Finished clobber step (success) 09:31:57 INFO - [mozharness: 2017-01-04 17:31:57.416460Z] Running read-buildbot-config step. 09:31:57 INFO - Running pre-action listener: _resource_record_pre_action 09:31:57 INFO - Running main action method: read_buildbot_config 09:31:57 INFO - Using buildbot properties: 09:31:57 INFO - { 09:31:57 INFO - "project": "", 09:31:57 INFO - "product": "firefox", 09:31:57 INFO - "script_repo_revision": "production", 09:31:57 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:31:57 INFO - "repository": "", 09:31:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:31:57 INFO - "buildid": "20170104081038", 09:31:57 INFO - "pgo_build": "False", 09:31:57 INFO - "basedir": "/builds/slave/test", 09:31:57 INFO - "buildnumber": 3, 09:31:57 INFO - "slavename": "t-yosemite-r7-0034", 09:31:57 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:31:57 INFO - "platform": "yosemite_r7", 09:31:57 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:31:57 INFO - "branch": "mozilla-beta", 09:31:57 INFO - "repo_path": "releases/mozilla-beta", 09:31:57 INFO - "revision": "b0bc6095804be7340749e6011b8d6043e5b320ec", 09:31:57 INFO - "stage_platform": "macosx64", 09:31:57 INFO - "builduid": "d0418ba9ed79487a96bcc888613a3c47", 09:31:57 INFO - "slavebuilddir": "test" 09:31:57 INFO - } 09:31:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 09:31:57 INFO - Running post-action listener: _resource_record_post_action 09:31:57 INFO - [mozharness: 2017-01-04 17:31:57.419984Z] Finished read-buildbot-config step (success) 09:31:57 INFO - [mozharness: 2017-01-04 17:31:57.420135Z] Running download-and-extract step. 09:31:57 INFO - Running pre-action listener: _resource_record_pre_action 09:31:57 INFO - Running main action method: download_and_extract 09:31:57 INFO - mkdir: /builds/slave/test/build/tests 09:31: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')]} 09:31:57 INFO - trying https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 09:31:57 INFO - Downloading https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 09:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 09:31:58 INFO - Downloaded 1165 bytes. 09:31:58 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 09:31:58 INFO - Using the following test package requirements: 09:31:58 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:31:58 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:31:58 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 09:31:58 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:31:58 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 09:31:58 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:31:58 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:31:58 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 09:31:58 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:31:58 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:31:58 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 09:31:58 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:31:58 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 09:31:58 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:31:58 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 09:31:58 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:31:58 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 09:31:58 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 09:31:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:31:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:31:58 INFO - Fetch https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:31:59 INFO - Expected file size: 16234496 09:32:06 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:32:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:32:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:32:06 INFO - Fetch https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:32:07 INFO - Expected file size: 16234496 09:32:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 09:32:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 09:32:14 INFO - Fetch https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 09:32:14 INFO - Expected file size: 14414089 09:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:15 INFO - trying https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:32:15 INFO - Downloading https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 09:32:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 09:32:17 INFO - Downloaded 86095553 bytes. 09:32:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:32:17 INFO - mkdir: /builds/slave/test/properties 09:32:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:32:17 INFO - Writing to file /builds/slave/test/properties/build_url 09:32:17 INFO - Contents: 09:32:17 INFO - build_url:https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:32:18 INFO - Running post-action listener: _resource_record_post_action 09:32:18 INFO - Running post-action listener: set_extra_try_arguments 09:32:18 INFO - [mozharness: 2017-01-04 17:32:18.250767Z] Finished download-and-extract step (success) 09:32:18 INFO - [mozharness: 2017-01-04 17:32:18.250919Z] Running populate-webroot step. 09:32:18 INFO - Running pre-action listener: _resource_record_pre_action 09:32:18 INFO - Running main action method: populate_webroot 09:32:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:32:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:32:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:32:18 INFO - '--tppagecycles', 09:32:18 INFO - '7'], 09:32:18 INFO - 'tests': ['tsvgm']}}, 09:32:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:32:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:32:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:32:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:32:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:18 INFO - 'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:32:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:32:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:18 INFO - 'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['damp', 'tps']}, 09:32:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:18 INFO - 'tests': ['damp', 'tps']}, 09:32:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['dromaeo_dom']}, 09:32:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:32:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['basic_compositor_video']}, 09:32:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:32:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['a11yr', 09:32:18 INFO - 'ts_paint', 09:32:18 INFO - 'tpaint', 09:32:18 INFO - 'sessionrestore', 09:32:18 INFO - 'sessionrestore_no_auto_restore', 09:32:18 INFO - 'tabpaint']}, 09:32:18 INFO - 'other-e10s': {'tests': ['a11yr', 09:32:18 INFO - 'ts_paint', 09:32:18 INFO - 'tpaint', 09:32:18 INFO - 'sessionrestore', 09:32:18 INFO - 'sessionrestore_no_auto_restore', 09:32:18 INFO - 'tabpaint']}, 09:32:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:32:18 INFO - 'ts_paint', 09:32:18 INFO - 'tpaint', 09:32:18 INFO - 'sessionrestore', 09:32:18 INFO - 'sessionrestore_no_auto_restore', 09:32:18 INFO - 'tabpaint']}, 09:32:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:32:18 INFO - 'ts_paint', 09:32:18 INFO - 'tpaint', 09:32:18 INFO - 'sessionrestore', 09:32:18 INFO - 'sessionrestore_no_auto_restore', 09:32:18 INFO - 'tabpaint']}, 09:32:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['a11yr', 09:32:18 INFO - 'ts_paint', 09:32:18 INFO - 'tpaint', 09:32:18 INFO - 'sessionrestore', 09:32:18 INFO - 'sessionrestore_no_auto_restore', 09:32:18 INFO - 'tabpaint']}, 09:32:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['a11yr', 09:32:18 INFO - 'ts_paint', 09:32:18 INFO - 'tpaint', 09:32:18 INFO - 'sessionrestore', 09:32:18 INFO - 'sessionrestore_no_auto_restore', 09:32:18 INFO - 'tabpaint']}, 09:32:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['tsvgx', 09:32:18 INFO - 'tsvgr_opacity', 09:32:18 INFO - 'tart', 09:32:18 INFO - 'tscrollx', 09:32:18 INFO - 'cart']}, 09:32:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:32:18 INFO - 'tsvgr_opacity', 09:32:18 INFO - 'tart', 09:32:18 INFO - 'tscrollx', 09:32:18 INFO - 'cart']}, 09:32:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:18 INFO - 'talos_options': ['--disable-e10s'], 09:32:18 INFO - 'tests': ['tp5o']}, 09:32:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:18 INFO - 'tests': ['tp5o']}, 09:32:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:18 INFO - 'talos_options': ['--disable-e10s', 09:32:18 INFO - '--xperf_path', 09:32:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:32:18 INFO - 'tests': ['tp5n']}, 09:32:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:18 INFO - 'talos_options': ['--xperf_path', 09:32:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:32:18 INFO - 'tests': ['tp5n']}}, 09:32:18 INFO - 'talos.zip': {'path': '', 09:32:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:32:18 INFO - Running post-action listener: _resource_record_post_action 09:32:18 INFO - [mozharness: 2017-01-04 17:32:18.270606Z] Finished populate-webroot step (success) 09:32:18 INFO - [mozharness: 2017-01-04 17:32:18.270722Z] Running create-virtualenv step. 09:32:18 INFO - Running pre-action listener: _resource_record_pre_action 09:32:18 INFO - Running main action method: create_virtualenv 09:32:18 INFO - Creating virtualenv /builds/slave/test/build/venv 09:32:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:32:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:32:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:32:18 INFO - Using real prefix '/tools/python27' 09:32:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:32:19 INFO - Installing distribute.............................................................................................................................................................................................done. 09:32:22 INFO - Installing pip.................done. 09:32:22 INFO - Return code: 0 09:32:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:22 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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:32:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:22 INFO - 'HOME': '/Users/cltbld', 09:32:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:22 INFO - 'LOGNAME': 'cltbld', 09:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:22 INFO - 'MOZ_NO_REMOTE': '1', 09:32:22 INFO - 'NO_EM_RESTART': '1', 09:32:22 INFO - 'PAGER': '/bin/cat', 09:32:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:22 INFO - 'PWD': '/builds/slave/test', 09:32:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:22 INFO - 'SHELL': '/bin/bash', 09:32:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:22 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:22 INFO - 'USER': 'cltbld', 09:32:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:22 INFO - 'XPC_FLAGS': '0x0', 09:32:22 INFO - 'XPC_SERVICE_NAME': '0', 09:32:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:32:22 INFO - Cleaning up... 09:32:22 INFO - Return code: 0 09:32:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:22 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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:32:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:22 INFO - 'HOME': '/Users/cltbld', 09:32:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:22 INFO - 'LOGNAME': 'cltbld', 09:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:22 INFO - 'MOZ_NO_REMOTE': '1', 09:32:22 INFO - 'NO_EM_RESTART': '1', 09:32:22 INFO - 'PAGER': '/bin/cat', 09:32:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:22 INFO - 'PWD': '/builds/slave/test', 09:32:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:22 INFO - 'SHELL': '/bin/bash', 09:32:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:22 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:22 INFO - 'USER': 'cltbld', 09:32:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:22 INFO - 'XPC_FLAGS': '0x0', 09:32:22 INFO - 'XPC_SERVICE_NAME': '0', 09:32:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:22 INFO - Downloading/unpacking psutil>=3.1.1 09:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:32:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:32:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:32:27 INFO - Installing collected packages: psutil 09:32:27 INFO - Running setup.py install for psutil 09:32:27 INFO - building 'psutil._psutil_osx' extension 09:32:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:32:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:32:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:32:27 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:32:27 INFO - building 'psutil._psutil_posix' extension 09:32:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:32:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:32:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:32:28 INFO - ^ 09:32:28 INFO - 1 warning generated. 09:32:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:32:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:32:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:32:28 INFO - Successfully installed psutil 09:32:28 INFO - Cleaning up... 09:32:28 INFO - Return code: 0 09:32:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:28 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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:32:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:28 INFO - 'HOME': '/Users/cltbld', 09:32:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:28 INFO - 'LOGNAME': 'cltbld', 09:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:28 INFO - 'MOZ_NO_REMOTE': '1', 09:32:28 INFO - 'NO_EM_RESTART': '1', 09:32:28 INFO - 'PAGER': '/bin/cat', 09:32:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:28 INFO - 'PWD': '/builds/slave/test', 09:32:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:28 INFO - 'SHELL': '/bin/bash', 09:32:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:28 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:28 INFO - 'USER': 'cltbld', 09:32:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:28 INFO - 'XPC_FLAGS': '0x0', 09:32:28 INFO - 'XPC_SERVICE_NAME': '0', 09:32:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:32:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:32:31 INFO - Installing collected packages: mozsystemmonitor 09:32:31 INFO - Running setup.py install for mozsystemmonitor 09:32:31 INFO - Successfully installed mozsystemmonitor 09:32:31 INFO - Cleaning up... 09:32:31 INFO - Return code: 0 09:32:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:31 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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:32:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:31 INFO - 'HOME': '/Users/cltbld', 09:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:31 INFO - 'LOGNAME': 'cltbld', 09:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:31 INFO - 'MOZ_NO_REMOTE': '1', 09:32:31 INFO - 'NO_EM_RESTART': '1', 09:32:31 INFO - 'PAGER': '/bin/cat', 09:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:31 INFO - 'PWD': '/builds/slave/test', 09:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:31 INFO - 'SHELL': '/bin/bash', 09:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:31 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:31 INFO - 'USER': 'cltbld', 09:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:31 INFO - 'XPC_FLAGS': '0x0', 09:32:31 INFO - 'XPC_SERVICE_NAME': '0', 09:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:31 INFO - Downloading/unpacking jsonschema==2.5.1 09:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:32:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:32:35 INFO - warning: no files found matching '*.txt' 09:32:35 INFO - no previously-included directories found matching 'build' 09:32:35 INFO - no previously-included directories found matching 'dist' 09:32:35 INFO - no previously-included directories found matching '.git*' 09:32:35 INFO - Installing collected packages: jsonschema, functools32 09:32:35 INFO - Running setup.py install for functools32 09:32:35 INFO - warning: no files found matching '*.txt' 09:32:35 INFO - no previously-included directories found matching 'build' 09:32:35 INFO - no previously-included directories found matching 'dist' 09:32:35 INFO - no previously-included directories found matching '.git*' 09:32:35 INFO - Successfully installed jsonschema functools32 09:32:35 INFO - Cleaning up... 09:32:35 INFO - Return code: 0 09:32:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:32: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')]} 09:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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')]} 09:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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', '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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:32: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 functools32==3.2.3-2 09:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:35 INFO - 'HOME': '/Users/cltbld', 09:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:35 INFO - 'LOGNAME': 'cltbld', 09:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:35 INFO - 'MOZ_NO_REMOTE': '1', 09:32:35 INFO - 'NO_EM_RESTART': '1', 09:32:35 INFO - 'PAGER': '/bin/cat', 09:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:35 INFO - 'PWD': '/builds/slave/test', 09:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:35 INFO - 'SHELL': '/bin/bash', 09:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:35 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:35 INFO - 'USER': 'cltbld', 09:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:35 INFO - 'XPC_FLAGS': '0x0', 09:32:35 INFO - 'XPC_SERVICE_NAME': '0', 09:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:32:35 INFO - Cleaning up... 09:32:35 INFO - Return code: 0 09:32:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:32: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')]} 09:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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')]} 09:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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', '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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:32: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 blobuploader==1.2.4 09:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:35 INFO - 'HOME': '/Users/cltbld', 09:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:35 INFO - 'LOGNAME': 'cltbld', 09:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:35 INFO - 'MOZ_NO_REMOTE': '1', 09:32:35 INFO - 'NO_EM_RESTART': '1', 09:32:35 INFO - 'PAGER': '/bin/cat', 09:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:35 INFO - 'PWD': '/builds/slave/test', 09:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:35 INFO - 'SHELL': '/bin/bash', 09:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:35 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:35 INFO - 'USER': 'cltbld', 09:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:35 INFO - 'XPC_FLAGS': '0x0', 09:32:35 INFO - 'XPC_SERVICE_NAME': '0', 09:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:38 INFO - Downloading blobuploader-1.2.4.tar.gz 09:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:32:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:32: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 09:32: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 09:32: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 09:32: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 09:32: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 09:32: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 09:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:32:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:40 INFO - Downloading docopt-0.6.1.tar.gz 09:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:32:40 INFO - Installing collected packages: blobuploader, requests, docopt 09:32:40 INFO - Running setup.py install for blobuploader 09:32:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:32:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:32:40 INFO - Running setup.py install for requests 09:32:40 INFO - Running setup.py install for docopt 09:32:40 INFO - Successfully installed blobuploader requests docopt 09:32:40 INFO - Cleaning up... 09:32:41 INFO - Return code: 0 09:32:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:41 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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:41 INFO - 'HOME': '/Users/cltbld', 09:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:41 INFO - 'LOGNAME': 'cltbld', 09:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:41 INFO - 'MOZ_NO_REMOTE': '1', 09:32:41 INFO - 'NO_EM_RESTART': '1', 09:32:41 INFO - 'PAGER': '/bin/cat', 09:32:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:41 INFO - 'PWD': '/builds/slave/test', 09:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:41 INFO - 'SHELL': '/bin/bash', 09:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:41 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:41 INFO - 'USER': 'cltbld', 09:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:41 INFO - 'XPC_FLAGS': '0x0', 09:32:41 INFO - 'XPC_SERVICE_NAME': '0', 09:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:41 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-J5QWki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:41 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-q0S1Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:41 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_HUFrJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:41 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-1YTaBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:41 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-msbRvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:41 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ZnzJAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:41 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-mN6v_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-YAwnFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-cFE_9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-DCDBoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-D1AEQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Kf9kIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qMOjQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-nIL64s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-LF9NxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:42 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-j2db4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:43 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ZCGIkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:32:43 INFO - Running setup.py install for manifestparser 09:32:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:32:43 INFO - Running setup.py install for mozcrash 09:32:43 INFO - Running setup.py install for mozdebug 09:32:43 INFO - Running setup.py install for mozdevice 09:32:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:32:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:32:43 INFO - Running setup.py install for mozfile 09:32:44 INFO - Running setup.py install for mozhttpd 09:32:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for mozinfo 09:32:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for mozInstall 09:32:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for mozleak 09:32:44 INFO - Running setup.py install for mozlog 09:32:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for moznetwork 09:32:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:32:44 INFO - Running setup.py install for mozprocess 09:32:45 INFO - Running setup.py install for mozprofile 09:32:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Running setup.py install for mozrunner 09:32:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Running setup.py install for mozscreenshot 09:32:45 INFO - Running setup.py install for moztest 09:32:45 INFO - Running setup.py install for mozversion 09:32:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:32:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:32:45 INFO - Cleaning up... 09:32:45 INFO - Return code: 0 09:32:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:45 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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:32:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:45 INFO - 'HOME': '/Users/cltbld', 09:32:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:45 INFO - 'LOGNAME': 'cltbld', 09:32:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:45 INFO - 'MOZ_NO_REMOTE': '1', 09:32:45 INFO - 'NO_EM_RESTART': '1', 09:32:45 INFO - 'PAGER': '/bin/cat', 09:32:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:45 INFO - 'PWD': '/builds/slave/test', 09:32:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:45 INFO - 'SHELL': '/bin/bash', 09:32:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:45 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:45 INFO - 'USER': 'cltbld', 09:32:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:45 INFO - 'XPC_FLAGS': '0x0', 09:32:45 INFO - 'XPC_SERVICE_NAME': '0', 09:32:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-gT3sWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ZW6Ue9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hvBjtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-I5NCPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-7h4Awe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-SNgOTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-2uWddc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EL6riN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-CWgw3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hjIiJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-2Ntqck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ozADlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-yKyUhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-zlkYh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_qUSh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-7LjMcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:48 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-TaAup5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:32:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:50 INFO - Downloading blessings-1.6.tar.gz 09:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:32:50 INFO - Installing collected packages: blessings 09:32:50 INFO - Running setup.py install for blessings 09:32:51 INFO - Successfully installed blessings 09:32:51 INFO - Cleaning up... 09:32:51 INFO - Return code: 0 09:32:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:51 INFO - Reading from file tmpfile_stdout 09:32:51 INFO - Current package versions: 09:32:51 INFO - blessings == 1.6 09:32:51 INFO - blobuploader == 1.2.4 09:32:51 INFO - docopt == 0.6.1 09:32:51 INFO - functools32 == 3.2.3-2 09:32:51 INFO - jsonschema == 2.5.1 09:32:51 INFO - manifestparser == 1.1 09:32:51 INFO - mozInstall == 1.12 09:32:51 INFO - mozcrash == 0.17 09:32:51 INFO - mozdebug == 0.1 09:32:51 INFO - mozdevice == 0.48 09:32:51 INFO - mozfile == 1.2 09:32:51 INFO - mozhttpd == 0.7 09:32:51 INFO - mozinfo == 0.9 09:32:51 INFO - mozleak == 0.1 09:32:51 INFO - mozlog == 3.4 09:32:51 INFO - moznetwork == 0.27 09:32:51 INFO - mozprocess == 0.23 09:32:51 INFO - mozprofile == 0.28 09:32:51 INFO - mozrunner == 6.12 09:32:51 INFO - mozscreenshot == 0.1 09:32:51 INFO - mozsystemmonitor == 0.3 09:32:51 INFO - moztest == 0.7 09:32:51 INFO - mozversion == 1.4 09:32:51 INFO - psutil == 3.1.1 09:32:51 INFO - requests == 1.2.3 09:32:51 INFO - wsgiref == 0.1.2 09:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:51 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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:51 INFO - 'HOME': '/Users/cltbld', 09:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:51 INFO - 'LOGNAME': 'cltbld', 09:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:51 INFO - 'MOZ_NO_REMOTE': '1', 09:32:51 INFO - 'NO_EM_RESTART': '1', 09:32:51 INFO - 'PAGER': '/bin/cat', 09:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:51 INFO - 'PWD': '/builds/slave/test', 09:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:51 INFO - 'SHELL': '/bin/bash', 09:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:51 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:51 INFO - 'USER': 'cltbld', 09:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:51 INFO - 'XPC_FLAGS': '0x0', 09:32:51 INFO - 'XPC_SERVICE_NAME': '0', 09:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:32:52 INFO - Cleaning up... 09:32:52 INFO - Return code: 0 09:32:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:32: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')]} 09:32:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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')]} 09:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:52 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 0x103723490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103699570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb868497830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, '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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:32:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:52 INFO - 'HOME': '/Users/cltbld', 09:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:52 INFO - 'LOGNAME': 'cltbld', 09:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:52 INFO - 'MOZ_NO_REMOTE': '1', 09:32:52 INFO - 'NO_EM_RESTART': '1', 09:32:52 INFO - 'PAGER': '/bin/cat', 09:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:52 INFO - 'PWD': '/builds/slave/test', 09:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:52 INFO - 'SHELL': '/bin/bash', 09:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:32:52 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:52 INFO - 'USER': 'cltbld', 09:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:52 INFO - 'XPC_FLAGS': '0x0', 09:32:52 INFO - 'XPC_SERVICE_NAME': '0', 09:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:32:52 INFO - Cleaning up... 09:32:52 INFO - Return code: 0 09:32:52 INFO - Running post-action listener: _resource_record_post_action 09:32:52 INFO - Running post-action listener: _start_resource_monitoring 09:32:52 INFO - Starting resource monitoring. 09:32:52 INFO - [mozharness: 2017-01-04 17:32:52.471184Z] Finished create-virtualenv step (success) 09:32:52 INFO - [mozharness: 2017-01-04 17:32:52.472355Z] Running install step. 09:32:52 INFO - Running pre-action listener: _resource_record_pre_action 09:32:52 INFO - Running main action method: install 09:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:52 INFO - Reading from file tmpfile_stdout 09:32:52 INFO - Detecting whether we're running mozinstall >=1.0... 09:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:32:52 INFO - Reading from file tmpfile_stdout 09:32:52 INFO - Output received: 09:32:52 INFO - Usage: mozinstall [options] installer 09:32:52 INFO - Options: 09:32:52 INFO - -h, --help show this help message and exit 09:32:52 INFO - -d DEST, --destination=DEST 09:32:52 INFO - Directory to install application into. [default: 09:32:52 INFO - "/builds/slave/test"] 09:32:52 INFO - --app=APP Application being installed. [default: firefox] 09:32:52 INFO - mkdir: /builds/slave/test/build/application 09:32:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:33:15 INFO - Reading from file tmpfile_stdout 09:33:15 INFO - Output received: 09:33:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:33:15 INFO - Running post-action listener: _resource_record_post_action 09:33:15 INFO - [mozharness: 2017-01-04 17:33:15.488062Z] Finished install step (success) 09:33:15 INFO - [mozharness: 2017-01-04 17:33:15.488205Z] Running run-tests step. 09:33:15 INFO - Running pre-action listener: _resource_record_pre_action 09:33:15 INFO - Running main action method: run_tests 09:33:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:33:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:33:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:33:15 INFO - 2017-01-04 09:33:15.504 screenresolution[2512:19276] starting screenresolution argv=screenresolution get 09:33:15 INFO - 2017-01-04 09:33:15.507 screenresolution[2512:19276] Display 0: 1600x1200x32@60 09:33:15 INFO - 2017-01-04 09:33:15.513 screenresolution[2513:19279] starting screenresolution argv=screenresolution list 09:33:15 INFO - Available Modes on Display 0 09:33:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:33:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:33:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:33:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:33:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:33:15 INFO - Intel Iris: 09:33:15 INFO - Chipset Model: Intel Iris 09:33:15 INFO - Type: GPU 09:33:15 INFO - Bus: Built-In 09:33:15 INFO - VRAM (Dynamic, Max): 1536 MB 09:33:15 INFO - Vendor: Intel (0x8086) 09:33:15 INFO - Device ID: 0x0a2e 09:33:15 INFO - Revision ID: 0x0009 09:33:15 INFO - Displays: 09:33:15 INFO - Display: 09:33:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:33:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:33:15 INFO - Display Serial Number: 1600x1200 60 09:33:15 INFO - Main Display: Yes 09:33:15 INFO - Mirror: Off 09:33:15 INFO - Online: Yes 09:33:15 INFO - Rotation: Supported 09:33:15 INFO - Return code: 0 09:33:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:33:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:33:15 INFO - Python 2.7.3 09:33:15 INFO - Return code: 0 09:33:15 INFO - grabbing minidump binary from tooltool 09:33:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:15 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 0x103537d30>, '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 0x10339ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10352fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:33:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:33:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:33:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:33:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:33:15 INFO - Return code: 0 09:33:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:33:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:33:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:33:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:33:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:33:15 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/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:33:15 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/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:33:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rRzW7Cx5E1/Render', 09:33:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:15 INFO - 'HOME': '/Users/cltbld', 09:33:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:15 INFO - 'LOGNAME': 'cltbld', 09:33:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:33:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:33:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:15 INFO - 'MOZ_NO_REMOTE': '1', 09:33:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:33:15 INFO - 'NO_EM_RESTART': '1', 09:33:15 INFO - 'PAGER': '/bin/cat', 09:33:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:15 INFO - 'PWD': '/builds/slave/test', 09:33:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:33:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:15 INFO - 'SHELL': '/bin/bash', 09:33:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8KFl7Yk0wk/Listeners', 09:33:15 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 09:33:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:15 INFO - 'USER': 'cltbld', 09:33:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:15 INFO - 'XPC_FLAGS': '0x0', 09:33:15 INFO - 'XPC_SERVICE_NAME': '0', 09:33:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:15 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/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:33:16 INFO - mozversion application_buildid: 20170104081038 09:33:16 INFO - mozversion application_changeset: b0bc6095804be7340749e6011b8d6043e5b320ec 09:33:16 INFO - mozversion application_display_name: Firefox 09:33:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:33:16 INFO - mozversion application_name: Firefox 09:33:16 INFO - mozversion application_remotingname: firefox 09:33:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:33:16 INFO - mozversion application_vendor: Mozilla 09:33:16 INFO - mozversion application_version: 51.0 09:33:16 INFO - mozversion platform_buildid: 20170104081038 09:33:16 INFO - mozversion platform_changeset: b0bc6095804be7340749e6011b8d6043e5b320ec 09:33:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:33:16 INFO - mozversion platform_version: 51.0 09:33:16 INFO - using testdate: 1483551196 09:33:16 INFO - actual date: 1483551196 09:33:16 INFO - starting webserver on 'localhost:53904' 09:33:16 INFO - SUITE-START | Running 2 tests 09:33:16 INFO - TEST-START | dromaeo_css 09:33:16 INFO - operating with platform_type : mac_ 09:33:16 INFO - Initialising browser for dromaeo_css test... 09:33:16 INFO - TEST-INFO | started process 2523 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpcjhBbg/profile http://localhost:53904/getInfo.html) 09:33:18 INFO - PROCESS | 2523 | __metrics Screen width/height:1600/1200 09:33:18 INFO - PROCESS | 2523 | colorDepth:24 09:33:18 INFO - PROCESS | 2523 | Browser inner width/height: 1024/689 09:33:18 INFO - PROCESS | 2523 | __metrics 09:33:18 INFO - PROCESS | 2523 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:33:18 INFO - PROCESS | 2523 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:33:19 INFO - TEST-INFO | 2523: exit 0 09:33:19 INFO - Browser initialized. 09:33:19 INFO - Running cycle 1/1 for dromaeo_css test... 09:33:19 INFO - TEST-INFO | started process 2526 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpcjhBbg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:31 INFO - PROCESS | 2526 | RSS: Main: 168689664 09:33:31 INFO - PROCESS | 2526 | 09:34:22 INFO - PROCESS | 2526 | 1483551262619 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:36:32 INFO - PROCESS | 2526 | Cycle 1(1): loaded http://localhost:53904/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53904/tests/dromaeo/cssquery-ext.html) 09:36:33 INFO - PROCESS | 2526 | RSS: Main: 291598336 09:36:33 INFO - PROCESS | 2526 | 09:39:36 INFO - PROCESS | 2526 | Cycle 1(1): loaded http://localhost:53904/tests/dromaeo/cssquery-ext.html (next: http://localhost:53904/tests/dromaeo/cssquery-jquery.html) 09:39:36 INFO - PROCESS | 2526 | RSS: Main: 299929600 09:39:36 INFO - PROCESS | 2526 | 09:42:39 INFO - PROCESS | 2526 | Cycle 1(1): loaded http://localhost:53904/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53904/tests/dromaeo/cssquery-mootools.html) 09:42:39 INFO - PROCESS | 2526 | RSS: Main: 299122688 09:42:39 INFO - PROCESS | 2526 | 09:45:42 INFO - PROCESS | 2526 | Cycle 1(1): loaded http://localhost:53904/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53904/tests/dromaeo/cssquery-prototype.html) 09:45:42 INFO - PROCESS | 2526 | RSS: Main: 269500416 09:45:42 INFO - PROCESS | 2526 | 09:48:45 INFO - PROCESS | 2526 | Cycle 1(1): loaded http://localhost:53904/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53904/tests/dromaeo/cssquery-yui.html) 09:48:46 INFO - PROCESS | 2526 | RSS: Main: 258514944 09:48:46 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | Cycle 1(1): loaded http://localhost:53904/tests/dromaeo/cssquery-yui.html (next: http://localhost:53904/tests/dromaeo/cssquery-dojo.html) 09:51:49 INFO - PROCESS | 2526 | RSS: Main: 307200000 09:51:49 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | __start_tp_report 09:51:49 INFO - PROCESS | 2526 | _x_x_mozilla_page_load 09:51:49 INFO - PROCESS | 2526 | _x_x_mozilla_page_load_details 09:51:49 INFO - PROCESS | 2526 | |i|pagename|runs| 09:51:49 INFO - PROCESS | 2526 | |0;dojo.html;2362.503874186421;2893.146521776076;2899.753520950718;2920.269932516871;2967.9703202967976;4795.376601041864;4855.878603034917;4859.465458799529;4869.074875773601;4942.728149951751;4847.515248475151;4848.612111031118;4850.563449289563;4901.558859702626;4916.459189489162;4462.286034234523;4490.438695163105;4500.459944806611;4533.365328853963;4605.986682929767;4332.545082766312;4427.800748966288;4445.51099379068;4535.183666939949;4568.314752787075;4547.658925580578;4567.177907976563;4567.794449249792;4585.2663573828195;4590.288505281686;4546.227141385973;4562.384078149441;4566.771661416932;4567.36056952027;4582.702124361923;4498.79755411004;4515.7516336601475;4520.59314661682;4531.297648864432;4532.957419793466;4799.424069111729;4814.7351895645725;4864.683795553277;4889.437714662824;4904.4605093439695;5179.689218646856;5179.689218646856;5200.323957885451;5299.231611416323;5301.045811753883;5365.195220716884;5365.731713414314;5379.1662292344745;5406.053940560386;5435.157550579666;4869.610431165498;4936.728479933567;4939.802407903644;5033.899322013539;5063;4381.6494680425485;5319.122344813062;5387.515123638813;5440.483154100354;5459.727013649302;5234.345706786652;5238.5547228486;5265.578753699695;5267.051930652443;5300.9734951325;5150.175971844487;5215.634905556651;5328.3605967284175;5335.0663633863915;5381.408045115034;5139.177731562932;5174.301469301671;5203.765830537635;5240.711760853113;5309.761060752276;5258.631895767336;5269.868253293698;5278.683279003272;5293.417724050351;5313.282706834528;4362.585554372393;4373.715708478939;4379.802908869108;4384.4081049058605;4423.889402764957;3815.313243616176;3866.903327416781;3897.512810898638;3923.8822835314986;3925.4896863407575;4610.331501932268;4663.044075964487;4705.882352941136;4763.857084287477;4789.664723469394;5314.3091398117995;5332.760025798849;5338.279332290091;5368.114261146867;5379.1662292344745;4586.7018643788715;4754.7147171169145;4756.833510827105;4785.688930219525;4810.63920205986;4580.4045474088825;4617.145828021759;4649.790759415834;4662.4405071390975;4721.858344249609;5514.283143191359;5539.335279766454;5545.500904918576;5560.527355174855;5574.16387541872;5256.9211461827335;5272.736363181749;5300.522952934254;5321.866953326043;5352.678839269656;4512.90974180508;5259.631825772312;5354.919676204782;5537.36320323168;5544.390117087198;5094.541491265655;5165.974170129125;5182;5249.921251181312;5292.470752924677;5245.763940622604;5297.364316282071;5307.3100496936695;5314.734263286898;5327.147656375117;5183.714895680774;5201.141811601044;5201.557867581147;5204.037253108036;5205.765740541609;4959.057779021974;4970.701757894393;5054.671446355975;5077.1876499759865;5122.257272695364;5096.362954630671;5128.820491282787;5142.948570514247;5143.6399452037995;5167.276581278549;5189.091908916;5193.37679478465;5202.3497062867145;5212.113940630026;5250.1074817281915;4919.0899683558655;4973.502649735142;4975.676581022222;4991.6006719463085;5083.87290317745 09:51:49 INFO - PROCESS | 2526 | |1;ext.html;430.42538211487664;448.5716141085262;466.92996050592404;469.7604221846859;499.35333742803067;31296.687033129663;40914.38628420572;42055.57944420555;42090.36864447032;44144.779276103654;44082.33876491847;44276;44321;44324.77837610807;44395.11209775811;6886.931130688704;8560.315174786003;9724.465154416486;9962.651307204213;9982.151517121054;8992.595333210022;9181.357304988653;9195.678151264707;9201.907980920172;11785.941070294679;11804.055675545937;12071.818922716166;12078.516859325617;12106.576269830557;12123.393830308449;12057.819132713017;12160.574379896705;12177.634670959886;12178.81731774024;12182.329971851545;11827.112966527546;11936.522539098425;12023.63929082129;12048.457819398149;12052.156349055569;41840.79079604613;41875.58124418747;41894.79052604748;41928.37107443391;41934.790326048176;3511.3328467591077;3525.435930251173;3972.3842804365368;3987.920241595181;4058.0260737422805;29249.41501169986;29912.401751965055;30240.941567045047;32007.51988720171;32275;3310.3710295043866;3350.229447227127;3407.1822762537063;3458.3774920514297;3469.271452994874;516695.24957125675;519592;521746;521912;523264;34188;34797;34913.30173396569;35897.46153807695;60167.699161503915;35592.82203588966;35610.10974725601;35673.46489802655;35766.28467430689;35782.92651220468;15070.09579425238;15184.46854360092;15223.923880380527;15226.086434813946;15261.847381526042;11948.76102477963;11974.64076077719;11977.461014254379;12010.919017288486;12206.755864882653;7288.453365997571;7439.330460258603;7439.962800186073;7561.810954726176;7844.294013538809;3128.6402063762957;3213.53403756454;3283.6387997320276;3318.784279021856;3340.5657264555452;824.686619084756;866.9869951950726;883.8276536075532;900.8918929728475;914.9359544831931;30295;30440.086797396114;30454.238644034078;30456.69543304583;31169;895.8566629339274;977.359829311802;1001.3791449301334;1037.2998226197437;1078.245228340165;15477.458288959833;15680.92159539195;15792.60518487047;15807.604809879846;16107.919460402858;663661;672650;673686;674801;674873;49343.506564933894;58652.41347586555;59047.11429328564;59133;59171;1196.7367179220557;1200.4057991294364;1280.2510531339271;1295.902807289457;1342.4764341906664;30043.849780750956;31396.215094622818;31456;31540;32054.519182211818;49143;49810.25284620806;49829.75085124551;49859.75070124626;50133.99732005412;554731;559313;559866.2006689941;568525;775421;26554.734452655226;26838.329041774112;26876.59685104764;26916.596251056642;26921;46015.76992115018;46291.76854115708;46497.53502464932;46708.766456167505;46861.2970805445;31311.84344078265;34729.958101257005;37834.81082594569;38450.807745961094;38727.6127238724;31241.37517249597;33598.83200583982;36866.44700329444;37134.8143259282;37411.812940935124;36913.261734764616;37223.81388093042;37601.4359784598;37690.869273921824;37967.240655186186;31523.054308370785;33478.83260583682;34107.14732131716;34246.48630270598;34337.96986090421;25618.74381256238;25632.615510767726;25802.61296080523;26051.869740651935;26313.736862631893 09:51:49 INFO - PROCESS | 2526 | |2;jquery.html;343.76930588493195;343.7816986213754;353.60749567979536;363.892651667758;381.67938931297704;19644.508887277807;20418.795812041895;20768.89615551924;21646.91765411729;21844.563108737857;15108.942374033822;17204.483865484024;19891.801081989175;20088.995550222502;20466;5810.535157187436;5897.498712609423;6170.043643235305;6181.59819611726;6194.163787888622;5968.641881487103;6051.788187413442;6076.756929722806;6099.756009759604;6111.838750637365;5728.283964504437;5997.460228579427;6058.424449677295;6068.6662233577135;6071.089336599523;6007.128966299884;6035.486983606399;6079.635221886679;6080.027195648675;6102.20671312731;5212.817551385683;5228.79084836606;5278.683279003234;5282;5307.442718514578;25812.838422270856;25903.870480647663;25916.481670366677;25921.22236332913;26216.213513594623;2002.6094911492264;2237.8881055947136;2363.2910126962015;2402.9639555406507;2426.5389575980507;10062.094411503;10064.547095380653;10156.796864062677;10205.183585313158;10300.948495257475;1704.6505466379363;1791.7312403139504;1832.5876677747533;1871.1299245850744;1952.1898412158937;110225;116549.41725291403;124120;125442;125576;9230.261579073658;9618.711438656852;9932.80134397308;9979.351342162736;10047.296689231829;12228.327441990776;12291.754164916652;12295.508179672714;12314.50741970329;12366.690832729253;7755.95294635217;8099.311558517474;8116.188381161836;8205.671773129128;8207.466514676527;7084.362407383257;8610.913890861137;8611.741647750578;8772.91227087721;8777.253933415732;3842.5965273646425;3862.633049860259;3884.592117827644;3920.941185882214;3966.345552983725;1542.537238828347;1744.145368769294;1839.3378383781828;1895.109298629642;1896.2699360746212;625.5464788028643;699.9055127557715;820.3929092471528;867.9088695686988;882.3911501064246;20142.69785953212;21182.15271389158;21371.67942480864;21409.143634254455;21697.56604867894;841.9158084191654;853.0914575976565;870.2037635563515;885.9158379954023;932.5523748600709;10447.373157610567;11131.944340278247;11158.944205279084;11177.44112794357;11193.720156995978;616230;628545;631650;633991;645169;106765.46617266865;131900.8314043878;132933;135013.3249333747;137500;888.7511496780912;938.9765255868522;957.2342126299072;958.750724811554;961.8845738511424;18301.63396732031;18731.344402946375;19022.858628482336;19049.85700857953;19069.90465047666;54371.72814135904;54621.72689136529;55869;56239.15641265466;56373.71813141072;73489.6325518369;76447.61776191083;80033.7978031426;80523;80745.59627201826;6561.507886908405;6989.860202795814;7160.856782864209;7486.43851711113;7500.249975002456;11533.942330288295;12863.807042894552;13155.276459794803;13226.140300880412;13605;6386.105945167773;10359.689209323733;11426.314421134759;11551.075913927034;11698.824517632413;8364.494391009677;10429.26995110335;11023.61417350389;11196.216264861381;11669.241499302518;11208.10335173168;11355.205135640756;11432.388776995605;11436.94281528587;11747.765044699228;7417.258274172539;7466.626668666654;7527.494501099693;7557.732680196029;7781.182975787461;9551.665691700757;11290.209685322277;11315.603953861575;11426.2001659883;11497.252678575866 09:51:49 INFO - PROCESS | 2526 | |3;mootools.html;1432.2767002663657;1440.2870579063363;1585.2390852390852;1738.539287088921;1784.803671596125;8266.917330826689;8801.16388943051;8828.69099581514;8838.160374764395;8841.823163536741;7720.266574675411;8063.1130575636635;8412.158784121584;8662.393632445732;8692.130786921305;1988.2444671025025;2296.724393072825;2323.4307594639336;2331.6735657008035;2332.5684748321614;2275.7269127704617;2289.885505724707;2323.9419014524688;2324.662923876037;2324.9883750581225;2312.3409828198965;2319.3273950554403;2329.77867102625;2336.5560543496767;2340.473393486469;2294.1511640692966;2296.977030229693;2298.5977453945493;2301.275098344033;2301.976980230193;2298.678185054094;2305.619572770491;2307.284741730069;2308.284431826139;2314.7685231476885;8702.738917832476;8914.153155450222;8914.910850891472;8920.330975176823;8932.419392739452;5175.922361164586;5348.91976620351;5349.759260833272;5350.812721554766;5354.464553544653;3343.632200457948;3401.523786669868;3511.5434993450945;3518.1732292911147;3520.2431477232262;1036.2331874412955;1037.273908264218;1038.0709265207597;1057.9947100264449;1061.6549621373008;11531.76936461266;11661.24201926872;11663.12526560503;11685.474153663106;11699.473523691455;4449.310356894686;4636.258198688193;4645.094206629742;4771.021940502189;4794.976025119852;9907.603695852229;9913.702588922344;9913.752156196153;9922.156616687662;9947.701568952943;5211.635185536976;5220.347456567929;5221.895562088813;5223.033738758342;5233.319668443154;3767.585565587783;4236.76697781625;4381.21138195128;4802.783874725646;4805.903881922342;3872.6320999505;3891.2217556488818;3949.6247856454165;3974.18529201513;3977.5624681285035;1221.3770976801895;1248.1574936917543;1248.3383806582526;1255.9623211303676;1264.917780344293;788.8461749958818;792.6195426195459;803.658445160816;841.2260720137489;849.5412477262332;5456.809011684572;5616.213730077792;5717.742701578356;5725.656460612377;5847.976604094267;385.1238432565879;396.11073140798516;402.2919661395964;405.2968100345897;406.89420750604467;3806;3840.9423858642144;3865.7873816940337;3879.6120387960977;3887.688984881203;10283.588656453658;10863.891361086446;10867.565297388173;10893.945530272456;10903.127749779998;10808.56765729362;10812.621558245424;10817.513211905325;10819.891801081889;10826.079783218358;7854.253845884632;7998.600069996477;8120.878186827203;8142.796430089295;8148.511089334659;5549.195366671891;5678.375378708256;5748.626339287933;5841.50347220491;5891.617044892068;9608.22620052875;9695.321327507289;9695.9030409695;9871.802563948537;10001.14990225839;9879.061489158807;10091.646792362231;10169.898301016896;10223.7444063899;10288.691339259834;6637.6349300788925;6690.330966903465;7288.489805713549;7339.486235963432;7425.108986921604;8274.462159959583;8442.577871106543;8454;8455.239028487465;8456.788580285542;7341.229170936975;7380.335769780745;7441.627918604157;7495.287947644965;7567.129780075362;7159.176694680178;7294.63526823646;7345.632718364167;7423.331900129015;7457.105147382349;6930.3762661360715;6949.166100067822;7031.824204394931;7035.085438892911;7037.753678621224;4918.286848407032;4932.482089380564;4937.407511098691;4943.20908654628;4955.4301255356095;4804.135255653876;4939.357883475269;4957.206846904478;4970.055689418999;5108.182690769459 09:51:49 INFO - PROCESS | 2526 | |4;prototype.html;277.46587818449484;284.83479581842533;286.8623060930754;292.2955676818867;294.6964626434773;3683.889483315498;3776.9811150944274;4067.6135767102114;4086.7547947123194;4099.3645984872355;3608.7654302470323;3737.42069979153;3933.3706606942897;3987.7607343559407;4013.71561100448;3043.8281261714287;3169.9049028529125;3239.805611663308;3242.902712918616;3252.219467327836;3057.6330840299083;3215.742740580748;3223.9844448998606;3260.1686569924636;3272.8527216275206;2816.816906901055;3235.724963378104;3254.5931758530182;3256.1045712429154;3267.6895694908944;2800.2436717167457;2849.173739615509;2962.5335458947743;3224.0811368760023;3258.8696452141885;2865.627468429091;2884.120343295294;2886.379428422878;2887.3936473340623;2887.7112288770954;3862.1310205203886;3870.3613903705855;3878.991462219815;3887.222555488885;3955.9802200989097;3185.2833112549724;3669.834857431422;3684.0290444612274;3702.4631428442995;3726.7763934164036;3214.324991751735;4286.5284818669925;4298.312270036779;4329.372241025065;4350.151720414488;2694.154504164634;3144.3868445653106;3156.7632427568024;3161.7470602351755;3165.9366812663848;3850.5186851643543;4426.358178064195;4470.731756094645;4498.008824131981;4524.864254072416;3179.078067360486;3189.681031896792;3191.973097083369;3198.784461904508;3200.9519857202163;2694.757471827545;3051.969480305213;3226.274088330154;3228.790128641631;3238.417084924689;3632.927341453209;3643.708503319781;3656.3966945454235;3676.4117741161285;3677.9150150705605;4301.591348821857;4330.523642399334;4333.349997500401;4337.544557821447;4350.086481838786;958.6740508227236;1056.2553399853084;1109.628274528026;1110.4780753046055;1111.8610173728284;626.8620903401245;673.9292374300628;702.6964979962097;705.7282946065699;734.2327268004948;3314.5691060162026;3387.017764848216;3462.4460086386057;3893.5911729268587;3965.722399432012;3490.877819276313;3886.3198940185353;4469.553044695569;4481.820727170942;4491.258942274488;3858.401947698111;3871.787051712183;3888.7723189753;3900.2199560088097;3900.6099390061336;1778.8487978521712;2216.5592364962904;2234.162189179058;2263.5925533403815;2296.1401264975425;4473.5526447355005;4561.407017087758;4564.497905230422;4609.700369475973;4673.883152921204;3774.452704357853;4506.459224893034;4526.683132180781;4559.338895860082;4606.447226332794;3653.392507296781;4320.351947207944;4446.132624297868;4518.638508919278;4539.818407263672;3564.7326450515798;4282.593153650399;4351.170969546779;4380.6057454829215;4388.489208633017;4346.934795978126;4354.325079612665;4385.671074669349;4416.933745993877;4421;3753.1476078180235;4503.887402814956;4510.458744950627;4530.501644819134;4538.818447262072;2702.90539831105;2905.535114381689;2979.7616190705126;3108.5492603572807;3134.200778801391;3753.868614598476;4066.898327541835;4381.430414046154;4421.955780442155;4453.710508816916;4277;4329.588689074533;4372.475302963665;4403.889902752328;4416.6025057744955;3856.3636999895825;3923.8822835314986;3968.4245784361683;4272.807723652505;4275.529691733969;3805.276997370491;3967.0875698588907;4044.4944381952255;4083.6528895044253;4313.870583882489;3566.839492222908;3577.157763751722;3925.92148157041;4291.463567054118;4356.085222103394;3760.082357997496;3949;3991.9062726387283;4028.798560072043;4161.3549899765585 09:51:49 INFO - PROCESS | 2526 | |5;yui.html;609.4545381883214;676.489250615785;743.3050098158219;775.7432958607054;803.4335793265747;15584.376624935003;16407.425740099097;16422.589435264123;16438.75341869873;16475.83524164758;12623.305718185497;14482.710345793077;14787.482438114668;14791.778123328158;16285.430009949654;823.7487566292278;848.5672307123381;867.5271976772674;868.6178081644088;870.0212261206143;867.9783005424852;868.3357231717741;873.0570983338012;874.3922973533397;876.7808047988003;913.8811954445946;916.6791622931954;917.8944421391525;922.2161163011453;922.7231830450871;910.1762904571374;910.4946754551221;914.2959920860966;915.3363811236833;916.9495677737722;681.3595220492771;681.8602186551794;682.222266616058;683.3815397065614;684.565301033847;14939.551813445614;15233;15255.23723813816;15316;15875.126868022255;13825.585232443043;14564.586320780692;14581.416743330361;14934.477293294682;15156.848431515655;11395.886041139649;11603.419829008515;11665.475053622522;11828.763424731544;12127.696807579881;3764.4729737836724;3790.824844298249;3809.4095415210686;3861.2084522672785;3953.5453422856453;116539;121214.18178727326;123967.38016309951;124238;131737.34131329283;10473.266871318934;10813.729656758644;11177.83233251502;11228.27016243958;11337.093032557375;22568.887155564447;22636.773632263466;22663.20678776235;22670.65994010091;22845;7911.841763164704;7931.2068793120225;7975.960120199478;8111.553864537507;8331.625076871554;4392.407025051641;4441.289393696993;4554.294084416921;4562.7262364257585;4566.520515345907;3513.771604845677;3683.8894833154513;3690.317291301118;3691.390920498088;3692.8522859085874;560.1262031231211;561.2843624378868;565.2482198675751;567.2823877794593;568.5536853570011;464.6004436184878;489.05856226763336;534.0533457747251;547.8329109621603;552.2847911954029;9092.454452732858;9341.766455838659;9415.105564971316;9561.665341713007;9578.47318397481;570.3469527391114;581.9534437245095;584.2113147251262;590.7636945221859;591.9585629005928;4259.7231179973205;4260.169266992969;4276.72201306914;4320.783960801948;4359.12817436514;103929;111125.88874111156;153227;153658;163083.1845840763;127530.72469275373;129308.70691293155;131545.0268245977;132482;133030.66969330376;87727.12272877317;94449.5277523608;94708.52645736728;102476.97523024824;105382.4730876356;8870.15733505316;8981;9428.915674697497;9723.222142228746;9762.804743905224;55243.447565525435;55442.16836747533;56738.716306419854;56929;56941.71529142328;90105.09894900967;90356;91306.08693912976;91582;93608;10927.289726167774;12308.323042232763;12503.812442813183;12526.624201273977;13417.731645367234;13520.459181633018;13840.031197816057;14813.629659258604;15109.54671359861;15522.767158492858;13900;14475.348609312396;14900.105993639983;15092.622684432978;15112.39550418015;13632.65918352049;13796.241206733726;13927.442902283796;13927.86072139306;14903.403863845324;13712.725745484835;13841.653958651113;13917.512887048904;14116.647083822987;14706.044107133002;11119.221654484109;11391.772164556829;11405.828912566152;11675.416229188337;11766.705832354259;10530.894256102998;11132.443377831238;11291.943540282246;11386.658400248005;11545.538178472767 09:51:49 INFO - PROCESS | 2526 | __end_tp_report 09:51:49 INFO - PROCESS | 2526 | __start_cc_report 09:51:49 INFO - PROCESS | 2526 | _x_x_mozilla_cycle_collect,1080 09:51:49 INFO - PROCESS | 2526 | __end_cc_report 09:51:49 INFO - PROCESS | 2526 | __startTimestamp1483552309729__endTimestamp 09:51:49 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | ------- Summary: start ------- 09:51:49 INFO - PROCESS | 2526 | Number of tests: 6 09:51:49 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | [#0] dojo.html Cycles:165 Average:4900.91 Median:5054.67 stddev:532.64 (10.5%) stddev-sans-first:495.66 09:51:49 INFO - PROCESS | 2526 | Values: 2362.5 2893.1 2899.8 2920.3 2968.0 4795.4 4855.9 4859.5 4869.1 4942.7 4847.5 4848.6 4850.6 4901.6 4916.5 4462.3 4490.4 4500.5 4533.4 4606.0 4332.5 4427.8 4445.5 4535.2 4568.3 4547.7 4567.2 4567.8 4585.3 4590.3 4546.2 4562.4 4566.8 4567.4 4582.7 4498.8 4515.8 4520.6 4531.3 4533.0 4799.4 4814.7 4864.7 4889.4 4904.5 5179.7 5179.7 5200.3 5299.2 5301.0 5365.2 5365.7 5379.2 5406.1 5435.2 4869.6 4936.7 4939.8 5033.9 5063.0 4381.6 5319.1 5387.5 5440.5 5459.7 5234.3 5238.6 5265.6 5267.1 5301.0 5150.2 5215.6 5328.4 5335.1 5381.4 5139.2 5174.3 5203.8 5240.7 5309.8 5258.6 5269.9 5278.7 5293.4 5313.3 4362.6 4373.7 4379.8 4384.4 4423.9 3815.3 3866.9 3897.5 3923.9 3925.5 4610.3 4663.0 4705.9 4763.9 4789.7 5314.3 5332.8 5338.3 5368.1 5379.2 4586.7 4754.7 4756.8 4785.7 4810.6 4580.4 4617.1 4649.8 4662.4 4721.9 5514.3 5539.3 5545.5 5560.5 5574.2 5256.9 5272.7 5300.5 5321.9 5352.7 4512.9 5259.6 5354.9 5537.4 5544.4 5094.5 5166.0 5182.0 5249.9 5292.5 5245.8 5297.4 5307.3 5314.7 5327.1 5183.7 5201.1 5201.6 5204.0 5205.8 4959.1 4970.7 5054.7 5077.2 5122.3 5096.4 5128.8 5142.9 5143.6 5167.3 5189.1 5193.4 5202.3 5212.1 5250.1 4919.1 4973.5 4975.7 4991.6 5083.9 09:51:49 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | [#1] ext.html Cycles:180 Average:71072.74 Median:35641.79 stddev:161984.01 (454.5%) stddev-sans-first:162351.58 09:51:49 INFO - PROCESS | 2526 | Values: 430.4 448.6 466.9 469.8 499.4 31296.7 40914.4 42055.6 42090.4 44144.8 44082.3 44276.0 44321.0 44324.8 44395.1 6886.9 8560.3 9724.5 9962.7 9982.2 8992.6 9181.4 9195.7 9201.9 11785.9 11804.1 12071.8 12078.5 12106.6 12123.4 12057.8 12160.6 12177.6 12178.8 12182.3 11827.1 11936.5 12023.6 12048.5 12052.2 41840.8 41875.6 41894.8 41928.4 41934.8 3511.3 3525.4 3972.4 3987.9 4058.0 29249.4 29912.4 30240.9 32007.5 32275.0 3310.4 3350.2 3407.2 3458.4 3469.3 516695.2 519592.0 521746.0 521912.0 523264.0 34188.0 34797.0 34913.3 35897.5 60167.7 35592.8 35610.1 35673.5 35766.3 35782.9 15070.1 15184.5 15223.9 15226.1 15261.8 11948.8 11974.6 11977.5 12010.9 12206.8 7288.5 7439.3 7440.0 7561.8 7844.3 3128.6 3213.5 3283.6 3318.8 3340.6 824.7 867.0 883.8 900.9 914.9 30295.0 30440.1 30454.2 30456.7 31169.0 895.9 977.4 1001.4 1037.3 1078.2 15477.5 15680.9 15792.6 15807.6 16107.9 663661.0 672650.0 673686.0 674801.0 674873.0 49343.5 58652.4 59047.1 59133.0 59171.0 1196.7 1200.4 1280.3 1295.9 1342.5 30043.8 31396.2 31456.0 31540.0 32054.5 49143.0 49810.3 49829.8 49859.8 50134.0 554731.0 559313.0 559866.2 568525.0 775421.0 26554.7 26838.3 26876.6 26916.6 26921.0 46015.8 46291.8 46497.5 46708.8 46861.3 31311.8 34730.0 37834.8 38450.8 38727.6 31241.4 33598.8 36866.4 37134.8 37411.8 36913.3 37223.8 37601.4 37690.9 37967.2 31523.1 33478.8 34107.1 34246.5 34338.0 25618.7 25632.6 25802.6 26051.9 26313.7 09:51:49 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | [#2] jquery.html Cycles:180 Average:35892.54 Median:358.75 stddev:105213.11 (29327.7%) stddev-sans-first:105474.40 09:51:49 INFO - PROCESS | 2526 | Values: 343.8 343.8 353.6 363.9 381.7 19644.5 20418.8 20768.9 21646.9 21844.6 15108.9 17204.5 19891.8 20089.0 20466.0 5810.5 5897.5 6170.0 6181.6 6194.2 5968.6 6051.8 6076.8 6099.8 6111.8 5728.3 5997.5 6058.4 6068.7 6071.1 6007.1 6035.5 6079.6 6080.0 6102.2 5212.8 5228.8 5278.7 5282.0 5307.4 25812.8 25903.9 25916.5 25921.2 26216.2 2002.6 2237.9 2363.3 2403.0 2426.5 10062.1 10064.5 10156.8 10205.2 10300.9 1704.7 1791.7 1832.6 1871.1 1952.2 110225.0 116549.4 124120.0 125442.0 125576.0 9230.3 9618.7 9932.8 9979.4 10047.3 12228.3 12291.8 12295.5 12314.5 12366.7 7756.0 8099.3 8116.2 8205.7 8207.5 7084.4 8610.9 8611.7 8772.9 8777.3 3842.6 3862.6 3884.6 3920.9 3966.3 1542.5 1744.1 1839.3 1895.1 1896.3 625.5 699.9 820.4 867.9 882.4 20142.7 21182.2 21371.7 21409.1 21697.6 841.9 853.1 870.2 885.9 932.6 10447.4 11131.9 11158.9 11177.4 11193.7 616230.0 628545.0 631650.0 633991.0 645169.0 106765.5 131900.8 132933.0 135013.3 137500.0 888.8 939.0 957.2 958.8 961.9 18301.6 18731.3 19022.9 19049.9 19069.9 54371.7 54621.7 55869.0 56239.2 56373.7 73489.6 76447.6 80033.8 80523.0 80745.6 6561.5 6989.9 7160.9 7486.4 7500.2 11533.9 12863.8 13155.3 13226.1 13605.0 6386.1 10359.7 11426.3 11551.1 11698.8 8364.5 10429.3 11023.6 11196.2 11669.2 11208.1 11355.2 11432.4 11436.9 11747.8 7417.3 7466.6 7527.5 7557.7 7781.2 9551.7 11290.2 11315.6 11426.2 11497.3 09:51:49 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | [#3] mootools.html Cycles:180 Average:5639.78 Median:4862.10 stddev:3232.09 (66.5%) stddev-sans-first:3225.70 09:51:49 INFO - PROCESS | 2526 | Values: 1432.3 1440.3 1585.2 1738.5 1784.8 8266.9 8801.2 8828.7 8838.2 8841.8 7720.3 8063.1 8412.2 8662.4 8692.1 1988.2 2296.7 2323.4 2331.7 2332.6 2275.7 2289.9 2323.9 2324.7 2325.0 2312.3 2319.3 2329.8 2336.6 2340.5 2294.2 2297.0 2298.6 2301.3 2302.0 2298.7 2305.6 2307.3 2308.3 2314.8 8702.7 8914.2 8914.9 8920.3 8932.4 5175.9 5348.9 5349.8 5350.8 5354.5 3343.6 3401.5 3511.5 3518.2 3520.2 1036.2 1037.3 1038.1 1058.0 1061.7 11531.8 11661.2 11663.1 11685.5 11699.5 4449.3 4636.3 4645.1 4771.0 4795.0 9907.6 9913.7 9913.8 9922.2 9947.7 5211.6 5220.3 5221.9 5223.0 5233.3 3767.6 4236.8 4381.2 4802.8 4805.9 3872.6 3891.2 3949.6 3974.2 3977.6 1221.4 1248.2 1248.3 1256.0 1264.9 788.8 792.6 803.7 841.2 849.5 5456.8 5616.2 5717.7 5725.7 5848.0 385.1 396.1 402.3 405.3 406.9 3806.0 3840.9 3865.8 3879.6 3887.7 10283.6 10863.9 10867.6 10893.9 10903.1 10808.6 10812.6 10817.5 10819.9 10826.1 7854.3 7998.6 8120.9 8142.8 8148.5 5549.2 5678.4 5748.6 5841.5 5891.6 9608.2 9695.3 9695.9 9871.8 10001.1 9879.1 10091.6 10169.9 10223.7 10288.7 6637.6 6690.3 7288.5 7339.5 7425.1 8274.5 8442.6 8454.0 8455.2 8456.8 7341.2 7380.3 7441.6 7495.3 7567.1 7159.2 7294.6 7345.6 7423.3 7457.1 6930.4 6949.2 7031.8 7035.1 7037.8 4918.3 4932.5 4937.4 4943.2 4955.4 4804.1 4939.4 4957.2 4970.1 5108.2 09:51:49 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | [#4] prototype.html Cycles:180 Average:3504.55 Median:3866.25 stddev:1041.71 (26.9%) stddev-sans-first:1016.08 09:51:49 INFO - PROCESS | 2526 | Values: 277.5 284.8 286.9 292.3 294.7 3683.9 3777.0 4067.6 4086.8 4099.4 3608.8 3737.4 3933.4 3987.8 4013.7 3043.8 3169.9 3239.8 3242.9 3252.2 3057.6 3215.7 3224.0 3260.2 3272.9 2816.8 3235.7 3254.6 3256.1 3267.7 2800.2 2849.2 2962.5 3224.1 3258.9 2865.6 2884.1 2886.4 2887.4 2887.7 3862.1 3870.4 3879.0 3887.2 3956.0 3185.3 3669.8 3684.0 3702.5 3726.8 3214.3 4286.5 4298.3 4329.4 4350.2 2694.2 3144.4 3156.8 3161.7 3165.9 3850.5 4426.4 4470.7 4498.0 4524.9 3179.1 3189.7 3192.0 3198.8 3201.0 2694.8 3052.0 3226.3 3228.8 3238.4 3632.9 3643.7 3656.4 3676.4 3677.9 4301.6 4330.5 4333.3 4337.5 4350.1 958.7 1056.3 1109.6 1110.5 1111.9 626.9 673.9 702.7 705.7 734.2 3314.6 3387.0 3462.4 3893.6 3965.7 3490.9 3886.3 4469.6 4481.8 4491.3 3858.4 3871.8 3888.8 3900.2 3900.6 1778.8 2216.6 2234.2 2263.6 2296.1 4473.6 4561.4 4564.5 4609.7 4673.9 3774.5 4506.5 4526.7 4559.3 4606.4 3653.4 4320.4 4446.1 4518.6 4539.8 3564.7 4282.6 4351.2 4380.6 4388.5 4346.9 4354.3 4385.7 4416.9 4421.0 3753.1 4503.9 4510.5 4530.5 4538.8 2702.9 2905.5 2979.8 3108.5 3134.2 3753.9 4066.9 4381.4 4422.0 4453.7 4277.0 4329.6 4372.5 4403.9 4416.6 3856.4 3923.9 3968.4 4272.8 4275.5 3805.3 3967.1 4044.5 4083.7 4313.9 3566.8 3577.2 3925.9 4291.5 4356.1 3760.1 3949.0 3991.9 4028.8 4161.4 09:51:49 INFO - PROCESS | 2526 | 09:51:49 INFO - PROCESS | 2526 | [#5] yui.html Cycles:180 Average:24560.80 Median:3692.12 stddev:38927.57 (1054.3%) stddev-sans-first:38995.23 09:51:49 INFO - PROCESS | 2526 | Values: 609.5 676.5 743.3 775.7 803.4 15584.4 16407.4 16422.6 16438.8 16475.8 12623.3 14482.7 14787.5 14791.8 16285.4 823.7 848.6 867.5 868.6 870.0 868.0 868.3 873.1 874.4 876.8 913.9 916.7 917.9 922.2 922.7 910.2 910.5 914.3 915.3 916.9 681.4 681.9 682.2 683.4 684.6 14939.6 15233.0 15255.2 15316.0 15875.1 13825.6 14564.6 14581.4 14934.5 15156.8 11395.9 11603.4 11665.5 11828.8 12127.7 3764.5 3790.8 3809.4 3861.2 3953.5 116539.0 121214.2 123967.4 124238.0 131737.3 10473.3 10813.7 11177.8 11228.3 11337.1 22568.9 22636.8 22663.2 22670.7 22845.0 7911.8 7931.2 7976.0 8111.6 8331.6 4392.4 4441.3 4554.3 4562.7 4566.5 3513.8 3683.9 3690.3 3691.4 3692.9 560.1 561.3 565.2 567.3 568.6 464.6 489.1 534.1 547.8 552.3 9092.5 9341.8 9415.1 9561.7 9578.5 570.3 582.0 584.2 590.8 592.0 4259.7 4260.2 4276.7 4320.8 4359.1 103929.0 111125.9 153227.0 153658.0 163083.2 127530.7 129308.7 131545.0 132482.0 133030.7 87727.1 94449.5 94708.5 102477.0 105382.5 8870.2 8981.0 9428.9 9723.2 9762.8 55243.4 55442.2 56738.7 56929.0 56941.7 90105.1 90356.0 91306.1 91582.0 93608.0 10927.3 12308.3 12503.8 12526.6 13417.7 13520.5 13840.0 14813.6 15109.5 15522.8 13900.0 14475.3 14900.1 15092.6 15112.4 13632.7 13796.2 13927.4 13927.9 14903.4 13712.7 13841.7 13917.5 14116.6 14706.0 11119.2 11391.8 11405.8 11675.4 11766.7 10530.9 11132.4 11291.9 11386.7 11545.5 09:51:49 INFO - PROCESS | 2526 | -------- Summary: end -------- 09:51:49 INFO - PROCESS | 2526 | 09:51:50 INFO - TEST-INFO | 2526: exit 0 09:51:50 INFO - TEST-OK | dromaeo_css | took 1114233ms 09:51:50 INFO - TEST-START | kraken 09:51:50 INFO - operating with platform_type : mac_ 09:51:50 INFO - Initialising browser for kraken test... 09:51:50 INFO - TEST-INFO | started process 2528 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpPsDNxV/profile http://localhost:53904/getInfo.html) 09:51:52 INFO - PROCESS | 2528 | __metrics Screen width/height:1600/1200 09:51:52 INFO - PROCESS | 2528 | colorDepth:24 09:51:52 INFO - PROCESS | 2528 | Browser inner width/height: 1024/689 09:51:52 INFO - PROCESS | 2528 | __metrics 09:51:52 INFO - PROCESS | 2528 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:51:52 INFO - PROCESS | 2528 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:51:53 INFO - TEST-INFO | 2528: exit 0 09:51:53 INFO - Browser initialized. 09:51:53 INFO - Running cycle 1/1 for kraken test... 09:51:53 INFO - TEST-INFO | started process 2530 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpPsDNxV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:05 INFO - PROCESS | 2530 | RSS: Main: 168513536 09:52:05 INFO - PROCESS | 2530 | 09:52:08 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53904/tests/kraken/driver.html?testName=audio-beat-detection) 09:52:09 INFO - PROCESS | 2530 | RSS: Main: 218103808 09:52:09 INFO - PROCESS | 2530 | 09:52:11 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53904/tests/kraken/driver.html?testName=audio-dft) 09:52:12 INFO - PROCESS | 2530 | RSS: Main: 231878656 09:52:12 INFO - PROCESS | 2530 | 09:52:16 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53904/tests/kraken/driver.html?testName=audio-fft) 09:52:16 INFO - PROCESS | 2530 | RSS: Main: 328339456 09:52:16 INFO - PROCESS | 2530 | 09:52:18 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53904/tests/kraken/driver.html?testName=audio-oscillator) 09:52:19 INFO - PROCESS | 2530 | RSS: Main: 228851712 09:52:19 INFO - PROCESS | 2530 | 09:52:21 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53904/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:52:22 INFO - PROCESS | 2530 | RSS: Main: 229531648 09:52:22 INFO - PROCESS | 2530 | 09:52:25 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53904/tests/kraken/driver.html?testName=imaging-darkroom) 09:52:25 INFO - PROCESS | 2530 | RSS: Main: 267862016 09:52:25 INFO - PROCESS | 2530 | 09:52:28 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53904/tests/kraken/driver.html?testName=imaging-desaturate) 09:52:29 INFO - PROCESS | 2530 | RSS: Main: 266780672 09:52:29 INFO - PROCESS | 2530 | 09:52:32 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53904/tests/kraken/driver.html?testName=json-parse-financial) 09:52:32 INFO - PROCESS | 2530 | RSS: Main: 265994240 09:52:32 INFO - PROCESS | 2530 | 09:52:34 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53904/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:52:35 INFO - PROCESS | 2530 | RSS: Main: 239091712 09:52:35 INFO - PROCESS | 2530 | 09:52:36 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53904/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:52:37 INFO - PROCESS | 2530 | RSS: Main: 245264384 09:52:37 INFO - PROCESS | 2530 | 09:52:39 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53904/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:52:40 INFO - PROCESS | 2530 | RSS: Main: 251629568 09:52:40 INFO - PROCESS | 2530 | 09:52:43 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53904/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:52:44 INFO - PROCESS | 2530 | RSS: Main: 265940992 09:52:44 INFO - PROCESS | 2530 | 09:52:47 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53904/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:52:47 INFO - PROCESS | 2530 | RSS: Main: 259993600 09:52:47 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | Cycle 1(1): loaded http://localhost:53904/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53904/tests/kraken/driver.html?testName=ai-astar) 09:52:49 INFO - PROCESS | 2530 | RSS: Main: 303915008 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | __start_tp_report 09:52:49 INFO - PROCESS | 2530 | _x_x_mozilla_page_load 09:52:49 INFO - PROCESS | 2530 | _x_x_mozilla_page_load_details 09:52:49 INFO - PROCESS | 2530 | |i|pagename|runs| 09:52:49 INFO - PROCESS | 2530 | |0;ai-astar;118;163;172;134;119;117;116;118;117;116 09:52:49 INFO - PROCESS | 2530 | |1;audio-beat-detection;149;113;114;148;113;113;114;112;146;139 09:52:49 INFO - PROCESS | 2530 | |2;audio-dft;212;212;213;214;213;215;216;212;210;212 09:52:49 INFO - PROCESS | 2530 | |3;audio-fft;86;59;59;99;85;84;84;60;84;59 09:52:49 INFO - PROCESS | 2530 | |4;audio-oscillator;95;72;98;72;70;73;91;82;102;125 09:52:49 INFO - PROCESS | 2530 | |5;imaging-gaussian-blur;94;88;92;89;116;122;91;89;90;88 09:52:49 INFO - PROCESS | 2530 | |6;imaging-darkroom;96;97;98;97;97;96;98;97;97;97 09:52:49 INFO - PROCESS | 2530 | |7;imaging-desaturate;105;106;100;100;105;105;105;100;100;123 09:52:49 INFO - PROCESS | 2530 | |8;json-parse-financial;59;56;56;57;58;61;60;61;60;60 09:52:49 INFO - PROCESS | 2530 | |9;json-stringify-tinderbox;70;75;70;80;75;76;76;77;76;76 09:52:49 INFO - PROCESS | 2530 | |10;stanford-crypto-aes;86;88;86;85;86;102;103;107;83;92 09:52:49 INFO - PROCESS | 2530 | |11;stanford-crypto-ccm;145;142;144;145;140;142;147;152;203;179 09:52:49 INFO - PROCESS | 2530 | |12;stanford-crypto-pbkdf2;167;169;169;169;169;168;169;168;167;167 09:52:49 INFO - PROCESS | 2530 | |13;stanford-crypto-sha256-iterative;69;69;70;69;67;69;68;68;66;68 09:52:49 INFO - PROCESS | 2530 | __end_tp_report 09:52:49 INFO - PROCESS | 2530 | __start_cc_report 09:52:49 INFO - PROCESS | 2530 | _x_x_mozilla_cycle_collect,1966 09:52:49 INFO - PROCESS | 2530 | __end_cc_report 09:52:49 INFO - PROCESS | 2530 | __startTimestamp1483552369619__endTimestamp 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | ------- Summary: start ------- 09:52:49 INFO - PROCESS | 2530 | Number of tests: 14 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#0] ai-astar Cycles:10 Average:129.00 Median:118.50 stddev:21.08 (17.8%) stddev-sans-first:21.98 09:52:49 INFO - PROCESS | 2530 | Values: 118.0 163.0 172.0 134.0 119.0 117.0 116.0 118.0 117.0 116.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:126.50 stddev:16.91 (13.4%) stddev-sans-first:15.77 09:52:49 INFO - PROCESS | 2530 | Values: 149.0 113.0 114.0 148.0 113.0 113.0 114.0 112.0 146.0 139.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#2] audio-dft Cycles:10 Average:212.90 Median:213.00 stddev:1.73 (0.8%) stddev-sans-first:1.80 09:52:49 INFO - PROCESS | 2530 | Values: 212.0 212.0 213.0 214.0 213.0 215.0 216.0 212.0 210.0 212.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#3] audio-fft Cycles:10 Average:75.90 Median:84.00 stddev:15.00 (17.9%) stddev-sans-first:15.46 09:52:49 INFO - PROCESS | 2530 | Values: 86.0 59.0 59.0 99.0 85.0 84.0 84.0 60.0 84.0 59.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:77.50 stddev:17.70 (22.8%) stddev-sans-first:18.59 09:52:49 INFO - PROCESS | 2530 | Values: 95.0 72.0 98.0 72.0 70.0 73.0 91.0 82.0 102.0 125.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:89.50 stddev:12.40 (13.9%) stddev-sans-first:13.13 09:52:49 INFO - PROCESS | 2530 | Values: 94.0 88.0 92.0 89.0 116.0 122.0 91.0 89.0 90.0 88.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:97.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 09:52:49 INFO - PROCESS | 2530 | Values: 96.0 97.0 98.0 97.0 97.0 96.0 98.0 97.0 97.0 97.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#7] imaging-desaturate Cycles:10 Average:104.90 Median:105.00 stddev:6.87 (6.5%) stddev-sans-first:7.29 09:52:49 INFO - PROCESS | 2530 | Values: 105.0 106.0 100.0 100.0 105.0 105.0 105.0 100.0 100.0 123.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:60.00 stddev:1.93 (3.2%) stddev-sans-first:2.05 09:52:49 INFO - PROCESS | 2530 | Values: 59.0 56.0 56.0 57.0 58.0 61.0 60.0 61.0 60.0 60.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#9] json-stringify-tinderbox Cycles:10 Average:75.10 Median:76.00 stddev:3.03 (4.0%) stddev-sans-first:2.60 09:52:49 INFO - PROCESS | 2530 | Values: 70.0 75.0 70.0 80.0 75.0 76.0 76.0 77.0 76.0 76.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#10] stanford-crypto-aes Cycles:10 Average:91.80 Median:86.00 stddev:8.82 (10.3%) stddev-sans-first:9.10 09:52:49 INFO - PROCESS | 2530 | Values: 86.0 88.0 86.0 85.0 86.0 102.0 103.0 107.0 83.0 92.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#11] stanford-crypto-ccm Cycles:10 Average:153.90 Median:146.00 stddev:20.62 (14.1%) stddev-sans-first:21.61 09:52:49 INFO - PROCESS | 2530 | Values: 145.0 142.0 144.0 145.0 140.0 142.0 147.0 152.0 203.0 179.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:169.00 stddev:0.92 (0.5%) stddev-sans-first:0.87 09:52:49 INFO - PROCESS | 2530 | Values: 167.0 169.0 169.0 169.0 169.0 168.0 169.0 168.0 167.0 167.0 09:52:49 INFO - PROCESS | 2530 | 09:52:49 INFO - PROCESS | 2530 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 09:52:49 INFO - PROCESS | 2530 | Values: 69.0 69.0 70.0 69.0 67.0 69.0 68.0 68.0 66.0 68.0 09:52:49 INFO - PROCESS | 2530 | -------- Summary: end -------- 09:52:49 INFO - PROCESS | 2530 | 09:52:50 INFO - TEST-INFO | 2530: exit 0 09:52:50 INFO - TEST-OK | kraken | took 60111ms 09:52:50 INFO - SUITE-END | took 1174s 09:52:50 INFO - Completed test suite (00:19:34) 09:52:50 INFO - javascript benchmark 09:52:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2362.503874186421, 2893.146521776076, 2899.753520950718, 2920.269932516871, 2967.9703202967976, 4795.376601041864, 4855.878603034917, 4859.465458799529, 4869.074875773601, 4942.728149951751, 4847.515248475151, 4848.612111031118, 4850.563449289563, 4901.558859702626, 4916.459189489162, 4462.286034234523, 4490.438695163105, 4500.459944806611, 4533.365328853963, 4605.986682929767, 4332.545082766312, 4427.800748966288, 4445.51099379068, 4535.183666939949, 4568.314752787075, 4547.658925580578, 4567.177907976563, 4567.794449249792, 4585.2663573828195, 4590.288505281686, 4546.227141385973, 4562.384078149441, 4566.771661416932, 4567.36056952027, 4582.702124361923, 4498.79755411004, 4515.7516336601475, 4520.59314661682, 4531.297648864432, 4532.957419793466, 4799.424069111729, 4814.7351895645725, 4864.683795553277, 4889.437714662824, 4904.4605093439695, 5179.689218646856, 5179.689218646856, 5200.323957885451, 5299.231611416323, 5301.045811753883, 5365.195220716884, 5365.731713414314, 5379.1662292344745, 5406.053940560386, 5435.157550579666, 4869.610431165498, 4936.728479933567, 4939.802407903644, 5033.899322013539, 5063.0, 4381.6494680425485, 5319.122344813062, 5387.515123638813, 5440.483154100354, 5459.727013649302, 5234.345706786652, 5238.5547228486, 5265.578753699695, 5267.051930652443, 5300.9734951325, 5150.175971844487, 5215.634905556651, 5328.3605967284175, 5335.0663633863915, 5381.408045115034, 5139.177731562932, 5174.301469301671, 5203.765830537635, 5240.711760853113, 5309.761060752276, 5258.631895767336, 5269.868253293698, 5278.683279003272, 5293.417724050351, 5313.282706834528, 4362.585554372393, 4373.715708478939, 4379.802908869108, 4384.4081049058605, 4423.889402764957, 3815.313243616176, 3866.903327416781, 3897.512810898638, 3923.8822835314986, 3925.4896863407575, 4610.331501932268, 4663.044075964487, 4705.882352941136, 4763.857084287477, 4789.664723469394, 5314.3091398117995, 5332.760025798849, 5338.279332290091, 5368.114261146867, 5379.1662292344745, 4586.7018643788715, 4754.7147171169145, 4756.833510827105, 4785.688930219525, 4810.63920205986, 4580.4045474088825, 4617.145828021759, 4649.790759415834, 4662.4405071390975, 4721.858344249609, 5514.283143191359, 5539.335279766454, 5545.500904918576, 5560.527355174855, 5574.16387541872, 5256.9211461827335, 5272.736363181749, 5300.522952934254, 5321.866953326043, 5352.678839269656, 4512.90974180508, 5259.631825772312, 5354.919676204782, 5537.36320323168, 5544.390117087198, 5094.541491265655, 5165.974170129125, 5182.0, 5249.921251181312, 5292.470752924677, 5245.763940622604, 5297.364316282071, 5307.3100496936695, 5314.734263286898, 5327.147656375117, 5183.714895680774, 5201.141811601044, 5201.557867581147, 5204.037253108036, 5205.765740541609, 4959.057779021974, 4970.701757894393, 5054.671446355975, 5077.1876499759865, 5122.257272695364, 5096.362954630671, 5128.820491282787, 5142.948570514247, 5143.6399452037995, 5167.276581278549, 5189.091908916, 5193.37679478465, 5202.3497062867145, 5212.113940630026, 5250.1074817281915, 4919.0899683558655, 4973.502649735142, 4975.676581022222, 4991.6006719463085, 5083.87290317745], "value": 4867.520863563187, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.42538211487664, 448.5716141085262, 466.92996050592404, 469.7604221846859, 499.35333742803067, 31296.687033129663, 40914.38628420572, 42055.57944420555, 42090.36864447032, 44144.779276103654, 44082.33876491847, 44276.0, 44321.0, 44324.77837610807, 44395.11209775811, 6886.931130688704, 8560.315174786003, 9724.465154416486, 9962.651307204213, 9982.151517121054, 8992.595333210022, 9181.357304988653, 9195.678151264707, 9201.907980920172, 11785.941070294679, 11804.055675545937, 12071.818922716166, 12078.516859325617, 12106.576269830557, 12123.393830308449, 12057.819132713017, 12160.574379896705, 12177.634670959886, 12178.81731774024, 12182.329971851545, 11827.112966527546, 11936.522539098425, 12023.63929082129, 12048.457819398149, 12052.156349055569, 41840.79079604613, 41875.58124418747, 41894.79052604748, 41928.37107443391, 41934.790326048176, 3511.3328467591077, 3525.435930251173, 3972.3842804365368, 3987.920241595181, 4058.0260737422805, 29249.41501169986, 29912.401751965055, 30240.941567045047, 32007.51988720171, 32275.0, 3310.3710295043866, 3350.229447227127, 3407.1822762537063, 3458.3774920514297, 3469.271452994874, 516695.24957125675, 519592.0, 521746.0, 521912.0, 523264.0, 34188.0, 34797.0, 34913.30173396569, 35897.46153807695, 60167.699161503915, 35592.82203588966, 35610.10974725601, 35673.46489802655, 35766.28467430689, 35782.92651220468, 15070.09579425238, 15184.46854360092, 15223.923880380527, 15226.086434813946, 15261.847381526042, 11948.76102477963, 11974.64076077719, 11977.461014254379, 12010.919017288486, 12206.755864882653, 7288.453365997571, 7439.330460258603, 7439.962800186073, 7561.810954726176, 7844.294013538809, 3128.6402063762957, 3213.53403756454, 3283.6387997320276, 3318.784279021856, 3340.5657264555452, 824.686619084756, 866.9869951950726, 883.8276536075532, 900.8918929728475, 914.9359544831931, 30295.0, 30440.086797396114, 30454.238644034078, 30456.69543304583, 31169.0, 895.8566629339274, 977.359829311802, 1001.3791449301334, 1037.2998226197437, 1078.245228340165, 15477.458288959833, 15680.92159539195, 15792.60518487047, 15807.604809879846, 16107.919460402858, 663661.0, 672650.0, 673686.0, 674801.0, 674873.0, 49343.506564933894, 58652.41347586555, 59047.11429328564, 59133.0, 59171.0, 1196.7367179220557, 1200.4057991294364, 1280.2510531339271, 1295.902807289457, 1342.4764341906664, 30043.849780750956, 31396.215094622818, 31456.0, 31540.0, 32054.519182211818, 49143.0, 49810.25284620806, 49829.75085124551, 49859.75070124626, 50133.99732005412, 554731.0, 559313.0, 559866.2006689941, 568525.0, 775421.0, 26554.734452655226, 26838.329041774112, 26876.59685104764, 26916.596251056642, 26921.0, 46015.76992115018, 46291.76854115708, 46497.53502464932, 46708.766456167505, 46861.2970805445, 31311.84344078265, 34729.958101257005, 37834.81082594569, 38450.807745961094, 38727.6127238724, 31241.37517249597, 33598.83200583982, 36866.44700329444, 37134.8143259282, 37411.812940935124, 36913.261734764616, 37223.81388093042, 37601.4359784598, 37690.869273921824, 37967.240655186186, 31523.054308370785, 33478.83260583682, 34107.14732131716, 34246.48630270598, 34337.96986090421, 25618.74381256238, 25632.615510767726, 25802.61296080523, 26051.869740651935, 26313.736862631893], "value": 18768.567770752576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.76930588493195, 343.7816986213754, 353.60749567979536, 363.892651667758, 381.67938931297704, 19644.508887277807, 20418.795812041895, 20768.89615551924, 21646.91765411729, 21844.563108737857, 15108.942374033822, 17204.483865484024, 19891.801081989175, 20088.995550222502, 20466.0, 5810.535157187436, 5897.498712609423, 6170.043643235305, 6181.59819611726, 6194.163787888622, 5968.641881487103, 6051.788187413442, 6076.756929722806, 6099.756009759604, 6111.838750637365, 5728.283964504437, 5997.460228579427, 6058.424449677295, 6068.6662233577135, 6071.089336599523, 6007.128966299884, 6035.486983606399, 6079.635221886679, 6080.027195648675, 6102.20671312731, 5212.817551385683, 5228.79084836606, 5278.683279003234, 5282.0, 5307.442718514578, 25812.838422270856, 25903.870480647663, 25916.481670366677, 25921.22236332913, 26216.213513594623, 2002.6094911492264, 2237.8881055947136, 2363.2910126962015, 2402.9639555406507, 2426.5389575980507, 10062.094411503, 10064.547095380653, 10156.796864062677, 10205.183585313158, 10300.948495257475, 1704.6505466379363, 1791.7312403139504, 1832.5876677747533, 1871.1299245850744, 1952.1898412158937, 110225.0, 116549.41725291403, 124120.0, 125442.0, 125576.0, 9230.261579073658, 9618.711438656852, 9932.80134397308, 9979.351342162736, 10047.296689231829, 12228.327441990776, 12291.754164916652, 12295.508179672714, 12314.50741970329, 12366.690832729253, 7755.95294635217, 8099.311558517474, 8116.188381161836, 8205.671773129128, 8207.466514676527, 7084.362407383257, 8610.913890861137, 8611.741647750578, 8772.91227087721, 8777.253933415732, 3842.5965273646425, 3862.633049860259, 3884.592117827644, 3920.941185882214, 3966.345552983725, 1542.537238828347, 1744.145368769294, 1839.3378383781828, 1895.109298629642, 1896.2699360746212, 625.5464788028643, 699.9055127557715, 820.3929092471528, 867.9088695686988, 882.3911501064246, 20142.69785953212, 21182.15271389158, 21371.67942480864, 21409.143634254455, 21697.56604867894, 841.9158084191654, 853.0914575976565, 870.2037635563515, 885.9158379954023, 932.5523748600709, 10447.373157610567, 11131.944340278247, 11158.944205279084, 11177.44112794357, 11193.720156995978, 616230.0, 628545.0, 631650.0, 633991.0, 645169.0, 106765.46617266865, 131900.8314043878, 132933.0, 135013.3249333747, 137500.0, 888.7511496780912, 938.9765255868522, 957.2342126299072, 958.750724811554, 961.8845738511424, 18301.63396732031, 18731.344402946375, 19022.858628482336, 19049.85700857953, 19069.90465047666, 54371.72814135904, 54621.72689136529, 55869.0, 56239.15641265466, 56373.71813141072, 73489.6325518369, 76447.61776191083, 80033.7978031426, 80523.0, 80745.59627201826, 6561.507886908405, 6989.860202795814, 7160.856782864209, 7486.43851711113, 7500.249975002456, 11533.942330288295, 12863.807042894552, 13155.276459794803, 13226.140300880412, 13605.0, 6386.105945167773, 10359.689209323733, 11426.314421134759, 11551.075913927034, 11698.824517632413, 8364.494391009677, 10429.26995110335, 11023.61417350389, 11196.216264861381, 11669.241499302518, 11208.10335173168, 11355.205135640756, 11432.388776995605, 11436.94281528587, 11747.765044699228, 7417.258274172539, 7466.626668666654, 7527.494501099693, 7557.732680196029, 7781.182975787461, 9551.665691700757, 11290.209685322277, 11315.603953861575, 11426.2001659883, 11497.252678575866], "value": 9284.281317533747, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.2767002663657, 1440.2870579063363, 1585.2390852390852, 1738.539287088921, 1784.803671596125, 8266.917330826689, 8801.16388943051, 8828.69099581514, 8838.160374764395, 8841.823163536741, 7720.266574675411, 8063.1130575636635, 8412.158784121584, 8662.393632445732, 8692.130786921305, 1988.2444671025025, 2296.724393072825, 2323.4307594639336, 2331.6735657008035, 2332.5684748321614, 2275.7269127704617, 2289.885505724707, 2323.9419014524688, 2324.662923876037, 2324.9883750581225, 2312.3409828198965, 2319.3273950554403, 2329.77867102625, 2336.5560543496767, 2340.473393486469, 2294.1511640692966, 2296.977030229693, 2298.5977453945493, 2301.275098344033, 2301.976980230193, 2298.678185054094, 2305.619572770491, 2307.284741730069, 2308.284431826139, 2314.7685231476885, 8702.738917832476, 8914.153155450222, 8914.910850891472, 8920.330975176823, 8932.419392739452, 5175.922361164586, 5348.91976620351, 5349.759260833272, 5350.812721554766, 5354.464553544653, 3343.632200457948, 3401.523786669868, 3511.5434993450945, 3518.1732292911147, 3520.2431477232262, 1036.2331874412955, 1037.273908264218, 1038.0709265207597, 1057.9947100264449, 1061.6549621373008, 11531.76936461266, 11661.24201926872, 11663.12526560503, 11685.474153663106, 11699.473523691455, 4449.310356894686, 4636.258198688193, 4645.094206629742, 4771.021940502189, 4794.976025119852, 9907.603695852229, 9913.702588922344, 9913.752156196153, 9922.156616687662, 9947.701568952943, 5211.635185536976, 5220.347456567929, 5221.895562088813, 5223.033738758342, 5233.319668443154, 3767.585565587783, 4236.76697781625, 4381.21138195128, 4802.783874725646, 4805.903881922342, 3872.6320999505, 3891.2217556488818, 3949.6247856454165, 3974.18529201513, 3977.5624681285035, 1221.3770976801895, 1248.1574936917543, 1248.3383806582526, 1255.9623211303676, 1264.917780344293, 788.8461749958818, 792.6195426195459, 803.658445160816, 841.2260720137489, 849.5412477262332, 5456.809011684572, 5616.213730077792, 5717.742701578356, 5725.656460612377, 5847.976604094267, 385.1238432565879, 396.11073140798516, 402.2919661395964, 405.2968100345897, 406.89420750604467, 3806.0, 3840.9423858642144, 3865.7873816940337, 3879.6120387960977, 3887.688984881203, 10283.588656453658, 10863.891361086446, 10867.565297388173, 10893.945530272456, 10903.127749779998, 10808.56765729362, 10812.621558245424, 10817.513211905325, 10819.891801081889, 10826.079783218358, 7854.253845884632, 7998.600069996477, 8120.878186827203, 8142.796430089295, 8148.511089334659, 5549.195366671891, 5678.375378708256, 5748.626339287933, 5841.50347220491, 5891.617044892068, 9608.22620052875, 9695.321327507289, 9695.9030409695, 9871.802563948537, 10001.14990225839, 9879.061489158807, 10091.646792362231, 10169.898301016896, 10223.7444063899, 10288.691339259834, 6637.6349300788925, 6690.330966903465, 7288.489805713549, 7339.486235963432, 7425.108986921604, 8274.462159959583, 8442.577871106543, 8454.0, 8455.239028487465, 8456.788580285542, 7341.229170936975, 7380.335769780745, 7441.627918604157, 7495.287947644965, 7567.129780075362, 7159.176694680178, 7294.63526823646, 7345.632718364167, 7423.331900129015, 7457.105147382349, 6930.3762661360715, 6949.166100067822, 7031.824204394931, 7035.085438892911, 7037.753678621224, 4918.286848407032, 4932.482089380564, 4937.407511098691, 4943.20908654628, 4955.4301255356095, 4804.135255653876, 4939.357883475269, 4957.206846904478, 4970.055689418999, 5108.182690769459], "value": 4413.717561556707, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.46587818449484, 284.83479581842533, 286.8623060930754, 292.2955676818867, 294.6964626434773, 3683.889483315498, 3776.9811150944274, 4067.6135767102114, 4086.7547947123194, 4099.3645984872355, 3608.7654302470323, 3737.42069979153, 3933.3706606942897, 3987.7607343559407, 4013.71561100448, 3043.8281261714287, 3169.9049028529125, 3239.805611663308, 3242.902712918616, 3252.219467327836, 3057.6330840299083, 3215.742740580748, 3223.9844448998606, 3260.1686569924636, 3272.8527216275206, 2816.816906901055, 3235.724963378104, 3254.5931758530182, 3256.1045712429154, 3267.6895694908944, 2800.2436717167457, 2849.173739615509, 2962.5335458947743, 3224.0811368760023, 3258.8696452141885, 2865.627468429091, 2884.120343295294, 2886.379428422878, 2887.3936473340623, 2887.7112288770954, 3862.1310205203886, 3870.3613903705855, 3878.991462219815, 3887.222555488885, 3955.9802200989097, 3185.2833112549724, 3669.834857431422, 3684.0290444612274, 3702.4631428442995, 3726.7763934164036, 3214.324991751735, 4286.5284818669925, 4298.312270036779, 4329.372241025065, 4350.151720414488, 2694.154504164634, 3144.3868445653106, 3156.7632427568024, 3161.7470602351755, 3165.9366812663848, 3850.5186851643543, 4426.358178064195, 4470.731756094645, 4498.008824131981, 4524.864254072416, 3179.078067360486, 3189.681031896792, 3191.973097083369, 3198.784461904508, 3200.9519857202163, 2694.757471827545, 3051.969480305213, 3226.274088330154, 3228.790128641631, 3238.417084924689, 3632.927341453209, 3643.708503319781, 3656.3966945454235, 3676.4117741161285, 3677.9150150705605, 4301.591348821857, 4330.523642399334, 4333.349997500401, 4337.544557821447, 4350.086481838786, 958.6740508227236, 1056.2553399853084, 1109.628274528026, 1110.4780753046055, 1111.8610173728284, 626.8620903401245, 673.9292374300628, 702.6964979962097, 705.7282946065699, 734.2327268004948, 3314.5691060162026, 3387.017764848216, 3462.4460086386057, 3893.5911729268587, 3965.722399432012, 3490.877819276313, 3886.3198940185353, 4469.553044695569, 4481.820727170942, 4491.258942274488, 3858.401947698111, 3871.787051712183, 3888.7723189753, 3900.2199560088097, 3900.6099390061336, 1778.8487978521712, 2216.5592364962904, 2234.162189179058, 2263.5925533403815, 2296.1401264975425, 4473.5526447355005, 4561.407017087758, 4564.497905230422, 4609.700369475973, 4673.883152921204, 3774.452704357853, 4506.459224893034, 4526.683132180781, 4559.338895860082, 4606.447226332794, 3653.392507296781, 4320.351947207944, 4446.132624297868, 4518.638508919278, 4539.818407263672, 3564.7326450515798, 4282.593153650399, 4351.170969546779, 4380.6057454829215, 4388.489208633017, 4346.934795978126, 4354.325079612665, 4385.671074669349, 4416.933745993877, 4421.0, 3753.1476078180235, 4503.887402814956, 4510.458744950627, 4530.501644819134, 4538.818447262072, 2702.90539831105, 2905.535114381689, 2979.7616190705126, 3108.5492603572807, 3134.200778801391, 3753.868614598476, 4066.898327541835, 4381.430414046154, 4421.955780442155, 4453.710508816916, 4277.0, 4329.588689074533, 4372.475302963665, 4403.889902752328, 4416.6025057744955, 3856.3636999895825, 3923.8822835314986, 3968.4245784361683, 4272.807723652505, 4275.529691733969, 3805.276997370491, 3967.0875698588907, 4044.4944381952255, 4083.6528895044253, 4313.870583882489, 3566.839492222908, 3577.157763751722, 3925.92148157041, 4291.463567054118, 4356.085222103394, 3760.082357997496, 3949.0, 3991.9062726387283, 4028.798560072043, 4161.3549899765585], "value": 3187.388604482005, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.4545381883214, 676.489250615785, 743.3050098158219, 775.7432958607054, 803.4335793265747, 15584.376624935003, 16407.425740099097, 16422.589435264123, 16438.75341869873, 16475.83524164758, 12623.305718185497, 14482.710345793077, 14787.482438114668, 14791.778123328158, 16285.430009949654, 823.7487566292278, 848.5672307123381, 867.5271976772674, 868.6178081644088, 870.0212261206143, 867.9783005424852, 868.3357231717741, 873.0570983338012, 874.3922973533397, 876.7808047988003, 913.8811954445946, 916.6791622931954, 917.8944421391525, 922.2161163011453, 922.7231830450871, 910.1762904571374, 910.4946754551221, 914.2959920860966, 915.3363811236833, 916.9495677737722, 681.3595220492771, 681.8602186551794, 682.222266616058, 683.3815397065614, 684.565301033847, 14939.551813445614, 15233.0, 15255.23723813816, 15316.0, 15875.126868022255, 13825.585232443043, 14564.586320780692, 14581.416743330361, 14934.477293294682, 15156.848431515655, 11395.886041139649, 11603.419829008515, 11665.475053622522, 11828.763424731544, 12127.696807579881, 3764.4729737836724, 3790.824844298249, 3809.4095415210686, 3861.2084522672785, 3953.5453422856453, 116539.0, 121214.18178727326, 123967.38016309951, 124238.0, 131737.34131329283, 10473.266871318934, 10813.729656758644, 11177.83233251502, 11228.27016243958, 11337.093032557375, 22568.887155564447, 22636.773632263466, 22663.20678776235, 22670.65994010091, 22845.0, 7911.841763164704, 7931.2068793120225, 7975.960120199478, 8111.553864537507, 8331.625076871554, 4392.407025051641, 4441.289393696993, 4554.294084416921, 4562.7262364257585, 4566.520515345907, 3513.771604845677, 3683.8894833154513, 3690.317291301118, 3691.390920498088, 3692.8522859085874, 560.1262031231211, 561.2843624378868, 565.2482198675751, 567.2823877794593, 568.5536853570011, 464.6004436184878, 489.05856226763336, 534.0533457747251, 547.8329109621603, 552.2847911954029, 9092.454452732858, 9341.766455838659, 9415.105564971316, 9561.665341713007, 9578.47318397481, 570.3469527391114, 581.9534437245095, 584.2113147251262, 590.7636945221859, 591.9585629005928, 4259.7231179973205, 4260.169266992969, 4276.72201306914, 4320.783960801948, 4359.12817436514, 103929.0, 111125.88874111156, 153227.0, 153658.0, 163083.1845840763, 127530.72469275373, 129308.70691293155, 131545.0268245977, 132482.0, 133030.66969330376, 87727.12272877317, 94449.5277523608, 94708.52645736728, 102476.97523024824, 105382.4730876356, 8870.15733505316, 8981.0, 9428.915674697497, 9723.222142228746, 9762.804743905224, 55243.447565525435, 55442.16836747533, 56738.716306419854, 56929.0, 56941.71529142328, 90105.09894900967, 90356.0, 91306.08693912976, 91582.0, 93608.0, 10927.289726167774, 12308.323042232763, 12503.812442813183, 12526.624201273977, 13417.731645367234, 13520.459181633018, 13840.031197816057, 14813.629659258604, 15109.54671359861, 15522.767158492858, 13900.0, 14475.348609312396, 14900.105993639983, 15092.622684432978, 15112.39550418015, 13632.65918352049, 13796.241206733726, 13927.442902283796, 13927.86072139306, 14903.403863845324, 13712.725745484835, 13841.653958651113, 13917.512887048904, 14116.647083822987, 14706.044107133002, 11119.221654484109, 11391.772164556829, 11405.828912566152, 11675.416229188337, 11766.705832354259, 10530.894256102998, 11132.443377831238, 11291.943540282246, 11386.658400248005, 11545.538178472767], "value": 7776.55427246196, "unit": "score"}], "name": "dromaeo_css", "value": 6728.6725451696475, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 163.0, 172.0, 134.0, 119.0, 117.0, 116.0, 118.0, 117.0, 116.0], "value": 129.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 114.0, 148.0, 113.0, 113.0, 114.0, 112.0, 146.0, 139.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 212.0, 213.0, 214.0, 213.0, 215.0, 216.0, 212.0, 210.0, 212.0], "value": 212.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 59.0, 59.0, 99.0, 85.0, 84.0, 84.0, 60.0, 84.0, 59.0], "value": 75.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 72.0, 98.0, 72.0, 70.0, 73.0, 91.0, 82.0, 102.0, 125.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 88.0, 92.0, 89.0, 116.0, 122.0, 91.0, 89.0, 90.0, 88.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 98.0, 97.0, 97.0, 96.0, 98.0, 97.0, 97.0, 97.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 100.0, 100.0, 105.0, 105.0, 105.0, 100.0, 100.0, 123.0], "value": 104.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 56.0, 57.0, 58.0, 61.0, 60.0, 61.0, 60.0, 60.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 70.0, 80.0, 75.0, 76.0, 76.0, 77.0, 76.0, 76.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 85.0, 86.0, 102.0, 103.0, 107.0, 83.0, 92.0], "value": 91.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 142.0, 144.0, 145.0, 140.0, 142.0, 147.0, 152.0, 203.0, 179.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 169.0, 169.0, 169.0, 168.0, 169.0, 168.0, 167.0, 167.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 69.0, 67.0, 69.0, 68.0, 68.0, 66.0, 68.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1545.8, "alertThreshold": 2.0}]} 09:52:50 INFO - Return code: 0 09:52:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:52:50 INFO - # TBPL SUCCESS # 09:52:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:52:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:52:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:52:50 INFO - 2017-01-04 09:52:50.958 screenresolution[2534:21169] starting screenresolution argv=screenresolution get 09:52:50 INFO - 2017-01-04 09:52:50.961 screenresolution[2534:21169] Display 0: 1600x1200x32@60 09:52:50 INFO - 2017-01-04 09:52:50.967 screenresolution[2535:21172] starting screenresolution argv=screenresolution list 09:52:50 INFO - Available Modes on Display 0 09:52:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:52:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:52:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:52:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:52:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:52:51 INFO - Intel Iris: 09:52:51 INFO - Chipset Model: Intel Iris 09:52:51 INFO - Type: GPU 09:52:51 INFO - Bus: Built-In 09:52:51 INFO - VRAM (Dynamic, Max): 1536 MB 09:52:51 INFO - Vendor: Intel (0x8086) 09:52:51 INFO - Device ID: 0x0a2e 09:52:51 INFO - Revision ID: 0x0009 09:52:51 INFO - Displays: 09:52:51 INFO - Display: 09:52:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:52:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:52:51 INFO - Display Serial Number: 1600x1200 60 09:52:51 INFO - Main Display: Yes 09:52:51 INFO - Mirror: Off 09:52:51 INFO - Online: Yes 09:52:51 INFO - Rotation: Supported 09:52:51 INFO - Return code: 0 09:52:51 INFO - Running post-action listener: _resource_record_post_action 09:52:51 INFO - [mozharness: 2017-01-04 17:52:51.215250Z] Finished run-tests step (success) 09:52:51 INFO - Running post-run listener: _resource_record_post_run 09:52:51 INFO - Total resource usage - Wall time: 1198s; CPU: 53.0%; Read bytes: 274432; Write bytes: 493157888; Read time: 3; Write time: 1680 09:52:51 INFO - TinderboxPrint: CPU usage
53.5% 09:52:51 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 3 09:52:51 INFO - TinderboxPrint: I/O write bytes / time
493,157,888 / 1,680 09:52:51 INFO - TinderboxPrint: CPU idle
2,229.9 (46.5%) 09:52:51 INFO - TinderboxPrint: CPU system
962.6 (20.1%) 09:52:51 INFO - TinderboxPrint: CPU user
1,601.2 (33.4%) 09:52:51 INFO - TinderboxPrint: Swap in / out
195,031,040 / 0 09:52:51 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 0; Write bytes: 196141056; Read time: 0; Write time: 364 09:52:51 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 118784; Write bytes: 295640576; Read time: 1; Write time: 1310 09:52:51 INFO - Running post-run listener: _upload_blobber_files 09:52:51 INFO - Blob upload gear active. 09:52:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:52:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:52:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:52:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:52:52 INFO - (blobuploader) - INFO - Open directory for files ... 09:52:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:52:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:52:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:52 INFO - (blobuploader) - INFO - Done attempting. 09:52:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:52:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:52:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:53 INFO - (blobuploader) - INFO - Done attempting. 09:52:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:52:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:52:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:56 INFO - (blobuploader) - INFO - Done attempting. 09:52:56 INFO - (blobuploader) - INFO - Iteration through files over. 09:52:56 INFO - Return code: 0 09:52:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:52:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:52:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eef7f65588628409540d5eef2fbedb9908684d5694838a5a4dd4fe7d75e33fc5b03487a90a73b5e946f18121bc565123b26a14f26546105c2b5457db1af7226", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16aef27889e59edc1d28d494886b5e70577853622379d0e17f0f3ab9f2361491a72672b1f93761b91f05ccc24c10a8b09b916e8efaea2e59b80b27de2b162d3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82bbc0befc1d28b3d1f718ebd89683d7caf7dbf5e65675a34ccb27d3a3ab1cb324f73b34ac7407a1391c4f470da2037cc488ffa03b8254bce7c47572266b83ba"} 09:52:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:52:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:52:56 INFO - Contents: 09:52:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eef7f65588628409540d5eef2fbedb9908684d5694838a5a4dd4fe7d75e33fc5b03487a90a73b5e946f18121bc565123b26a14f26546105c2b5457db1af7226", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16aef27889e59edc1d28d494886b5e70577853622379d0e17f0f3ab9f2361491a72672b1f93761b91f05ccc24c10a8b09b916e8efaea2e59b80b27de2b162d3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82bbc0befc1d28b3d1f718ebd89683d7caf7dbf5e65675a34ccb27d3a3ab1cb324f73b34ac7407a1391c4f470da2037cc488ffa03b8254bce7c47572266b83ba"} 09:52:56 INFO - Running post-run listener: copy_logs_to_upload_dir 09:52:56 INFO - Copying logs to upload dir... 09:52:56 INFO - mkdir: /builds/slave/test/build/upload/logs 09:52:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.983259 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-04 09:52:56.093463) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 09:52:56.096909) ========= 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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/8eef7f65588628409540d5eef2fbedb9908684d5694838a5a4dd4fe7d75e33fc5b03487a90a73b5e946f18121bc565123b26a14f26546105c2b5457db1af7226", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16aef27889e59edc1d28d494886b5e70577853622379d0e17f0f3ab9f2361491a72672b1f93761b91f05ccc24c10a8b09b916e8efaea2e59b80b27de2b162d3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82bbc0befc1d28b3d1f718ebd89683d7caf7dbf5e65675a34ccb27d3a3ab1cb324f73b34ac7407a1391c4f470da2037cc488ffa03b8254bce7c47572266b83ba"} build_url:https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012238 build_url: 'https://queue.taskcluster.net/v1/task/czTIvD0YSf-2MLQXcCRRNg/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/8eef7f65588628409540d5eef2fbedb9908684d5694838a5a4dd4fe7d75e33fc5b03487a90a73b5e946f18121bc565123b26a14f26546105c2b5457db1af7226", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16aef27889e59edc1d28d494886b5e70577853622379d0e17f0f3ab9f2361491a72672b1f93761b91f05ccc24c10a8b09b916e8efaea2e59b80b27de2b162d3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/82bbc0befc1d28b3d1f718ebd89683d7caf7dbf5e65675a34ccb27d3a3ab1cb324f73b34ac7407a1391c4f470da2037cc488ffa03b8254bce7c47572266b83ba"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 09:52:56.133563) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:52:56.133950) ========= 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.rRzW7Cx5E1/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.8KFl7Yk0wk/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005568 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:52:56.183420) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 09:52:56.183776) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 09:52:56.184091) ========= ========= Total master_lag: 0.18 =========