builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0163 starttime: 1469145496.1 results: success (0) buildid: 20160721144820 builduid: fb0d2f467d3543f18d34a808d054c000 revision: 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:16.100493) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:16.101233) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:16.101791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:16.130369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:16.130926) ========= 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.utEXqAU3nL/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.ile810U4Ki/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-21 16:58:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.95M=0.001s 2016-07-21 16:58:16 (7.95 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144365 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:16.300149) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:16.300703) ========= 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.utEXqAU3nL/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.ile810U4Ki/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.020688 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:16.351865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 16:58:16.352557) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.utEXqAU3nL/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.ile810U4Ki/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-21 16:58:16,431 truncating revision to first 12 chars 2016-07-21 16:58:16,431 Setting DEBUG logging. 2016-07-21 16:58:16,431 attempt 1/10 2016-07-21 16:58:16,431 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/10501352b0ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 16:58:17,595 unpacking tar archive at: mozilla-central-10501352b0ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.417342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 16:58:17.793357) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:17.793668) ========= script_repo_revision: 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:17.797101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:17.797391) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 16:58:17.816289) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-07-21 16:58:17.817019) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.utEXqAU3nL/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.ile810U4Ki/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:58:18 INFO - MultiFileLogger online at 20160721 16:58:18 in /builds/slave/test 16:58:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:58:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:58:18 INFO - {'append_to_log': False, 16:58:18 INFO - 'base_work_dir': '/builds/slave/test', 16:58:18 INFO - 'blob_upload_branch': 'Firefox', 16:58:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:58:18 INFO - 'branch': 'Firefox', 16:58:18 INFO - 'buildbot_json_path': 'buildprops.json', 16:58:18 INFO - 'config_files': ('talos/mac_config.py',), 16:58:18 INFO - 'default_actions': ('clobber', 16:58:18 INFO - 'read-buildbot-config', 16:58:18 INFO - 'download-and-extract', 16:58:18 INFO - 'populate-webroot', 16:58:18 INFO - 'create-virtualenv', 16:58:18 INFO - 'install', 16:58:18 INFO - 'run-tests'), 16:58:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:58:18 INFO - 'download_minidump_stackwalk': True, 16:58:18 INFO - 'download_symbols': 'ondemand', 16:58:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:58:18 INFO - 'tooltool.py': '/tools/tooltool.py', 16:58:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:58:18 INFO - '/tools/misc-python/virtualenv.py')}, 16:58:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:58:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:58:18 INFO - 'installer_path': 'installer.exe', 16:58:18 INFO - 'log_level': 'info', 16:58:18 INFO - 'log_name': 'talos', 16:58:18 INFO - 'log_to_console': True, 16:58:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:58:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:58:18 INFO - 'opt_config_files': (), 16:58:18 INFO - 'pip_index': False, 16:58:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:58:18 INFO - 'cmd': ('bash', 16:58:18 INFO - '-c', 16:58:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:58:18 INFO - 'enabled': True, 16:58:18 INFO - 'halt_on_failure': False, 16:58:18 INFO - 'name': 'check_screen_resolution'},), 16:58:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:58:18 INFO - 'cmd': ('bash', 16:58:18 INFO - '-c', 16:58:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:58:18 INFO - 'enabled': True, 16:58:18 INFO - 'halt_on_failure': False, 16:58:18 INFO - 'name': 'check_screen_resolution'},), 16:58:18 INFO - 'run_cmd_checks_enabled': True, 16:58:18 INFO - 'sps_profile': False, 16:58:18 INFO - 'sps_profile_interval': 0, 16:58:18 INFO - 'suite': 'dromaeojs', 16:58:18 INFO - 'system_bits': '32', 16:58:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:58:18 INFO - 'title': 't-yosemite-r7-0163', 16:58:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:58:18 INFO - 'use_talos_json': True, 16:58:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:58:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:58:18 INFO - 'work_dir': 'build'} 16:58:18 INFO - [mozharness: 2016-07-21 23:58:18.011730Z] Running clobber step. 16:58:18 INFO - Running pre-action listener: _resource_record_pre_action 16:58:18 INFO - Running main action method: clobber 16:58:18 INFO - rmtree: /builds/slave/test/build 16:58:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:58:19 INFO - Running post-action listener: _resource_record_post_action 16:58:19 INFO - [mozharness: 2016-07-21 23:58:19.409117Z] Finished clobber step (success) 16:58:19 INFO - [mozharness: 2016-07-21 23:58:19.409339Z] Running read-buildbot-config step. 16:58:19 INFO - Running pre-action listener: _resource_record_pre_action 16:58:19 INFO - Running main action method: read_buildbot_config 16:58:19 INFO - Using buildbot properties: 16:58:19 INFO - { 16:58:19 INFO - "project": "", 16:58:19 INFO - "product": "firefox", 16:58:19 INFO - "script_repo_revision": "production", 16:58:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:58:19 INFO - "repository": "", 16:58:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:58:19 INFO - "buildid": "20160721144820", 16:58:19 INFO - "pgo_build": "False", 16:58:19 INFO - "basedir": "/builds/slave/test", 16:58:19 INFO - "buildnumber": 39, 16:58:19 INFO - "slavename": "t-yosemite-r7-0163", 16:58:19 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:58:19 INFO - "platform": "yosemite_r7", 16:58:19 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:58:19 INFO - "branch": "mozilla-central", 16:58:19 INFO - "repo_path": "mozilla-central", 16:58:19 INFO - "revision": "10501352b0eef4ecf19dd4d5b1d307ebb212cf5e", 16:58:19 INFO - "stage_platform": "macosx64", 16:58:19 INFO - "builduid": "fb0d2f467d3543f18d34a808d054c000", 16:58:19 INFO - "slavebuilddir": "test" 16:58:19 INFO - } 16:58:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:58:19 INFO - Running post-action listener: _resource_record_post_action 16:58:19 INFO - [mozharness: 2016-07-21 23:58:19.413585Z] Finished read-buildbot-config step (success) 16:58:19 INFO - [mozharness: 2016-07-21 23:58:19.413710Z] Running download-and-extract step. 16:58:19 INFO - Running pre-action listener: _resource_record_pre_action 16:58:19 INFO - Running main action method: download_and_extract 16:58:19 INFO - mkdir: /builds/slave/test/build/tests 16:58:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:58:19 INFO - https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:58:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:58:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:58:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:58:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 16:58:42 INFO - Downloaded 1199 bytes. 16:58:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:58:42 INFO - Using the following test package requirements: 16:58:42 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:58:42 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:58:42 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:58:42 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:58:42 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:58:42 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:58:42 INFO - u'jsshell-mac.zip'], 16:58:42 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:58:42 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:58:42 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:58:42 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:58:42 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:58:42 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:58:42 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:58:42 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:58:42 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:58:42 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:58:42 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:58:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:58:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:58:42 INFO - https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:58:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:58:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:58:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:58:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:58:44 INFO - Downloaded 18806575 bytes. 16:58:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:58:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:58:47 INFO - Return code: 0 16:58:47 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:58:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:58:47 INFO - https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:58:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:58:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:58:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:58:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:58:50 INFO - Downloaded 18806575 bytes. 16:58:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:58:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:58:54 INFO - Return code: 0 16:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:58:54 INFO - https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:58:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:58:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:58:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:58:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:58:55 INFO - Downloaded 14403317 bytes. 16:58:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:58:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:58:55 INFO - Return code: 0 16:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:58:55 INFO - https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:58:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:58:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:58:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:58:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 16:58:58 INFO - Downloaded 112791928 bytes. 16:58:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:58:58 INFO - mkdir: /builds/slave/test/properties 16:58:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:58:58 INFO - Writing to file /builds/slave/test/properties/build_url 16:58:58 INFO - Contents: 16:58:58 INFO - build_url:https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:58:59 INFO - Running post-action listener: _resource_record_post_action 16:58:59 INFO - Running post-action listener: set_extra_try_arguments 16:58:59 INFO - [mozharness: 2016-07-21 23:58:59.430766Z] Finished download-and-extract step (success) 16:58:59 INFO - [mozharness: 2016-07-21 23:58:59.430917Z] Running populate-webroot step. 16:58:59 INFO - Running pre-action listener: _resource_record_pre_action 16:58:59 INFO - Running main action method: populate_webroot 16:58:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:58:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:58:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:58:59 INFO - '--tppagecycles', 16:58:59 INFO - '7'], 16:58:59 INFO - 'tests': ['tsvgm']}}, 16:58:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:58:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:58:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:58:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:58:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:59 INFO - 'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:58:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:58:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:59 INFO - 'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['damp', 'tps']}, 16:58:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:59 INFO - 'tests': ['damp', 'tps']}, 16:58:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['dromaeo_dom']}, 16:58:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:58:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['basic_compositor_video']}, 16:58:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:58:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['a11yr', 16:58:59 INFO - 'ts_paint', 16:58:59 INFO - 'tpaint', 16:58:59 INFO - 'sessionrestore', 16:58:59 INFO - 'sessionrestore_no_auto_restore', 16:58:59 INFO - 'tabpaint']}, 16:58:59 INFO - 'other-e10s': {'tests': ['a11yr', 16:58:59 INFO - 'ts_paint', 16:58:59 INFO - 'tpaint', 16:58:59 INFO - 'sessionrestore', 16:58:59 INFO - 'sessionrestore_no_auto_restore', 16:58:59 INFO - 'tabpaint']}, 16:58:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:58:59 INFO - 'ts_paint', 16:58:59 INFO - 'tpaint', 16:58:59 INFO - 'sessionrestore', 16:58:59 INFO - 'sessionrestore_no_auto_restore', 16:58:59 INFO - 'tabpaint']}, 16:58:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:58:59 INFO - 'ts_paint', 16:58:59 INFO - 'tpaint', 16:58:59 INFO - 'sessionrestore', 16:58:59 INFO - 'sessionrestore_no_auto_restore', 16:58:59 INFO - 'tabpaint']}, 16:58:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['a11yr', 16:58:59 INFO - 'ts_paint', 16:58:59 INFO - 'tpaint', 16:58:59 INFO - 'sessionrestore', 16:58:59 INFO - 'sessionrestore_no_auto_restore', 16:58:59 INFO - 'tabpaint']}, 16:58:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['a11yr', 16:58:59 INFO - 'ts_paint', 16:58:59 INFO - 'tpaint', 16:58:59 INFO - 'sessionrestore', 16:58:59 INFO - 'sessionrestore_no_auto_restore', 16:58:59 INFO - 'tabpaint']}, 16:58:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['tsvgx', 16:58:59 INFO - 'tsvgr_opacity', 16:58:59 INFO - 'tart', 16:58:59 INFO - 'tscrollx', 16:58:59 INFO - 'cart']}, 16:58:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:58:59 INFO - 'tsvgr_opacity', 16:58:59 INFO - 'tart', 16:58:59 INFO - 'tscrollx', 16:58:59 INFO - 'cart']}, 16:58:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:59 INFO - 'talos_options': ['--disable-e10s'], 16:58:59 INFO - 'tests': ['tp5o']}, 16:58:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:59 INFO - 'tests': ['tp5o']}, 16:58:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:59 INFO - 'talos_options': ['--disable-e10s', 16:58:59 INFO - '--xperf_path', 16:58:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:58:59 INFO - 'tests': ['tp5n']}, 16:58:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:58:59 INFO - 'talos_options': ['--xperf_path', 16:58:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:58:59 INFO - 'tests': ['tp5n']}}, 16:58:59 INFO - 'talos.zip': {'path': '', 16:58:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:58:59 INFO - Running post-action listener: _resource_record_post_action 16:58:59 INFO - [mozharness: 2016-07-21 23:58:59.449555Z] Finished populate-webroot step (success) 16:58:59 INFO - [mozharness: 2016-07-21 23:58:59.449659Z] Running create-virtualenv step. 16:58:59 INFO - Running pre-action listener: _resource_record_pre_action 16:58:59 INFO - Running main action method: create_virtualenv 16:58:59 INFO - Creating virtualenv /builds/slave/test/build/venv 16:58:59 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 16:58:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:58:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:58:59 INFO - Using real prefix '/tools/python27' 16:58:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:59:00 INFO - Installing distribute.............................................................................................................................................................................................done. 16:59:03 INFO - Installing pip.................done. 16:59:03 INFO - Return code: 0 16:59:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:03 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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:03 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 16:59:03 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 16:59:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:03 INFO - 'HOME': '/Users/cltbld', 16:59:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:03 INFO - 'LOGNAME': 'cltbld', 16:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:03 INFO - 'MOZ_NO_REMOTE': '1', 16:59:03 INFO - 'NO_EM_RESTART': '1', 16:59:03 INFO - 'PAGER': '/bin/cat', 16:59:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:03 INFO - 'PWD': '/builds/slave/test', 16:59:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:03 INFO - 'SHELL': '/bin/bash', 16:59:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:03 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:03 INFO - 'USER': 'cltbld', 16:59:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:03 INFO - 'XPC_FLAGS': '0x0', 16:59:03 INFO - 'XPC_SERVICE_NAME': '0', 16:59:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:03 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 16:59:03 INFO - Cleaning up... 16:59:03 INFO - Return code: 0 16:59:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:03 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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:03 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 16:59:03 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 16:59:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:03 INFO - 'HOME': '/Users/cltbld', 16:59:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:03 INFO - 'LOGNAME': 'cltbld', 16:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:03 INFO - 'MOZ_NO_REMOTE': '1', 16:59:03 INFO - 'NO_EM_RESTART': '1', 16:59:03 INFO - 'PAGER': '/bin/cat', 16:59:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:03 INFO - 'PWD': '/builds/slave/test', 16:59:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:03 INFO - 'SHELL': '/bin/bash', 16:59:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:03 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:03 INFO - 'USER': 'cltbld', 16:59:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:03 INFO - 'XPC_FLAGS': '0x0', 16:59:03 INFO - 'XPC_SERVICE_NAME': '0', 16:59:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:03 INFO - Downloading/unpacking psutil>=3.1.1 16:59:03 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 16:59:03 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 16:59:03 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 16:59:03 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 16:59:03 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 16:59:03 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 16:59:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:59:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:59:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:59:06 INFO - Installing collected packages: psutil 16:59:06 INFO - Running setup.py install for psutil 16:59:06 INFO - building 'psutil._psutil_osx' extension 16:59:06 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 16:59:06 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 16:59:07 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 16:59:07 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 16:59:07 INFO - building 'psutil._psutil_posix' extension 16:59:07 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 16:59:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:59:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:59:07 INFO - ^ 16:59:07 INFO - 1 warning generated. 16:59:07 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 16:59:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:59:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:59:07 INFO - Successfully installed psutil 16:59:07 INFO - Cleaning up... 16:59:07 INFO - Return code: 0 16:59:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:59:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:07 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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:07 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 16:59:07 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 16:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:07 INFO - 'HOME': '/Users/cltbld', 16:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:07 INFO - 'LOGNAME': 'cltbld', 16:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:07 INFO - 'MOZ_NO_REMOTE': '1', 16:59:07 INFO - 'NO_EM_RESTART': '1', 16:59:07 INFO - 'PAGER': '/bin/cat', 16:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:07 INFO - 'PWD': '/builds/slave/test', 16:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:07 INFO - 'SHELL': '/bin/bash', 16:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:07 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:07 INFO - 'USER': 'cltbld', 16:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:07 INFO - 'XPC_FLAGS': '0x0', 16:59:07 INFO - 'XPC_SERVICE_NAME': '0', 16:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:59:07 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 16:59:07 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 16:59:07 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 16:59:07 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 16:59:07 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 16:59:07 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 16:59:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:59:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:59:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:59:10 INFO - Installing collected packages: mozsystemmonitor 16:59:10 INFO - Running setup.py install for mozsystemmonitor 16:59:10 INFO - Successfully installed mozsystemmonitor 16:59:10 INFO - Cleaning up... 16:59:10 INFO - Return code: 0 16:59:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:59:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:10 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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:10 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 16:59:10 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 16:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:10 INFO - 'HOME': '/Users/cltbld', 16:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:10 INFO - 'LOGNAME': 'cltbld', 16:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:10 INFO - 'MOZ_NO_REMOTE': '1', 16:59:10 INFO - 'NO_EM_RESTART': '1', 16:59:10 INFO - 'PAGER': '/bin/cat', 16:59:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:10 INFO - 'PWD': '/builds/slave/test', 16:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:10 INFO - 'SHELL': '/bin/bash', 16:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:10 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:10 INFO - 'USER': 'cltbld', 16:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:10 INFO - 'XPC_FLAGS': '0x0', 16:59:10 INFO - 'XPC_SERVICE_NAME': '0', 16:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:10 INFO - Downloading/unpacking jsonschema==2.5.1 16:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:59:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:59:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:59:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:59:13 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 16:59:13 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 16:59:13 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 16:59:13 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 16:59:13 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 16:59:13 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 16:59:13 INFO - Downloading functools32-3.2.3-2.tar.gz 16:59:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:59:13 INFO - warning: no files found matching '*.txt' 16:59:13 INFO - no previously-included directories found matching 'build' 16:59:13 INFO - no previously-included directories found matching 'dist' 16:59:13 INFO - no previously-included directories found matching '.git*' 16:59:13 INFO - Installing collected packages: jsonschema, functools32 16:59:13 INFO - Running setup.py install for functools32 16:59:13 INFO - warning: no files found matching '*.txt' 16:59:13 INFO - no previously-included directories found matching 'build' 16:59:13 INFO - no previously-included directories found matching 'dist' 16:59:13 INFO - no previously-included directories found matching '.git*' 16:59:13 INFO - Successfully installed jsonschema functools32 16:59:13 INFO - Cleaning up... 16:59:14 INFO - Return code: 0 16:59:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:59:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:14 INFO - 'HOME': '/Users/cltbld', 16:59:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:14 INFO - 'LOGNAME': 'cltbld', 16:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:14 INFO - 'MOZ_NO_REMOTE': '1', 16:59:14 INFO - 'NO_EM_RESTART': '1', 16:59:14 INFO - 'PAGER': '/bin/cat', 16:59:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:14 INFO - 'PWD': '/builds/slave/test', 16:59:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:14 INFO - 'SHELL': '/bin/bash', 16:59:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:14 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:14 INFO - 'USER': 'cltbld', 16:59:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:14 INFO - 'XPC_FLAGS': '0x0', 16:59:14 INFO - 'XPC_SERVICE_NAME': '0', 16:59:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:14 INFO - Downloading/unpacking blobuploader==1.2.4 16:59:14 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 16:59:14 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 16:59:14 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 16:59:14 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 16:59:14 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 16:59:14 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 16:59:16 INFO - Downloading blobuploader-1.2.4.tar.gz 16:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:59:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:59:16 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 16:59:16 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 16:59:16 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 16:59:16 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 16:59:16 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 16:59:16 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 16:59:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:59:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:59:17 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 16:59:17 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 16:59:17 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 16:59:17 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 16:59:17 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 16:59:17 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 16:59:17 INFO - Downloading docopt-0.6.1.tar.gz 16:59:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:59:17 INFO - Installing collected packages: blobuploader, requests, docopt 16:59:17 INFO - Running setup.py install for blobuploader 16:59:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:59:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:59:18 INFO - Running setup.py install for requests 16:59:18 INFO - Running setup.py install for docopt 16:59:18 INFO - Successfully installed blobuploader requests docopt 16:59:18 INFO - Cleaning up... 16:59:18 INFO - Return code: 0 16:59:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:18 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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:18 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 16:59:18 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 16:59:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:18 INFO - 'HOME': '/Users/cltbld', 16:59:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:18 INFO - 'LOGNAME': 'cltbld', 16:59:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:18 INFO - 'MOZ_NO_REMOTE': '1', 16:59:18 INFO - 'NO_EM_RESTART': '1', 16:59:18 INFO - 'PAGER': '/bin/cat', 16:59:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:18 INFO - 'PWD': '/builds/slave/test', 16:59:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:18 INFO - 'SHELL': '/bin/bash', 16:59:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:18 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:18 INFO - 'USER': 'cltbld', 16:59:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:18 INFO - 'XPC_FLAGS': '0x0', 16:59:18 INFO - 'XPC_SERVICE_NAME': '0', 16:59:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-K0GHA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-vdLjav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-mXCJdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-aASQAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-w_5_kP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-UD2_6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-5Befin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-VuPgVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:59:19 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Lp9oS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:59:20 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-BR5PzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:59:20 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-IK_BpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:59:20 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-wupjTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:59:20 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-TU7dTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:59:20 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-nWk5hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:20 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-8ktoP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:59:20 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-VaDFnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:59:20 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-2Z11D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:59:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:59:20 INFO - Running setup.py install for manifestparser 16:59:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:59:21 INFO - Running setup.py install for mozcrash 16:59:21 INFO - Running setup.py install for mozdebug 16:59:21 INFO - Running setup.py install for mozdevice 16:59:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:59:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:59:21 INFO - Running setup.py install for mozfile 16:59:21 INFO - Running setup.py install for mozhttpd 16:59:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:59:21 INFO - Running setup.py install for mozinfo 16:59:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:59:21 INFO - Running setup.py install for mozInstall 16:59:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Running setup.py install for mozleak 16:59:22 INFO - Running setup.py install for mozlog 16:59:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Running setup.py install for moznetwork 16:59:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Running setup.py install for mozprocess 16:59:22 INFO - Running setup.py install for mozprofile 16:59:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:59:22 INFO - Running setup.py install for mozrunner 16:59:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:59:23 INFO - Running setup.py install for mozscreenshot 16:59:23 INFO - Running setup.py install for moztest 16:59:23 INFO - Running setup.py install for mozversion 16:59:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:59:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:59:23 INFO - Cleaning up... 16:59:23 INFO - Return code: 0 16:59:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:23 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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:23 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 16:59:23 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 16:59:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:23 INFO - 'HOME': '/Users/cltbld', 16:59:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:23 INFO - 'LOGNAME': 'cltbld', 16:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:23 INFO - 'MOZ_NO_REMOTE': '1', 16:59:23 INFO - 'NO_EM_RESTART': '1', 16:59:23 INFO - 'PAGER': '/bin/cat', 16:59:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:23 INFO - 'PWD': '/builds/slave/test', 16:59:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:23 INFO - 'SHELL': '/bin/bash', 16:59:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:23 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:23 INFO - 'USER': 'cltbld', 16:59:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:23 INFO - 'XPC_FLAGS': '0x0', 16:59:23 INFO - 'XPC_SERVICE_NAME': '0', 16:59:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:59:24 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-4K0o3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:59:24 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)) 16:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:59:24 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-rzPN80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:59:24 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)) 16:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:59:24 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-qyow2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:59:24 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)) 16:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:59:24 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-pWKWMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:59:24 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)) 16:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:59:24 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-wB49HB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:59:24 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)) 16:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:59:24 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-47sQrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:59:24 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)) 16:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:59:24 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-XzKQ8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-MXzxUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-OVNgBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-RNLuEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:59:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-12YslG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-NnOj_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-WZiiZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-sihl6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-JoIRU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-MP2Flp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:59:25 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)) 16:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:59:25 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-EDaWkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:59:26 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)) 16:59:26 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)) 16:59:26 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)) 16:59:26 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)) 16:59:26 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)) 16:59:26 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)) 16:59:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:59:26 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 16:59:26 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 16:59:26 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 16:59:26 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 16:59:26 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 16:59:26 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 16:59:28 INFO - Downloading blessings-1.6.tar.gz 16:59:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:59:28 INFO - Installing collected packages: blessings 16:59:28 INFO - Running setup.py install for blessings 16:59:29 INFO - Successfully installed blessings 16:59:29 INFO - Cleaning up... 16:59:29 INFO - Return code: 0 16:59:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:59:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:59:29 INFO - Reading from file tmpfile_stdout 16:59:29 INFO - Current package versions: 16:59:29 INFO - blessings == 1.6 16:59:29 INFO - blobuploader == 1.2.4 16:59:29 INFO - docopt == 0.6.1 16:59:29 INFO - functools32 == 3.2.3-2 16:59:29 INFO - jsonschema == 2.5.1 16:59:29 INFO - manifestparser == 1.1 16:59:29 INFO - mozInstall == 1.12 16:59:29 INFO - mozcrash == 0.17 16:59:29 INFO - mozdebug == 0.1 16:59:29 INFO - mozdevice == 0.48 16:59:29 INFO - mozfile == 1.2 16:59:29 INFO - mozhttpd == 0.7 16:59:29 INFO - mozinfo == 0.9 16:59:29 INFO - mozleak == 0.1 16:59:29 INFO - mozlog == 3.3 16:59:29 INFO - moznetwork == 0.27 16:59:29 INFO - mozprocess == 0.23 16:59:29 INFO - mozprofile == 0.28 16:59:29 INFO - mozrunner == 6.12 16:59:29 INFO - mozscreenshot == 0.1 16:59:29 INFO - mozsystemmonitor == 0.3 16:59:29 INFO - moztest == 0.7 16:59:29 INFO - mozversion == 1.4 16:59:29 INFO - psutil == 3.1.1 16:59:29 INFO - requests == 1.2.3 16:59:29 INFO - wsgiref == 0.1.2 16:59:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:59:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:29 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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:29 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 16:59:29 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 16:59:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:29 INFO - 'HOME': '/Users/cltbld', 16:59:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:29 INFO - 'LOGNAME': 'cltbld', 16:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:29 INFO - 'MOZ_NO_REMOTE': '1', 16:59:29 INFO - 'NO_EM_RESTART': '1', 16:59:29 INFO - 'PAGER': '/bin/cat', 16:59:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:29 INFO - 'PWD': '/builds/slave/test', 16:59:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:29 INFO - 'SHELL': '/bin/bash', 16:59:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:29 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:29 INFO - 'USER': 'cltbld', 16:59:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:29 INFO - 'XPC_FLAGS': '0x0', 16:59:29 INFO - 'XPC_SERVICE_NAME': '0', 16:59:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 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)) 16:59:29 INFO - Cleaning up... 16:59:29 INFO - Return code: 0 16:59:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:59:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:59:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:59:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:59:29 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 0x102a03a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a98618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1d3c76720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, '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.utEXqAU3nL/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.ile810U4Ki/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 16:59:29 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 16:59:29 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 16:59:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 16:59:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:59:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:59:29 INFO - 'HOME': '/Users/cltbld', 16:59:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:59:29 INFO - 'LOGNAME': 'cltbld', 16:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:59:29 INFO - 'MOZ_NO_REMOTE': '1', 16:59:29 INFO - 'NO_EM_RESTART': '1', 16:59:29 INFO - 'PAGER': '/bin/cat', 16:59:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:59:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:59:29 INFO - 'PWD': '/builds/slave/test', 16:59:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:59:29 INFO - 'SHELL': '/bin/bash', 16:59:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 16:59:29 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 16:59:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:59:29 INFO - 'USER': 'cltbld', 16:59:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:59:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:59:29 INFO - 'XPC_FLAGS': '0x0', 16:59:29 INFO - 'XPC_SERVICE_NAME': '0', 16:59:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:59:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:59:30 INFO - Cleaning up... 16:59:30 INFO - Return code: 0 16:59:30 INFO - Running post-action listener: _resource_record_post_action 16:59:30 INFO - Running post-action listener: _start_resource_monitoring 16:59:30 INFO - Starting resource monitoring. 16:59:30 INFO - [mozharness: 2016-07-21 23:59:30.126659Z] Finished create-virtualenv step (success) 16:59:30 INFO - [mozharness: 2016-07-21 23:59:30.127598Z] Running install step. 16:59:30 INFO - Running pre-action listener: _resource_record_pre_action 16:59:30 INFO - Running main action method: install 16:59:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:59:30 INFO - Reading from file tmpfile_stdout 16:59:30 INFO - Detecting whether we're running mozinstall >=1.0... 16:59:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:59:30 INFO - Reading from file tmpfile_stdout 16:59:30 INFO - Output received: 16:59:30 INFO - Usage: mozinstall [options] installer 16:59:30 INFO - Options: 16:59:30 INFO - -h, --help show this help message and exit 16:59:30 INFO - -d DEST, --destination=DEST 16:59:30 INFO - Directory to install application into. [default: 16:59:30 INFO - "/builds/slave/test"] 16:59:30 INFO - --app=APP Application being installed. [default: firefox] 16:59:30 INFO - mkdir: /builds/slave/test/build/application 16:59:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:59:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:00:05 INFO - Reading from file tmpfile_stdout 17:00:05 INFO - Output received: 17:00:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:00:05 INFO - Running post-action listener: _resource_record_post_action 17:00:05 INFO - [mozharness: 2016-07-22 00:00:05.555418Z] Finished install step (success) 17:00:05 INFO - [mozharness: 2016-07-22 00:00:05.555542Z] Running run-tests step. 17:00:05 INFO - Running pre-action listener: _resource_record_pre_action 17:00:05 INFO - Running main action method: run_tests 17:00:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:00:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:00:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:00:05 INFO - 2016-07-21 17:00:05.570 screenresolution[1935:15932] starting screenresolution argv=screenresolution get 17:00:05 INFO - 2016-07-21 17:00:05.572 screenresolution[1935:15932] Display 0: 1600x1200x32@60 17:00:05 INFO - 2016-07-21 17:00:05.578 screenresolution[1936:15934] starting screenresolution argv=screenresolution list 17:00:05 INFO - Available Modes on Display 0 17:00:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:00:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:00:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:00:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:00:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:00:05 INFO - Intel Iris: 17:00:05 INFO - Chipset Model: Intel Iris 17:00:05 INFO - Type: GPU 17:00:05 INFO - Bus: Built-In 17:00:05 INFO - VRAM (Dynamic, Max): 1536 MB 17:00:05 INFO - Vendor: Intel (0x8086) 17:00:05 INFO - Device ID: 0x0a2e 17:00:05 INFO - Revision ID: 0x0009 17:00:05 INFO - Displays: 17:00:05 INFO - Display: 17:00:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:00:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:00:05 INFO - Display Serial Number: 1600x1200 60 17:00:05 INFO - Main Display: Yes 17:00:05 INFO - Mirror: Off 17:00:05 INFO - Online: Yes 17:00:05 INFO - Rotation: Supported 17:00:05 INFO - Return code: 0 17:00:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:00:05 INFO - Python 2.7.3 17:00:05 INFO - Return code: 0 17:00:05 INFO - grabbing minidump binary from tooltool 17:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:00:05 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 0x102967d30>, '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 0x1026ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10295fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:00:05 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 17:00:05 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 17:00:05 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 17:00:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:00:05 INFO - Return code: 0 17:00:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:00:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:00:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:00:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:00:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:00:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 17:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0163 --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 17:00:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.utEXqAU3nL/Render', 17:00:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:05 INFO - 'HOME': '/Users/cltbld', 17:00:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:05 INFO - 'LOGNAME': 'cltbld', 17:00:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:00:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:05 INFO - 'MOZ_NO_REMOTE': '1', 17:00:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:00:05 INFO - 'NO_EM_RESTART': '1', 17:00:05 INFO - 'PAGER': '/bin/cat', 17:00:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:05 INFO - 'PWD': '/builds/slave/test', 17:00:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:00:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:05 INFO - 'SHELL': '/bin/bash', 17:00:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ile810U4Ki/Listeners', 17:00:05 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 17:00:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:05 INFO - 'USER': 'cltbld', 17:00:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:05 INFO - 'XPC_FLAGS': '0x0', 17:00:05 INFO - 'XPC_SERVICE_NAME': '0', 17:00:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:00:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 17:00:06 INFO - mozversion application_buildid: 20160721144820 17:00:06 INFO - mozversion application_changeset: 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e 17:00:06 INFO - mozversion application_display_name: Nightly 17:00:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:00:06 INFO - mozversion application_name: Firefox 17:00:06 INFO - mozversion application_remotingname: firefox 17:00:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:00:06 INFO - mozversion application_vendor: Mozilla 17:00:06 INFO - mozversion application_version: 50.0a1 17:00:06 INFO - mozversion platform_buildid: 20160721144820 17:00:06 INFO - mozversion platform_changeset: 10501352b0eef4ecf19dd4d5b1d307ebb212cf5e 17:00:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:00:06 INFO - mozversion platform_version: 50.0a1 17:00:06 INFO - using testdate: 1469145606 17:00:06 INFO - actual date: 1469145606 17:00:06 INFO - starting webserver on 'localhost:52106' 17:00:06 INFO - SUITE-START | Running 2 tests 17:00:06 INFO - TEST-START | dromaeo_css 17:00:06 INFO - operating with platform_type : mac_ 17:00:06 INFO - Initialising browser for dromaeo_css test... 17:00:06 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpqnLdkS/profile http://localhost:52106/getInfo.html) 17:00:08 INFO - PROCESS | 1946 | JavaScript error: , line 0: uncaught exception: undefined 17:00:08 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 17:00:08 INFO - PROCESS | 1946 | colorDepth:24 17:00:08 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 17:00:08 INFO - PROCESS | 1946 | __metrics 17:00:08 INFO - PROCESS | 1946 | 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" 17:00:08 INFO - PROCESS | 1946 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:00:09 INFO - TEST-INFO | 1946: exit 0 17:00:09 INFO - Browser initialized. 17:00:09 INFO - Running cycle 1/1 for dromaeo_css test... 17:00:09 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpqnLdkS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:00:21 INFO - PROCESS | 1948 | RSS: Main: 245559296 17:00:21 INFO - PROCESS | 1948 | 17:01:13 INFO - PROCESS | 1948 | 1469145673301 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] 17:03:23 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52106/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52106/tests/dromaeo/cssquery-ext.html) 17:03:25 INFO - PROCESS | 1948 | RSS: Main: 367116288 17:03:25 INFO - PROCESS | 1948 | 17:06:28 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52106/tests/dromaeo/cssquery-ext.html (next: http://localhost:52106/tests/dromaeo/cssquery-jquery.html) 17:06:29 INFO - PROCESS | 1948 | RSS: Main: 388988928 17:06:29 INFO - PROCESS | 1948 | 17:09:31 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52106/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52106/tests/dromaeo/cssquery-mootools.html) 17:09:32 INFO - PROCESS | 1948 | RSS: Main: 379924480 17:09:32 INFO - PROCESS | 1948 | 17:12:34 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52106/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52106/tests/dromaeo/cssquery-prototype.html) 17:12:34 INFO - PROCESS | 1948 | RSS: Main: 381845504 17:12:34 INFO - PROCESS | 1948 | 17:15:38 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52106/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52106/tests/dromaeo/cssquery-yui.html) 17:15:39 INFO - PROCESS | 1948 | RSS: Main: 347111424 17:15:39 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52106/tests/dromaeo/cssquery-yui.html (next: http://localhost:52106/tests/dromaeo/cssquery-dojo.html) 17:18:42 INFO - PROCESS | 1948 | RSS: Main: 396062720 17:18:42 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | __start_tp_report 17:18:42 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 17:18:42 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 17:18:42 INFO - PROCESS | 1948 | |i|pagename|runs| 17:18:42 INFO - PROCESS | 1948 | |0;dojo.html;3374.342003309354;3378.9493157602637;3382.680754505742;3400.5239266502676;3443.3113377324526;5598.580106492019;5684.5168160706335;5734.541236701064;5780.797672081478;5855.707214639267;5721.799737009205;5744.741486633101;5755.827325180241;5765.423457654232;5773.249477567921;5179.4561571035065;5216.191490318987;5252.6323157379;5257.7108259045735;5304.045271851065;5303.602229832759;5304.549113325353;5305.549028332577;5309.5752339812725;5312;5208.036513245042;5246.947530524684;5254.159334506479;5279.313689220396;5284.709340986245;5338.652987555835;5367.490088441592;5368.731563421852;5371.838844834622;5377.4084850666395;5085.974570127163;5105.617078719074;5178.430372659004;5271.103912334912;5278.261043453919;5691.800786972478;5696.344920334173;5696.3733989261145;5743.913841292384;5788.652680839163;6189.102580125901;6252.3747625237565;6271.655058971754;6272.717727702265;6273.686315684243;6138.447539721402;6183.165272688175;6245.812625621239;6257.968710156466;6262.96661050926;5728.770849166029;5788.073908174714;5829.795957141565;5850.707464626752;5919.230500035054;6315.305316415191;6325.082862984934;6325.240971083499;6325.557210995186;6349.111124442582;6063.757449701962;6174.413430724074;6177.62934223948;6178.382161783786;6179.969100154471;6138.416850399205;6186.319504854462;6197.783077592353;6200.56596038273;6294.276158241861;6255.40573645503;6269.247690277105;6274.152989346379;6278.089676996809;6285.497160227171;6216.564840461215;6264.467520260738;6276.717547710364;6279.560430769893;6287.591306565058;4877.341558889575;4891.633127515451;4916.729579873141;5019.749012549358;5024.598032157419;4448.755318457451;4462.129884672458;4466.798994045275;4470.645544515066;4509.323601459741;5881.735321910524;5883.7058147092475;5884.47039766423;5889.028310328834;5953.76184952597;6163.506919446433;6168.568200225941;6175.3515880832765;6185.938140618626;6195.81412557624;5773.88452230953;5779.162021506942;5791.797287095016;5806.506446951972;5825.097109947965;5440.075187218183;5440.455954404528;5441.646292991022;5454.181872719124;5458.590605704588;6221.968890155611;6280.183576135073;6303.810885673437;6313.305536390993;6337.841553961188;5849.356570777296;6186.753442596979;6242.719077641426;6247;6250.249970003629;6363.809085727436;6394.968025159845;6400.935990640221;6402.583832050902;6411.935880641134;6086.69566521681;6119.868162944292;6157.815265542041;6235.750569977332;6248.750049997949;6201.503879689524;6209.906851397323;6223.3465486123305;6226.719797609209;6242.406971337898;6160.722767475564;6165.383461653978;6169.290531588746;6181.84545386369;6194.752209911553;5836.445537673914;6016.578839481194;6018.909716354346;6150.630962142286;6177.382261773787;4547.52251013652;5891.646501209942;5892.469677728854;5898.852528686817;6000.069989151689;6112.235970503687;6119.44924956756;6121.441500740575;6124.5712800103565;6147.538934579835;5795.971020144872;5813.941860581509;5876.3536011039605;5952.494038006818;5983.072623743326 17:18:42 INFO - PROCESS | 1948 | |1;ext.html;511.872031992002;512.946140655231;515.1294312611689;528.3817933018371;528.497926969379;30193.245168870762;31935.680643193562;31982.720691172384;42281.36577951333;42971.35542966858;43406.348904766455;43780.78109609448;43880;44019.779901100446;44035;9716.562754676057;9871.555779989918;9878.802423951516;9885;9888.604455821795;8864.157904999014;9066.093390660948;11908.523659053628;12170.269783812955;12188.81716774249;12108.576199833007;12188.146829721923;12192.390380480985;12198.26810391375;12199.756004879942;12104.455299511543;12173.634790956285;12180.878191218151;12190.451429685685;12206.877931220663;8699.739007829776;8929;8931.64273429062;9059.773505662346;9063.50150741709;39504.40743388851;42232.366514502304;42375;42404.57595424037;42407;3466.843992020365;3615.114296997226;3684.244729830378;3727.1241258304276;4287.742735435852;31962;33110.66889331124;33113.6688633113;33169.170770730685;33251.00246992594;3234.1752853022563;3325.48454989477;3383.949240761366;3416.145963509123;3417.709494692954;524582.3770881159;525887;527154;528249;528793;61488;61781.69109154426;61802.38197617966;61897.690511548055;61936.380636193964;66069;66113.6694316535;66146.66926665336;66150.33849661538;66343.33656663468;16402.26189821461;16618.6676266474;16622.168891555375;16626.916865415595;16637.25132369022;11615;12857.842794148572;12932.612021639367;12937.2884491352;12946.093773435769;14061.648458788612;14651;14753.33609987553;16171.919140404223;16851.325946962228;5863.941360586425;6338.049292606146;6583.736650533925;6902.171739391205;6980.336867997532;811.9809638903257;825.6037102190982;1029.9897001030045;1055.963041293551;1088.8257878739364;30427.69572304293;31137.688623113478;31322.530162047588;31390.529142062886;32037.87867424629;942.9245660347156;980.2158273381267;1068.9198310126772;1138.4592318648577;1178.3401295274673;13033.348332583333;16033.788978642335;16076.517704468884;16207.351705931631;16395.508134755975;625370;628549;629561;630653;631112;46311.30533042007;51926;53118.20322695163;61030;61282;1402;1404.0662959132267;1448.2469116059801;1448.2903377345026;1448.4061534770904;31348.68651313549;32000.35999279955;32326.35347293088;32653.673463265062;33213.33573328568;51902.48097518976;57562.424375755705;59238;59302.70348648229;63250;560426;564236;566024;567901;568047;26433.33916652022;26866.925322986863;26882.462350753267;26903;26956.191314260603;38924;47837.76081119572;47993.56019319426;48667;49612;40218;40348.1930361397;41139.65760721405;41437.378439324035;41770;39669.00827479336;39985;41265;41294.58705412907;41307;38387.04032399212;39030.80484597559;39082.80458597689;39106.6089339103;39477.40783888187;34516;34807.82596087003;36064.410016447495;36160.09599760027;36201.818990904874;21285.6807147896;22418.775812241667;24219.878900605385;26035.95856165732;26136 17:18:42 INFO - PROCESS | 1948 | |2;jquery.html;335.20723488948636;357.1731441712434;379.1847527815196;382.6517868739446;400.13570687315405;15286.694266114671;16684.249208785604;20700.48248793781;21080.789192108074;21203.151873925042;19301;19869.20523179071;21118.683219751714;21145;21766;5215.504527069922;5307.071262529052;5449.5640348772;5480.068388373966;5487.533559647435;5286.154215325549;5422.810201642943;5430;5435.1847222916485;5439.836804895859;3873.5351757788962;5813.476787089161;6060.3939606039485;6105.358937311563;6136.662483563402;5813.651180929158;5953;6059.909101363483;6089.725962331706;6119.1739115219325;5900.114982752579;5900.55745819061;5903.881922361528;5932.436418540232;5954.761809527614;13365.66585835352;13415.865841341656;13491.932540337333;13523.864761352457;13642.454301827915;2088.0708084902226;2134.7011418401435;2196.3410976706923;2541.3392517945467;2709.376843326046;8772.210501054935;10360.689179324632;10429.21780866438;12009.939950300193;16289.104099274535;1869.9906500467546;1878.0891267735137;1907.3801014670148;1917.6260629177316;1942.3784388995527;111891;121473.17790233242;126585;127996;128741;9084.454932704059;11250.09999200062;11606.825897611543;11628;11797.587084452003;12102.697432564257;14680.853191467948;14694.19181944982;14711.779323310158;14722;8245.381596380295;8455.154484551496;8506.659733610586;8629.482231066157;8638.17937295956;7769.339606133429;7779.988601481884;8455.069942306465;8461.069282378934;8697;3684.705223582107;3710.220853620716;4539;4541.500434952152;4609.101225261109;1887.131919317102;1929.6588870734704;1938.3039840139877;1939.2340025689773;1939.4569520534274;866.3668983965146;867.8611422172548;912.5710915869531;984.522506584306;992.9553170107363;21350.57298854014;21696.891515542637;21764.673529897063;21822.563548729253;21916.23293184731;875.9036505984336;912.8356895758827;954.6467806911487;961.2694352292308;983.5033308179324;10698.946505267579;11195.944020280009;11272.492737826817;11354.943225283821;11456.599019034295;552438;553782.1497695412;556576;569404;588649;128646.35676821743;130052;130619;131977;132992.36572525595;878.8154487557684;970.4516947924541;983.2920297385871;1009.7172791618359;1009.8838633557235;19238.23047078157;19376.4187074388;19406.2237510498;19406.70889936624;19425.902870485555;44670;54055;57715.711421444306;58024;58201;78790;79924;80461.59769201117;81350.18649813427;83224;7590.127135379502;7668.80827979305;7971.12228403682;8187.549684767395;8338.24955753985;10499.47502624881;11183.72040698989;11382.829257561309;11605.89743974321;11872.228305160137;10885.782284354427;11020.779584408427;11490.712732181762;11608;11609.767804644029;10511.684649460529;10995.725106872393;10996.560137594406;11238.831417528907;11572.884271157518;9584;9932.900670993487;11390.373529455628;11609.013233874877;11639.359835209145;7847.349403553747;7975.361971042186;8029.3175080118845;8073.959630201812;8226;8085.272325490734;9622.95188524081;11244.66266012021;11377.658670239905;11560.537578496765 17:18:42 INFO - PROCESS | 1948 | |3;mootools.html;1383.5365152673853;1548.891577589569;1584.247482445838;1773.1577500687176;1899.5915878086216;6507.4143327100555;8289.253967142957;8292.419530632858;8294.212049855269;8295.875561866576;8067.47561408508;8348.499090054585;8697.782555436117;8698.217160455557;8802.119788021195;2348.4481146930502;2373.145667559686;2381.6189409694452;2381.630847218684;2385.403649087728;2319.489712263299;2319.675245465636;2380.785729284364;2383.3445802404303;2388.856668599881;2328.15022516781;2351.6119840226343;2363.4918492524102;2368.431576421655;2371.7983971362455;2319.895604697793;2348.6007378745653;2352.3766201956496;2352.5294941011866;2364.2907127861745;2317.2237300504353;2340.8595484271;2351.835371523994;2352.8353015288935;2354.564405584972;8751.956240218758;8756.730274110218;8823.470591764515;8832.249258813008;8844.425112367675;4927.137750893615;4954.752262386866;5225.686458812484;5375.435579264199;5384.623076384654;3143.4341818472667;3578.3380074686265;3663.816809159558;3675.246574452231;3681.834317455721;1074.3929679730927;1081.1404933078143;1098.4672433869566;1101.4052411697674;1113.120634698587;11543.076553875753;11673.1828771986;11678.357690327028;11678.88321116795;11686.357250351228;4501.099780043939;4532.138893610204;4588.105319462739;4658.604018658384;4661.067786442725;9196.678116265899;9199.540022998957;9202.263818894606;9210.585523651453;9215.585298661576;5258.869343091177;5270.657407268515;5277.261183434322;5279.076161671692;5287.968846075056;4213.599708027732;4442.888927776831;4454.688171827941;4619;4954.653174277766;3410.471376936579;3723.6168851934785;3763.9059023524633;3875.4691896700647;3889.2610404023144;1158.7566611011614;1171.66607516859;1172.0916289875245;1184.5853951117213;1188.2989036468525;915.6383228624655;928.7817362919709;939.8778158839444;946.5267366316841;998.1964518562627;5711.343195532567;5896.7936122235515;6029.216201893813;6095.512359011268;6180.938190618036;439.75813302684037;445.9130469558405;453.17295934918246;453.4332084893883;453.95687409696023;3940.330143875548;3954.683625309968;4053.109015345243;4089.1208390196252;4109.198706252312;9145.314101442285;10067.597296108073;10826.350418974887;10924.344539327665;10935.507902144423;8530.786730331667;10815.459227038617;10818.972197641211;10825.67522974312;10831.837522437012;7993.480423772437;8420.53687047206;8422.157784221652;8426.747197584082;8455.746327610304;4747.762611869462;5124.092144543968;6133.693315334126;6163.876722465615;6236.314005459305;10050.64822731201;10069.244806639677;10077.546510407195;10081.092701656886;10090.293679442368;9440.85838712406;9735.9026409735;9920;10088.445135517617;10288.537015834456;7463.2163622818825;7685.308322251024;7688.46180767341;7703.845923081619;7777.688892444239;8500.957495212484;8590.570471476527;8682.956585217033;8690.91309086901;8701.260392866678;7571.772846814604;7667.846643066996;7706;7716.382689384948;7836.333911617577;7444.73943411978;7477.476576639583;7495.625218738932;7510.399168066432;7564.8487030260185;7067.57594544329;7175.103112110986;7194.100737407824;7206.855862882609;7214.350708436266;5039.118154322906;5056.443791182894;5107.591392688501;5131.127708289674;5249.291345668286;5010.198368261061;5058.468860769566;5175.948240517547;5217.139172036723;5299.761510731949 17:18:42 INFO - PROCESS | 1948 | |4;prototype.html;299.7437191201524;302.4646375914631;314.7513464363153;316.17005360927567;319.7793522469496;4900.451765082155;5049.9647572247695;5312.707801070939;5348.46515348465;5616;4272.4232228649125;5235.973820130894;5371.200647782997;5386.218998245252;5516.255305533752;4109.753414795122;4177.456930599025;4216.325387937931;4257.382679511469;4261.893452663678;3611.458281257806;4254.212970600433;4264.658827293824;4274.850380236692;4279.978600106995;3662.487251784752;4077.2660921034203;4203.117345357478;4226.4716910386205;4234.364845273203;3443.517907492953;4183.414321994923;4205.411242426063;4211.7051806373565;4216.620504154625;3047.9085627431214;3356.7985920844826;3636.418173092319;3658.250058737979;3674.926501469956;4843.4187897452175;5145;5174.327337446146;5370.624056316059;5430.945690543083;4729.793902554861;4774.01878220538;4801.967576970968;4812.230043193073;4854.8543543693395;4928.359313289286;5843.0066888629035;5877.588568800185;5881.3530511643685;5888.175655408247;3201.6638252983566;3584.301061293049;3941.684665226775;3993.600639935983;3994.1811928554575;5042.697438153723;6095.359987201368;6140.140380346755;6141.754329826802;6240.906386404207;3845.288621605012;3981.3629819228786;3997.860609726214;4034.959650403517;4051.736637118578;4050.7164498485413;4051.8987025324604;4063.918721625551;4075.7554546727292;4098.836046558164;4425.491068527096;4654.837080702159;4722.716637001791;4746.288056791509;4750.311204875274;5461.344638643388;5640.520555752807;5893.675847828411;5905.43898329658;5933.2880054393745;1298.6753311672082;1370.3148425787106;1378.9172649641055;1382.2950295349244;1390.3454888682436;854.6752234151107;882.8984666763276;888.5157589113949;925.7084018534275;941.3833938770116;5388.5419739321815;5404.486573775485;5408.864778380494;5448.373437054789;5481.588880833953;5844;5847.093700476433;5874.383189765099;5957.106434034843;6008.2748695808;5080;5087.70263582784;5291.285676433709;5321.946780532223;5427.402985671573;2697.4470233602065;2943.67619561848;3050.7559395248772;3137.937241255162;3153.243221626793;4948.480409557017;5218.112920803473;5705.5435565154685;6228.937710622927;6312.337204593451;5223.712695801767;5462.667772658675;5640.492355688008;5900.380460051632;6081.6351018939;5158.3552055993005;5637.915431268534;6052.12244224585;6221.2845522765465;6265.49876009909;5611.354694210218;5613.410591887793;5717.399673034438;5983.222181116427;6075.453209211192;5453.672779633234;5862.267216597926;5865.530757539472;5871.853203669772;5881.588288819741;5503.504684578407;5916.497097746567;5926.911096333644;6193.349698281796;6226.377362263737;3318.966810331866;3856.460095586564;3932.5477570079806;3938.724289299836;3947.2697550953167;4502;4775.928361074517;5209.7395130244095;5903.940960590339;5924.822255332347;5040.949590504195;5364.624476286623;5416.668748719588;5660.773569057192;5723.537259957666;5711.828645140652;5787.189793428839;5788.450097240755;5835.328937172109;5845.386234445492;5251.343582052244;5368.2484452177505;5640.661560306395;5683.23276357703;5698.715064246854;5536;5782.710864456676;5792.870390273856;5800.767969281182;5833;4550.816787635173;4799.720132936827;5391.568674505952;5604.915926261027;5820.447057529528 17:18:42 INFO - PROCESS | 1948 | |5;yui.html;628.136312570216;738.4756822655914;762.8893810397492;810.9675612975482;817.5217497763808;15200;16523.091229982347;16543.66912661746;16552.255148518332;16602.584935376606;14869.661730444257;15023.32395042223;15035;15051.548453546384;15067.17130557819;699.727106428492;727.2182403915785;727.4507746651271;730.8647900138492;733.9339459448648;719.0364911019233;885.844977129003;919.429953428875;920.8894932608097;924.0205382294755;728.9198188199293;838.7525679924406;956.4165858826145;972.4894430424049;973.4791886340809;944.7590864329586;966.9129778319985;969.6315400147969;969.9863642493541;971.0920289529242;550.8127236739527;697.3061803505494;717.2325611595595;719.6221983458673;723.3164659396872;15395.923020384938;15444.382224711111;15504.302306396126;15512.844871551253;15515.379384824593;14267.78598321026;14510.274486275644;14714.926425367805;14722.190279534621;14774;12958;13243.73512529744;13256.536021239306;13479.460821567125;13771.036027478081;4591.862244132682;4634.119517291704;4708.882277943045;4772.331873537708;4793.2810078488155;95892.52053739686;120835.39582302033;124045;124705.37647311705;125100;11420.714482138013;11594.362310073027;12029.09781766371;12032.27806331623;12068.637940861789;23569.8787109161;23640.29079127629;23655.408614784425;23665;23694.170704025277;8084.555349455836;8364.289035432057;8400;8401.537915414588;8411;4400.207962566768;4573.5426457354;4660.976695116503;4711.623070154379;4764.95235047652;3464.913377165591;3550.715942724576;3595.137167079882;3606.3328284267495;3615.2769446110883;646.7833275852548;649.3182158733312;655.6852710698892;657.2441692054176;659.2155335151155;539.511741873605;620.5873094392178;639.3925770518026;655.9147310849654;671.0705672643425;9000.324975626723;9316.301277404222;9361.812763744687;9563.61745530171;9667.661631842851;515.9825083921847;528.1760453692176;556.3963100036425;595.76765061626;651.6774196772628;4547.340635607885;4591.311303304531;4609.723416595015;4629.368204670963;4666.766661666971;88100;91258.54370728237;149997;155879;158396;129286;131215;133755;135024.29951401113;135241;69397.65301173461;75570.62214689002;80205.19794801978;100698.49650751699;108248.91751082703;8468.237858592756;8656.177663121993;9005.414648048118;9039.457632541806;9201.861972070557;61788;62819.37180628135;63258.68370658117;63306.68346658237;63622.68188659027;90903;92417.53791231001;93520.53239733758;95684.52157739167;98094.52858206989;11213.327200368005;11441.885581144083;11654.65036048886;11975.134358669678;12063.517459301529;12604.432800523815;12619.179753316004;12678.429470673658;12694.682632934251;12737.29944853042;12101.757964840477;12111.152219344562;12165.817512737493;12167.330796805907;12228.388580571114;12078.939605301917;12100.939495302468;12103.455344509342;12239.632811015685;12296.938515307365;11758.470868810753;12006.279623222634;12025.579104731292;12041.638750837488;12064.939675301568;10020.69937901864;10036.397816131055;10341.84487232677;10532.57869685204;10589.152867770714;9815.067568580973;10200.3369780964;10212.489375531046;10249.436281004617;10279.972002799661 17:18:42 INFO - PROCESS | 1948 | __end_tp_report 17:18:42 INFO - PROCESS | 1948 | __start_cc_report 17:18:42 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,1266 17:18:42 INFO - PROCESS | 1948 | __end_cc_report 17:18:42 INFO - PROCESS | 1948 | __startTimestamp1469146722704__endTimestamp 17:18:42 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | ------- Summary: start ------- 17:18:42 INFO - PROCESS | 1948 | Number of tests: 6 17:18:42 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5766.96 Median:5919.23 stddev:631.87 (10.7%) stddev-sans-first:605.29 17:18:42 INFO - PROCESS | 1948 | Values: 3374.3 3378.9 3382.7 3400.5 3443.3 5598.6 5684.5 5734.5 5780.8 5855.7 5721.8 5744.7 5755.8 5765.4 5773.2 5179.5 5216.2 5252.6 5257.7 5304.0 5303.6 5304.5 5305.5 5309.6 5312.0 5208.0 5246.9 5254.2 5279.3 5284.7 5338.7 5367.5 5368.7 5371.8 5377.4 5086.0 5105.6 5178.4 5271.1 5278.3 5691.8 5696.3 5696.4 5743.9 5788.7 6189.1 6252.4 6271.7 6272.7 6273.7 6138.4 6183.2 6245.8 6258.0 6263.0 5728.8 5788.1 5829.8 5850.7 5919.2 6315.3 6325.1 6325.2 6325.6 6349.1 6063.8 6174.4 6177.6 6178.4 6180.0 6138.4 6186.3 6197.8 6200.6 6294.3 6255.4 6269.2 6274.2 6278.1 6285.5 6216.6 6264.5 6276.7 6279.6 6287.6 4877.3 4891.6 4916.7 5019.7 5024.6 4448.8 4462.1 4466.8 4470.6 4509.3 5881.7 5883.7 5884.5 5889.0 5953.8 6163.5 6168.6 6175.4 6185.9 6195.8 5773.9 5779.2 5791.8 5806.5 5825.1 5440.1 5440.5 5441.6 5454.2 5458.6 6222.0 6280.2 6303.8 6313.3 6337.8 5849.4 6186.8 6242.7 6247.0 6250.2 6363.8 6395.0 6400.9 6402.6 6411.9 6086.7 6119.9 6157.8 6235.8 6248.8 6201.5 6209.9 6223.3 6226.7 6242.4 6160.7 6165.4 6169.3 6181.8 6194.8 5836.4 6016.6 6018.9 6150.6 6177.4 4547.5 5891.6 5892.5 5898.9 6000.1 6112.2 6119.4 6121.4 6124.6 6147.5 5796.0 5813.9 5876.4 5952.5 5983.1 17:18:42 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:71266.14 Median:39292.01 stddev:153546.81 (390.8%) stddev-sans-first:153885.65 17:18:42 INFO - PROCESS | 1948 | Values: 511.9 512.9 515.1 528.4 528.5 30193.2 31935.7 31982.7 42281.4 42971.4 43406.3 43780.8 43880.0 44019.8 44035.0 9716.6 9871.6 9878.8 9885.0 9888.6 8864.2 9066.1 11908.5 12170.3 12188.8 12108.6 12188.1 12192.4 12198.3 12199.8 12104.5 12173.6 12180.9 12190.5 12206.9 8699.7 8929.0 8931.6 9059.8 9063.5 39504.4 42232.4 42375.0 42404.6 42407.0 3466.8 3615.1 3684.2 3727.1 4287.7 31962.0 33110.7 33113.7 33169.2 33251.0 3234.2 3325.5 3383.9 3416.1 3417.7 524582.4 525887.0 527154.0 528249.0 528793.0 61488.0 61781.7 61802.4 61897.7 61936.4 66069.0 66113.7 66146.7 66150.3 66343.3 16402.3 16618.7 16622.2 16626.9 16637.3 11615.0 12857.8 12932.6 12937.3 12946.1 14061.6 14651.0 14753.3 16171.9 16851.3 5863.9 6338.0 6583.7 6902.2 6980.3 812.0 825.6 1030.0 1056.0 1088.8 30427.7 31137.7 31322.5 31390.5 32037.9 942.9 980.2 1068.9 1138.5 1178.3 13033.3 16033.8 16076.5 16207.4 16395.5 625370.0 628549.0 629561.0 630653.0 631112.0 46311.3 51926.0 53118.2 61030.0 61282.0 1402.0 1404.1 1448.2 1448.3 1448.4 31348.7 32000.4 32326.4 32653.7 33213.3 51902.5 57562.4 59238.0 59302.7 63250.0 560426.0 564236.0 566024.0 567901.0 568047.0 26433.3 26866.9 26882.5 26903.0 26956.2 38924.0 47837.8 47993.6 48667.0 49612.0 40218.0 40348.2 41139.7 41437.4 41770.0 39669.0 39985.0 41265.0 41294.6 41307.0 38387.0 39030.8 39082.8 39106.6 39477.4 34516.0 34807.8 36064.4 36160.1 36201.8 21285.7 22418.8 24219.9 26036.0 26136.0 17:18:42 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:34038.42 Median:1522.29 stddev:94860.61 (6231.4%) stddev-sans-first:95092.96 17:18:42 INFO - PROCESS | 1948 | Values: 335.2 357.2 379.2 382.7 400.1 15286.7 16684.2 20700.5 21080.8 21203.2 19301.0 19869.2 21118.7 21145.0 21766.0 5215.5 5307.1 5449.6 5480.1 5487.5 5286.2 5422.8 5430.0 5435.2 5439.8 3873.5 5813.5 6060.4 6105.4 6136.7 5813.7 5953.0 6059.9 6089.7 6119.2 5900.1 5900.6 5903.9 5932.4 5954.8 13365.7 13415.9 13491.9 13523.9 13642.5 2088.1 2134.7 2196.3 2541.3 2709.4 8772.2 10360.7 10429.2 12009.9 16289.1 1870.0 1878.1 1907.4 1917.6 1942.4 111891.0 121473.2 126585.0 127996.0 128741.0 9084.5 11250.1 11606.8 11628.0 11797.6 12102.7 14680.9 14694.2 14711.8 14722.0 8245.4 8455.2 8506.7 8629.5 8638.2 7769.3 7780.0 8455.1 8461.1 8697.0 3684.7 3710.2 4539.0 4541.5 4609.1 1887.1 1929.7 1938.3 1939.2 1939.5 866.4 867.9 912.6 984.5 993.0 21350.6 21696.9 21764.7 21822.6 21916.2 875.9 912.8 954.6 961.3 983.5 10698.9 11195.9 11272.5 11354.9 11456.6 552438.0 553782.1 556576.0 569404.0 588649.0 128646.4 130052.0 130619.0 131977.0 132992.4 878.8 970.5 983.3 1009.7 1009.9 19238.2 19376.4 19406.2 19406.7 19425.9 44670.0 54055.0 57715.7 58024.0 58201.0 78790.0 79924.0 80461.6 81350.2 83224.0 7590.1 7668.8 7971.1 8187.5 8338.2 10499.5 11183.7 11382.8 11605.9 11872.2 10885.8 11020.8 11490.7 11608.0 11609.8 10511.7 10995.7 10996.6 11238.8 11572.9 9584.0 9932.9 11390.4 11609.0 11639.4 7847.3 7975.4 8029.3 8074.0 8226.0 8085.3 9623.0 11244.7 11377.7 11560.5 17:18:42 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:5659.43 Median:4940.90 stddev:3177.77 (64.3%) stddev-sans-first:3170.43 17:18:42 INFO - PROCESS | 1948 | Values: 1383.5 1548.9 1584.2 1773.2 1899.6 6507.4 8289.3 8292.4 8294.2 8295.9 8067.5 8348.5 8697.8 8698.2 8802.1 2348.4 2373.1 2381.6 2381.6 2385.4 2319.5 2319.7 2380.8 2383.3 2388.9 2328.2 2351.6 2363.5 2368.4 2371.8 2319.9 2348.6 2352.4 2352.5 2364.3 2317.2 2340.9 2351.8 2352.8 2354.6 8752.0 8756.7 8823.5 8832.2 8844.4 4927.1 4954.8 5225.7 5375.4 5384.6 3143.4 3578.3 3663.8 3675.2 3681.8 1074.4 1081.1 1098.5 1101.4 1113.1 11543.1 11673.2 11678.4 11678.9 11686.4 4501.1 4532.1 4588.1 4658.6 4661.1 9196.7 9199.5 9202.3 9210.6 9215.6 5258.9 5270.7 5277.3 5279.1 5288.0 4213.6 4442.9 4454.7 4619.0 4954.7 3410.5 3723.6 3763.9 3875.5 3889.3 1158.8 1171.7 1172.1 1184.6 1188.3 915.6 928.8 939.9 946.5 998.2 5711.3 5896.8 6029.2 6095.5 6180.9 439.8 445.9 453.2 453.4 454.0 3940.3 3954.7 4053.1 4089.1 4109.2 9145.3 10067.6 10826.4 10924.3 10935.5 8530.8 10815.5 10819.0 10825.7 10831.8 7993.5 8420.5 8422.2 8426.7 8455.7 4747.8 5124.1 6133.7 6163.9 6236.3 10050.6 10069.2 10077.5 10081.1 10090.3 9440.9 9735.9 9920.0 10088.4 10288.5 7463.2 7685.3 7688.5 7703.8 7777.7 8501.0 8590.6 8683.0 8690.9 8701.3 7571.8 7667.8 7706.0 7716.4 7836.3 7444.7 7477.5 7495.6 7510.4 7564.8 7067.6 7175.1 7194.1 7206.9 7214.4 5039.1 5056.4 5107.6 5131.1 5249.3 5010.2 5058.5 5175.9 5217.1 5299.8 17:18:42 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:4663.31 Median:5243.66 stddev:1435.46 (27.4%) stddev-sans-first:1401.62 17:18:42 INFO - PROCESS | 1948 | Values: 299.7 302.5 314.8 316.2 319.8 4900.5 5050.0 5312.7 5348.5 5616.0 4272.4 5236.0 5371.2 5386.2 5516.3 4109.8 4177.5 4216.3 4257.4 4261.9 3611.5 4254.2 4264.7 4274.9 4280.0 3662.5 4077.3 4203.1 4226.5 4234.4 3443.5 4183.4 4205.4 4211.7 4216.6 3047.9 3356.8 3636.4 3658.3 3674.9 4843.4 5145.0 5174.3 5370.6 5430.9 4729.8 4774.0 4802.0 4812.2 4854.9 4928.4 5843.0 5877.6 5881.4 5888.2 3201.7 3584.3 3941.7 3993.6 3994.2 5042.7 6095.4 6140.1 6141.8 6240.9 3845.3 3981.4 3997.9 4035.0 4051.7 4050.7 4051.9 4063.9 4075.8 4098.8 4425.5 4654.8 4722.7 4746.3 4750.3 5461.3 5640.5 5893.7 5905.4 5933.3 1298.7 1370.3 1378.9 1382.3 1390.3 854.7 882.9 888.5 925.7 941.4 5388.5 5404.5 5408.9 5448.4 5481.6 5844.0 5847.1 5874.4 5957.1 6008.3 5080.0 5087.7 5291.3 5321.9 5427.4 2697.4 2943.7 3050.8 3137.9 3153.2 4948.5 5218.1 5705.5 6228.9 6312.3 5223.7 5462.7 5640.5 5900.4 6081.6 5158.4 5637.9 6052.1 6221.3 6265.5 5611.4 5613.4 5717.4 5983.2 6075.5 5453.7 5862.3 5865.5 5871.9 5881.6 5503.5 5916.5 5926.9 6193.3 6226.4 3319.0 3856.5 3932.5 3938.7 3947.3 4502.0 4775.9 5209.7 5903.9 5924.8 5040.9 5364.6 5416.7 5660.8 5723.5 5711.8 5787.2 5788.5 5835.3 5845.4 5251.3 5368.2 5640.7 5683.2 5698.7 5536.0 5782.7 5792.9 5800.8 5833.0 4550.8 4799.7 5391.6 5604.9 5820.4 17:18:42 INFO - PROCESS | 1948 | 17:18:42 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:24048.60 Median:4582.43 stddev:38184.88 (833.3%) stddev-sans-first:38251.50 17:18:42 INFO - PROCESS | 1948 | Values: 628.1 738.5 762.9 811.0 817.5 15200.0 16523.1 16543.7 16552.3 16602.6 14869.7 15023.3 15035.0 15051.5 15067.2 699.7 727.2 727.5 730.9 733.9 719.0 885.8 919.4 920.9 924.0 728.9 838.8 956.4 972.5 973.5 944.8 966.9 969.6 970.0 971.1 550.8 697.3 717.2 719.6 723.3 15395.9 15444.4 15504.3 15512.8 15515.4 14267.8 14510.3 14714.9 14722.2 14774.0 12958.0 13243.7 13256.5 13479.5 13771.0 4591.9 4634.1 4708.9 4772.3 4793.3 95892.5 120835.4 124045.0 124705.4 125100.0 11420.7 11594.4 12029.1 12032.3 12068.6 23569.9 23640.3 23655.4 23665.0 23694.2 8084.6 8364.3 8400.0 8401.5 8411.0 4400.2 4573.5 4661.0 4711.6 4765.0 3464.9 3550.7 3595.1 3606.3 3615.3 646.8 649.3 655.7 657.2 659.2 539.5 620.6 639.4 655.9 671.1 9000.3 9316.3 9361.8 9563.6 9667.7 516.0 528.2 556.4 595.8 651.7 4547.3 4591.3 4609.7 4629.4 4666.8 88100.0 91258.5 149997.0 155879.0 158396.0 129286.0 131215.0 133755.0 135024.3 135241.0 69397.7 75570.6 80205.2 100698.5 108248.9 8468.2 8656.2 9005.4 9039.5 9201.9 61788.0 62819.4 63258.7 63306.7 63622.7 90903.0 92417.5 93520.5 95684.5 98094.5 11213.3 11441.9 11654.7 11975.1 12063.5 12604.4 12619.2 12678.4 12694.7 12737.3 12101.8 12111.2 12165.8 12167.3 12228.4 12078.9 12100.9 12103.5 12239.6 12296.9 11758.5 12006.3 12025.6 12041.6 12064.9 10020.7 10036.4 10341.8 10532.6 10589.2 9815.1 10200.3 10212.5 10249.4 10280.0 17:18:42 INFO - PROCESS | 1948 | -------- Summary: end -------- 17:18:42 INFO - PROCESS | 1948 | 17:18:43 INFO - TEST-INFO | 1948: exit 0 17:18:43 INFO - TEST-OK | dromaeo_css | took 1117162ms 17:18:43 INFO - TEST-START | kraken 17:18:43 INFO - operating with platform_type : mac_ 17:18:43 INFO - Initialising browser for kraken test... 17:18:43 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmprSSwY7/profile http://localhost:52106/getInfo.html) 17:18:45 INFO - PROCESS | 1951 | JavaScript error: , line 0: uncaught exception: undefined 17:18:45 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 17:18:45 INFO - PROCESS | 1951 | colorDepth:24 17:18:45 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 17:18:45 INFO - PROCESS | 1951 | __metrics 17:18:45 INFO - PROCESS | 1951 | 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" 17:18:45 INFO - PROCESS | 1951 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:18:46 INFO - TEST-INFO | 1951: exit 0 17:18:46 INFO - Browser initialized. 17:18:46 INFO - Running cycle 1/1 for kraken test... 17:18:46 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmprSSwY7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:58 INFO - PROCESS | 1952 | RSS: Main: 245501952 17:18:58 INFO - PROCESS | 1952 | 17:19:01 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52106/tests/kraken/driver.html?testName=audio-beat-detection) 17:19:02 INFO - PROCESS | 1952 | RSS: Main: 325185536 17:19:02 INFO - PROCESS | 1952 | 17:19:04 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52106/tests/kraken/driver.html?testName=audio-dft) 17:19:05 INFO - PROCESS | 1952 | RSS: Main: 339750912 17:19:05 INFO - PROCESS | 1952 | 17:19:08 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52106/tests/kraken/driver.html?testName=audio-fft) 17:19:09 INFO - PROCESS | 1952 | RSS: Main: 429961216 17:19:09 INFO - PROCESS | 1952 | 17:19:11 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52106/tests/kraken/driver.html?testName=audio-oscillator) 17:19:12 INFO - PROCESS | 1952 | RSS: Main: 329994240 17:19:12 INFO - PROCESS | 1952 | 17:19:14 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52106/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:19:14 INFO - PROCESS | 1952 | RSS: Main: 335077376 17:19:14 INFO - PROCESS | 1952 | 17:19:17 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52106/tests/kraken/driver.html?testName=imaging-darkroom) 17:19:18 INFO - PROCESS | 1952 | RSS: Main: 372547584 17:19:18 INFO - PROCESS | 1952 | 17:19:21 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52106/tests/kraken/driver.html?testName=imaging-desaturate) 17:19:21 INFO - PROCESS | 1952 | RSS: Main: 373837824 17:19:21 INFO - PROCESS | 1952 | 17:19:24 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52106/tests/kraken/driver.html?testName=json-parse-financial) 17:19:25 INFO - PROCESS | 1952 | RSS: Main: 372260864 17:19:25 INFO - PROCESS | 1952 | 17:19:26 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52106/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:19:27 INFO - PROCESS | 1952 | RSS: Main: 351522816 17:19:27 INFO - PROCESS | 1952 | 17:19:29 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52106/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:19:30 INFO - PROCESS | 1952 | RSS: Main: 357961728 17:19:30 INFO - PROCESS | 1952 | 17:19:32 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52106/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:19:33 INFO - PROCESS | 1952 | RSS: Main: 355684352 17:19:33 INFO - PROCESS | 1952 | 17:19:35 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52106/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:19:36 INFO - PROCESS | 1952 | RSS: Main: 364359680 17:19:36 INFO - PROCESS | 1952 | 17:19:39 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52106/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:19:40 INFO - PROCESS | 1952 | RSS: Main: 367075328 17:19:40 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52106/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52106/tests/kraken/driver.html?testName=ai-astar) 17:19:42 INFO - PROCESS | 1952 | RSS: Main: 410599424 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | __start_tp_report 17:19:42 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 17:19:42 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 17:19:42 INFO - PROCESS | 1952 | |i|pagename|runs| 17:19:42 INFO - PROCESS | 1952 | |0;ai-astar;102;112;112;114;113;114;113;113;113;117 17:19:42 INFO - PROCESS | 1952 | |1;audio-beat-detection;142;107;107;164;108;110;129;105;145;126 17:19:42 INFO - PROCESS | 1952 | |2;audio-dft;180;186;188;187;185;187;183;185;181;186 17:19:42 INFO - PROCESS | 1952 | |3;audio-fft;79;58;57;109;58;58;65;67;86;57 17:19:42 INFO - PROCESS | 1952 | |4;audio-oscillator;85;69;90;69;75;68;121;99;98;97 17:19:42 INFO - PROCESS | 1952 | |5;imaging-gaussian-blur;91;84;89;86;88;84;88;85;89;85 17:19:42 INFO - PROCESS | 1952 | |6;imaging-darkroom;94;94;94;94;93;94;93;94;99;94 17:19:42 INFO - PROCESS | 1952 | |7;imaging-desaturate;87;85;83;85;83;83;87;84;84;84 17:19:42 INFO - PROCESS | 1952 | |8;json-parse-financial;56;52;54;54;53;54;54;52;53;54 17:19:42 INFO - PROCESS | 1952 | |9;json-stringify-tinderbox;65;91;113;115;74;71;71;71;72;72 17:19:42 INFO - PROCESS | 1952 | |10;stanford-crypto-aes;86;82;83;80;86;83;83;85;82;78 17:19:42 INFO - PROCESS | 1952 | |11;stanford-crypto-ccm;136;136;139;141;121;142;147;152;220;136 17:19:42 INFO - PROCESS | 1952 | |12;stanford-crypto-pbkdf2;164;222;209;163;164;192;158;176;227;165 17:19:42 INFO - PROCESS | 1952 | |13;stanford-crypto-sha256-iterative;66;67;67;66;67;81;86;85;85;71 17:19:42 INFO - PROCESS | 1952 | __end_tp_report 17:19:42 INFO - PROCESS | 1952 | __start_cc_report 17:19:42 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,2105 17:19:42 INFO - PROCESS | 1952 | __end_cc_report 17:19:42 INFO - PROCESS | 1952 | __startTimestamp1469146782395__endTimestamp 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | ------- Summary: start ------- 17:19:42 INFO - PROCESS | 1952 | Number of tests: 14 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:3.89 (3.4%) stddev-sans-first:1.51 17:19:42 INFO - PROCESS | 1952 | Values: 102.0 112.0 112.0 114.0 113.0 114.0 113.0 113.0 113.0 117.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:127.50 stddev:20.51 (16.1%) stddev-sans-first:20.72 17:19:42 INFO - PROCESS | 1952 | Values: 142.0 107.0 107.0 164.0 108.0 110.0 129.0 105.0 145.0 126.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#2] audio-dft Cycles:10 Average:184.80 Median:186.00 stddev:2.66 (1.4%) stddev-sans-first:2.18 17:19:42 INFO - PROCESS | 1952 | Values: 180.0 186.0 188.0 187.0 185.0 187.0 183.0 185.0 181.0 186.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#3] audio-fft Cycles:10 Average:69.40 Median:61.50 stddev:17.19 (27.9%) stddev-sans-first:17.87 17:19:42 INFO - PROCESS | 1952 | Values: 79.0 58.0 57.0 109.0 58.0 58.0 65.0 67.0 86.0 57.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:87.50 stddev:17.28 (19.7%) stddev-sans-first:18.31 17:19:42 INFO - PROCESS | 1952 | Values: 85.0 69.0 90.0 69.0 75.0 68.0 121.0 99.0 98.0 97.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:2.42 (2.8%) stddev-sans-first:2.07 17:19:42 INFO - PROCESS | 1952 | Values: 91.0 84.0 89.0 86.0 88.0 84.0 88.0 85.0 89.0 85.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.00 stddev:1.70 (1.8%) stddev-sans-first:1.80 17:19:42 INFO - PROCESS | 1952 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 93.0 94.0 99.0 94.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.50 stddev:1.51 (1.8%) stddev-sans-first:1.30 17:19:42 INFO - PROCESS | 1952 | Values: 87.0 85.0 83.0 85.0 83.0 83.0 87.0 84.0 84.0 84.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:1.17 (2.2%) stddev-sans-first:0.87 17:19:42 INFO - PROCESS | 1952 | Values: 56.0 52.0 54.0 54.0 53.0 54.0 54.0 52.0 53.0 54.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#9] json-stringify-tinderbox Cycles:10 Average:81.50 Median:71.50 stddev:18.39 (25.7%) stddev-sans-first:18.51 17:19:42 INFO - PROCESS | 1952 | Values: 65.0 91.0 113.0 115.0 74.0 71.0 71.0 71.0 72.0 72.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.00 stddev:2.53 (3.0%) stddev-sans-first:2.40 17:19:42 INFO - PROCESS | 1952 | Values: 86.0 82.0 83.0 80.0 86.0 83.0 83.0 85.0 82.0 78.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:141.50 stddev:26.91 (19.0%) stddev-sans-first:28.25 17:19:42 INFO - PROCESS | 1952 | Values: 136.0 136.0 139.0 141.0 121.0 142.0 147.0 152.0 220.0 136.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.00 Median:184.00 stddev:26.51 (14.4%) stddev-sans-first:27.11 17:19:42 INFO - PROCESS | 1952 | Values: 164.0 222.0 209.0 163.0 164.0 192.0 158.0 176.0 227.0 165.0 17:19:42 INFO - PROCESS | 1952 | 17:19:42 INFO - PROCESS | 1952 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:76.00 stddev:8.94 (11.8%) stddev-sans-first:8.99 17:19:42 INFO - PROCESS | 1952 | Values: 66.0 67.0 67.0 66.0 67.0 81.0 86.0 85.0 85.0 71.0 17:19:42 INFO - PROCESS | 1952 | -------- Summary: end -------- 17:19:42 INFO - PROCESS | 1952 | 17:19:43 INFO - TEST-INFO | 1952: exit 0 17:19:43 INFO - TEST-OK | kraken | took 59950ms 17:19:43 INFO - SUITE-END | took 1177s 17:19:43 INFO - Completed test suite (00:19:37) 17:19:43 INFO - javascript benchmark 17:19:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3374.342003309354, 3378.9493157602637, 3382.680754505742, 3400.5239266502676, 3443.3113377324526, 5598.580106492019, 5684.5168160706335, 5734.541236701064, 5780.797672081478, 5855.707214639267, 5721.799737009205, 5744.741486633101, 5755.827325180241, 5765.423457654232, 5773.249477567921, 5179.4561571035065, 5216.191490318987, 5252.6323157379, 5257.7108259045735, 5304.045271851065, 5303.602229832759, 5304.549113325353, 5305.549028332577, 5309.5752339812725, 5312.0, 5208.036513245042, 5246.947530524684, 5254.159334506479, 5279.313689220396, 5284.709340986245, 5338.652987555835, 5367.490088441592, 5368.731563421852, 5371.838844834622, 5377.4084850666395, 5085.974570127163, 5105.617078719074, 5178.430372659004, 5271.103912334912, 5278.261043453919, 5691.800786972478, 5696.344920334173, 5696.3733989261145, 5743.913841292384, 5788.652680839163, 6189.102580125901, 6252.3747625237565, 6271.655058971754, 6272.717727702265, 6273.686315684243, 6138.447539721402, 6183.165272688175, 6245.812625621239, 6257.968710156466, 6262.96661050926, 5728.770849166029, 5788.073908174714, 5829.795957141565, 5850.707464626752, 5919.230500035054, 6315.305316415191, 6325.082862984934, 6325.240971083499, 6325.557210995186, 6349.111124442582, 6063.757449701962, 6174.413430724074, 6177.62934223948, 6178.382161783786, 6179.969100154471, 6138.416850399205, 6186.319504854462, 6197.783077592353, 6200.56596038273, 6294.276158241861, 6255.40573645503, 6269.247690277105, 6274.152989346379, 6278.089676996809, 6285.497160227171, 6216.564840461215, 6264.467520260738, 6276.717547710364, 6279.560430769893, 6287.591306565058, 4877.341558889575, 4891.633127515451, 4916.729579873141, 5019.749012549358, 5024.598032157419, 4448.755318457451, 4462.129884672458, 4466.798994045275, 4470.645544515066, 4509.323601459741, 5881.735321910524, 5883.7058147092475, 5884.47039766423, 5889.028310328834, 5953.76184952597, 6163.506919446433, 6168.568200225941, 6175.3515880832765, 6185.938140618626, 6195.81412557624, 5773.88452230953, 5779.162021506942, 5791.797287095016, 5806.506446951972, 5825.097109947965, 5440.075187218183, 5440.455954404528, 5441.646292991022, 5454.181872719124, 5458.590605704588, 6221.968890155611, 6280.183576135073, 6303.810885673437, 6313.305536390993, 6337.841553961188, 5849.356570777296, 6186.753442596979, 6242.719077641426, 6247.0, 6250.249970003629, 6363.809085727436, 6394.968025159845, 6400.935990640221, 6402.583832050902, 6411.935880641134, 6086.69566521681, 6119.868162944292, 6157.815265542041, 6235.750569977332, 6248.750049997949, 6201.503879689524, 6209.906851397323, 6223.3465486123305, 6226.719797609209, 6242.406971337898, 6160.722767475564, 6165.383461653978, 6169.290531588746, 6181.84545386369, 6194.752209911553, 5836.445537673914, 6016.578839481194, 6018.909716354346, 6150.630962142286, 6177.382261773787, 4547.52251013652, 5891.646501209942, 5892.469677728854, 5898.852528686817, 6000.069989151689, 6112.235970503687, 6119.44924956756, 6121.441500740575, 6124.5712800103565, 6147.538934579835, 5795.971020144872, 5813.941860581509, 5876.3536011039605, 5952.494038006818, 5983.072623743326], "value": 5727.055589694793, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.872031992002, 512.946140655231, 515.1294312611689, 528.3817933018371, 528.497926969379, 30193.245168870762, 31935.680643193562, 31982.720691172384, 42281.36577951333, 42971.35542966858, 43406.348904766455, 43780.78109609448, 43880.0, 44019.779901100446, 44035.0, 9716.562754676057, 9871.555779989918, 9878.802423951516, 9885.0, 9888.604455821795, 8864.157904999014, 9066.093390660948, 11908.523659053628, 12170.269783812955, 12188.81716774249, 12108.576199833007, 12188.146829721923, 12192.390380480985, 12198.26810391375, 12199.756004879942, 12104.455299511543, 12173.634790956285, 12180.878191218151, 12190.451429685685, 12206.877931220663, 8699.739007829776, 8929.0, 8931.64273429062, 9059.773505662346, 9063.50150741709, 39504.40743388851, 42232.366514502304, 42375.0, 42404.57595424037, 42407.0, 3466.843992020365, 3615.114296997226, 3684.244729830378, 3727.1241258304276, 4287.742735435852, 31962.0, 33110.66889331124, 33113.6688633113, 33169.170770730685, 33251.00246992594, 3234.1752853022563, 3325.48454989477, 3383.949240761366, 3416.145963509123, 3417.709494692954, 524582.3770881159, 525887.0, 527154.0, 528249.0, 528793.0, 61488.0, 61781.69109154426, 61802.38197617966, 61897.690511548055, 61936.380636193964, 66069.0, 66113.6694316535, 66146.66926665336, 66150.33849661538, 66343.33656663468, 16402.26189821461, 16618.6676266474, 16622.168891555375, 16626.916865415595, 16637.25132369022, 11615.0, 12857.842794148572, 12932.612021639367, 12937.2884491352, 12946.093773435769, 14061.648458788612, 14651.0, 14753.33609987553, 16171.919140404223, 16851.325946962228, 5863.941360586425, 6338.049292606146, 6583.736650533925, 6902.171739391205, 6980.336867997532, 811.9809638903257, 825.6037102190982, 1029.9897001030045, 1055.963041293551, 1088.8257878739364, 30427.69572304293, 31137.688623113478, 31322.530162047588, 31390.529142062886, 32037.87867424629, 942.9245660347156, 980.2158273381267, 1068.9198310126772, 1138.4592318648577, 1178.3401295274673, 13033.348332583333, 16033.788978642335, 16076.517704468884, 16207.351705931631, 16395.508134755975, 625370.0, 628549.0, 629561.0, 630653.0, 631112.0, 46311.30533042007, 51926.0, 53118.20322695163, 61030.0, 61282.0, 1402.0, 1404.0662959132267, 1448.2469116059801, 1448.2903377345026, 1448.4061534770904, 31348.68651313549, 32000.35999279955, 32326.35347293088, 32653.673463265062, 33213.33573328568, 51902.48097518976, 57562.424375755705, 59238.0, 59302.70348648229, 63250.0, 560426.0, 564236.0, 566024.0, 567901.0, 568047.0, 26433.33916652022, 26866.925322986863, 26882.462350753267, 26903.0, 26956.191314260603, 38924.0, 47837.76081119572, 47993.56019319426, 48667.0, 49612.0, 40218.0, 40348.1930361397, 41139.65760721405, 41437.378439324035, 41770.0, 39669.00827479336, 39985.0, 41265.0, 41294.58705412907, 41307.0, 38387.04032399212, 39030.80484597559, 39082.80458597689, 39106.6089339103, 39477.40783888187, 34516.0, 34807.82596087003, 36064.410016447495, 36160.09599760027, 36201.818990904874, 21285.6807147896, 22418.775812241667, 24219.878900605385, 26035.95856165732, 26136.0], "value": 20489.968093020692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.20723488948636, 357.1731441712434, 379.1847527815196, 382.6517868739446, 400.13570687315405, 15286.694266114671, 16684.249208785604, 20700.48248793781, 21080.789192108074, 21203.151873925042, 19301.0, 19869.20523179071, 21118.683219751714, 21145.0, 21766.0, 5215.504527069922, 5307.071262529052, 5449.5640348772, 5480.068388373966, 5487.533559647435, 5286.154215325549, 5422.810201642943, 5430.0, 5435.1847222916485, 5439.836804895859, 3873.5351757788962, 5813.476787089161, 6060.3939606039485, 6105.358937311563, 6136.662483563402, 5813.651180929158, 5953.0, 6059.909101363483, 6089.725962331706, 6119.1739115219325, 5900.114982752579, 5900.55745819061, 5903.881922361528, 5932.436418540232, 5954.761809527614, 13365.66585835352, 13415.865841341656, 13491.932540337333, 13523.864761352457, 13642.454301827915, 2088.0708084902226, 2134.7011418401435, 2196.3410976706923, 2541.3392517945467, 2709.376843326046, 8772.210501054935, 10360.689179324632, 10429.21780866438, 12009.939950300193, 16289.104099274535, 1869.9906500467546, 1878.0891267735137, 1907.3801014670148, 1917.6260629177316, 1942.3784388995527, 111891.0, 121473.17790233242, 126585.0, 127996.0, 128741.0, 9084.454932704059, 11250.09999200062, 11606.825897611543, 11628.0, 11797.587084452003, 12102.697432564257, 14680.853191467948, 14694.19181944982, 14711.779323310158, 14722.0, 8245.381596380295, 8455.154484551496, 8506.659733610586, 8629.482231066157, 8638.17937295956, 7769.339606133429, 7779.988601481884, 8455.069942306465, 8461.069282378934, 8697.0, 3684.705223582107, 3710.220853620716, 4539.0, 4541.500434952152, 4609.101225261109, 1887.131919317102, 1929.6588870734704, 1938.3039840139877, 1939.2340025689773, 1939.4569520534274, 866.3668983965146, 867.8611422172548, 912.5710915869531, 984.522506584306, 992.9553170107363, 21350.57298854014, 21696.891515542637, 21764.673529897063, 21822.563548729253, 21916.23293184731, 875.9036505984336, 912.8356895758827, 954.6467806911487, 961.2694352292308, 983.5033308179324, 10698.946505267579, 11195.944020280009, 11272.492737826817, 11354.943225283821, 11456.599019034295, 552438.0, 553782.1497695412, 556576.0, 569404.0, 588649.0, 128646.35676821743, 130052.0, 130619.0, 131977.0, 132992.36572525595, 878.8154487557684, 970.4516947924541, 983.2920297385871, 1009.7172791618359, 1009.8838633557235, 19238.23047078157, 19376.4187074388, 19406.2237510498, 19406.70889936624, 19425.902870485555, 44670.0, 54055.0, 57715.711421444306, 58024.0, 58201.0, 78790.0, 79924.0, 80461.59769201117, 81350.18649813427, 83224.0, 7590.127135379502, 7668.80827979305, 7971.12228403682, 8187.549684767395, 8338.24955753985, 10499.47502624881, 11183.72040698989, 11382.829257561309, 11605.89743974321, 11872.228305160137, 10885.782284354427, 11020.779584408427, 11490.712732181762, 11608.0, 11609.767804644029, 10511.684649460529, 10995.725106872393, 10996.560137594406, 11238.831417528907, 11572.884271157518, 9584.0, 9932.900670993487, 11390.373529455628, 11609.013233874877, 11639.359835209145, 7847.349403553747, 7975.361971042186, 8029.3175080118845, 8073.959630201812, 8226.0, 8085.272325490734, 9622.95188524081, 11244.66266012021, 11377.658670239905, 11560.537578496765], "value": 9337.21876214879, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.5365152673853, 1548.891577589569, 1584.247482445838, 1773.1577500687176, 1899.5915878086216, 6507.4143327100555, 8289.253967142957, 8292.419530632858, 8294.212049855269, 8295.875561866576, 8067.47561408508, 8348.499090054585, 8697.782555436117, 8698.217160455557, 8802.119788021195, 2348.4481146930502, 2373.145667559686, 2381.6189409694452, 2381.630847218684, 2385.403649087728, 2319.489712263299, 2319.675245465636, 2380.785729284364, 2383.3445802404303, 2388.856668599881, 2328.15022516781, 2351.6119840226343, 2363.4918492524102, 2368.431576421655, 2371.7983971362455, 2319.895604697793, 2348.6007378745653, 2352.3766201956496, 2352.5294941011866, 2364.2907127861745, 2317.2237300504353, 2340.8595484271, 2351.835371523994, 2352.8353015288935, 2354.564405584972, 8751.956240218758, 8756.730274110218, 8823.470591764515, 8832.249258813008, 8844.425112367675, 4927.137750893615, 4954.752262386866, 5225.686458812484, 5375.435579264199, 5384.623076384654, 3143.4341818472667, 3578.3380074686265, 3663.816809159558, 3675.246574452231, 3681.834317455721, 1074.3929679730927, 1081.1404933078143, 1098.4672433869566, 1101.4052411697674, 1113.120634698587, 11543.076553875753, 11673.1828771986, 11678.357690327028, 11678.88321116795, 11686.357250351228, 4501.099780043939, 4532.138893610204, 4588.105319462739, 4658.604018658384, 4661.067786442725, 9196.678116265899, 9199.540022998957, 9202.263818894606, 9210.585523651453, 9215.585298661576, 5258.869343091177, 5270.657407268515, 5277.261183434322, 5279.076161671692, 5287.968846075056, 4213.599708027732, 4442.888927776831, 4454.688171827941, 4619.0, 4954.653174277766, 3410.471376936579, 3723.6168851934785, 3763.9059023524633, 3875.4691896700647, 3889.2610404023144, 1158.7566611011614, 1171.66607516859, 1172.0916289875245, 1184.5853951117213, 1188.2989036468525, 915.6383228624655, 928.7817362919709, 939.8778158839444, 946.5267366316841, 998.1964518562627, 5711.343195532567, 5896.7936122235515, 6029.216201893813, 6095.512359011268, 6180.938190618036, 439.75813302684037, 445.9130469558405, 453.17295934918246, 453.4332084893883, 453.95687409696023, 3940.330143875548, 3954.683625309968, 4053.109015345243, 4089.1208390196252, 4109.198706252312, 9145.314101442285, 10067.597296108073, 10826.350418974887, 10924.344539327665, 10935.507902144423, 8530.786730331667, 10815.459227038617, 10818.972197641211, 10825.67522974312, 10831.837522437012, 7993.480423772437, 8420.53687047206, 8422.157784221652, 8426.747197584082, 8455.746327610304, 4747.762611869462, 5124.092144543968, 6133.693315334126, 6163.876722465615, 6236.314005459305, 10050.64822731201, 10069.244806639677, 10077.546510407195, 10081.092701656886, 10090.293679442368, 9440.85838712406, 9735.9026409735, 9920.0, 10088.445135517617, 10288.537015834456, 7463.2163622818825, 7685.308322251024, 7688.46180767341, 7703.845923081619, 7777.688892444239, 8500.957495212484, 8590.570471476527, 8682.956585217033, 8690.91309086901, 8701.260392866678, 7571.772846814604, 7667.846643066996, 7706.0, 7716.382689384948, 7836.333911617577, 7444.73943411978, 7477.476576639583, 7495.625218738932, 7510.399168066432, 7564.8487030260185, 7067.57594544329, 7175.103112110986, 7194.100737407824, 7206.855862882609, 7214.350708436266, 5039.118154322906, 5056.443791182894, 5107.591392688501, 5131.127708289674, 5249.291345668286, 5010.198368261061, 5058.468860769566, 5175.948240517547, 5217.139172036723, 5299.761510731949], "value": 4479.034136812307, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.7437191201524, 302.4646375914631, 314.7513464363153, 316.17005360927567, 319.7793522469496, 4900.451765082155, 5049.9647572247695, 5312.707801070939, 5348.46515348465, 5616.0, 4272.4232228649125, 5235.973820130894, 5371.200647782997, 5386.218998245252, 5516.255305533752, 4109.753414795122, 4177.456930599025, 4216.325387937931, 4257.382679511469, 4261.893452663678, 3611.458281257806, 4254.212970600433, 4264.658827293824, 4274.850380236692, 4279.978600106995, 3662.487251784752, 4077.2660921034203, 4203.117345357478, 4226.4716910386205, 4234.364845273203, 3443.517907492953, 4183.414321994923, 4205.411242426063, 4211.7051806373565, 4216.620504154625, 3047.9085627431214, 3356.7985920844826, 3636.418173092319, 3658.250058737979, 3674.926501469956, 4843.4187897452175, 5145.0, 5174.327337446146, 5370.624056316059, 5430.945690543083, 4729.793902554861, 4774.01878220538, 4801.967576970968, 4812.230043193073, 4854.8543543693395, 4928.359313289286, 5843.0066888629035, 5877.588568800185, 5881.3530511643685, 5888.175655408247, 3201.6638252983566, 3584.301061293049, 3941.684665226775, 3993.600639935983, 3994.1811928554575, 5042.697438153723, 6095.359987201368, 6140.140380346755, 6141.754329826802, 6240.906386404207, 3845.288621605012, 3981.3629819228786, 3997.860609726214, 4034.959650403517, 4051.736637118578, 4050.7164498485413, 4051.8987025324604, 4063.918721625551, 4075.7554546727292, 4098.836046558164, 4425.491068527096, 4654.837080702159, 4722.716637001791, 4746.288056791509, 4750.311204875274, 5461.344638643388, 5640.520555752807, 5893.675847828411, 5905.43898329658, 5933.2880054393745, 1298.6753311672082, 1370.3148425787106, 1378.9172649641055, 1382.2950295349244, 1390.3454888682436, 854.6752234151107, 882.8984666763276, 888.5157589113949, 925.7084018534275, 941.3833938770116, 5388.5419739321815, 5404.486573775485, 5408.864778380494, 5448.373437054789, 5481.588880833953, 5844.0, 5847.093700476433, 5874.383189765099, 5957.106434034843, 6008.2748695808, 5080.0, 5087.70263582784, 5291.285676433709, 5321.946780532223, 5427.402985671573, 2697.4470233602065, 2943.67619561848, 3050.7559395248772, 3137.937241255162, 3153.243221626793, 4948.480409557017, 5218.112920803473, 5705.5435565154685, 6228.937710622927, 6312.337204593451, 5223.712695801767, 5462.667772658675, 5640.492355688008, 5900.380460051632, 6081.6351018939, 5158.3552055993005, 5637.915431268534, 6052.12244224585, 6221.2845522765465, 6265.49876009909, 5611.354694210218, 5613.410591887793, 5717.399673034438, 5983.222181116427, 6075.453209211192, 5453.672779633234, 5862.267216597926, 5865.530757539472, 5871.853203669772, 5881.588288819741, 5503.504684578407, 5916.497097746567, 5926.911096333644, 6193.349698281796, 6226.377362263737, 3318.966810331866, 3856.460095586564, 3932.5477570079806, 3938.724289299836, 3947.2697550953167, 4502.0, 4775.928361074517, 5209.7395130244095, 5903.940960590339, 5924.822255332347, 5040.949590504195, 5364.624476286623, 5416.668748719588, 5660.773569057192, 5723.537259957666, 5711.828645140652, 5787.189793428839, 5788.450097240755, 5835.328937172109, 5845.386234445492, 5251.343582052244, 5368.2484452177505, 5640.661560306395, 5683.23276357703, 5698.715064246854, 5536.0, 5782.710864456676, 5792.870390273856, 5800.767969281182, 5833.0, 4550.816787635173, 4799.720132936827, 5391.568674505952, 5604.915926261027, 5820.447057529528], "value": 4203.252069101702, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.136312570216, 738.4756822655914, 762.8893810397492, 810.9675612975482, 817.5217497763808, 15200.0, 16523.091229982347, 16543.66912661746, 16552.255148518332, 16602.584935376606, 14869.661730444257, 15023.32395042223, 15035.0, 15051.548453546384, 15067.17130557819, 699.727106428492, 727.2182403915785, 727.4507746651271, 730.8647900138492, 733.9339459448648, 719.0364911019233, 885.844977129003, 919.429953428875, 920.8894932608097, 924.0205382294755, 728.9198188199293, 838.7525679924406, 956.4165858826145, 972.4894430424049, 973.4791886340809, 944.7590864329586, 966.9129778319985, 969.6315400147969, 969.9863642493541, 971.0920289529242, 550.8127236739527, 697.3061803505494, 717.2325611595595, 719.6221983458673, 723.3164659396872, 15395.923020384938, 15444.382224711111, 15504.302306396126, 15512.844871551253, 15515.379384824593, 14267.78598321026, 14510.274486275644, 14714.926425367805, 14722.190279534621, 14774.0, 12958.0, 13243.73512529744, 13256.536021239306, 13479.460821567125, 13771.036027478081, 4591.862244132682, 4634.119517291704, 4708.882277943045, 4772.331873537708, 4793.2810078488155, 95892.52053739686, 120835.39582302033, 124045.0, 124705.37647311705, 125100.0, 11420.714482138013, 11594.362310073027, 12029.09781766371, 12032.27806331623, 12068.637940861789, 23569.8787109161, 23640.29079127629, 23655.408614784425, 23665.0, 23694.170704025277, 8084.555349455836, 8364.289035432057, 8400.0, 8401.537915414588, 8411.0, 4400.207962566768, 4573.5426457354, 4660.976695116503, 4711.623070154379, 4764.95235047652, 3464.913377165591, 3550.715942724576, 3595.137167079882, 3606.3328284267495, 3615.2769446110883, 646.7833275852548, 649.3182158733312, 655.6852710698892, 657.2441692054176, 659.2155335151155, 539.511741873605, 620.5873094392178, 639.3925770518026, 655.9147310849654, 671.0705672643425, 9000.324975626723, 9316.301277404222, 9361.812763744687, 9563.61745530171, 9667.661631842851, 515.9825083921847, 528.1760453692176, 556.3963100036425, 595.76765061626, 651.6774196772628, 4547.340635607885, 4591.311303304531, 4609.723416595015, 4629.368204670963, 4666.766661666971, 88100.0, 91258.54370728237, 149997.0, 155879.0, 158396.0, 129286.0, 131215.0, 133755.0, 135024.29951401113, 135241.0, 69397.65301173461, 75570.62214689002, 80205.19794801978, 100698.49650751699, 108248.91751082703, 8468.237858592756, 8656.177663121993, 9005.414648048118, 9039.457632541806, 9201.861972070557, 61788.0, 62819.37180628135, 63258.68370658117, 63306.68346658237, 63622.68188659027, 90903.0, 92417.53791231001, 93520.53239733758, 95684.52157739167, 98094.52858206989, 11213.327200368005, 11441.885581144083, 11654.65036048886, 11975.134358669678, 12063.517459301529, 12604.432800523815, 12619.179753316004, 12678.429470673658, 12694.682632934251, 12737.29944853042, 12101.757964840477, 12111.152219344562, 12165.817512737493, 12167.330796805907, 12228.388580571114, 12078.939605301917, 12100.939495302468, 12103.455344509342, 12239.632811015685, 12296.938515307365, 11758.470868810753, 12006.279623222634, 12025.579104731292, 12041.638750837488, 12064.939675301568, 10020.69937901864, 10036.397816131055, 10341.84487232677, 10532.57869685204, 10589.152867770714, 9815.067568580973, 10200.3369780964, 10212.489375531046, 10249.436281004617, 10279.972002799661], "value": 7732.74477693692, "unit": "score"}], "name": "dromaeo_css", "value": 7364.444166977075, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 112.0, 114.0, 113.0, 114.0, 113.0, 113.0, 113.0, 117.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 107.0, 164.0, 108.0, 110.0, 129.0, 105.0, 145.0, 126.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 186.0, 188.0, 187.0, 185.0, 187.0, 183.0, 185.0, 181.0, 186.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 57.0, 109.0, 58.0, 58.0, 65.0, 67.0, 86.0, 57.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 69.0, 90.0, 69.0, 75.0, 68.0, 121.0, 99.0, 98.0, 97.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 89.0, 86.0, 88.0, 84.0, 88.0, 85.0, 89.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0, 99.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 83.0, 85.0, 83.0, 83.0, 87.0, 84.0, 84.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 52.0, 54.0, 54.0, 53.0, 54.0, 54.0, 52.0, 53.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 91.0, 113.0, 115.0, 74.0, 71.0, 71.0, 71.0, 72.0, 72.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 83.0, 80.0, 86.0, 83.0, 83.0, 85.0, 82.0, 78.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 139.0, 141.0, 121.0, 142.0, 147.0, 152.0, 220.0, 136.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 222.0, 209.0, 163.0, 164.0, 192.0, 158.0, 176.0, 227.0, 165.0], "value": 184.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 67.0, 81.0, 86.0, 85.0, 85.0, 71.0], "value": 74.1, "unit": "score"}], "name": "kraken", "value": 1466.6, "alertThreshold": 2.0}]} 17:19:43 INFO - Return code: 0 17:19:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:19:43 INFO - # TBPL SUCCESS # 17:19:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:19:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:19:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:19:43 INFO - 2016-07-21 17:19:43.679 screenresolution[1956:17728] starting screenresolution argv=screenresolution get 17:19:43 INFO - 2016-07-21 17:19:43.683 screenresolution[1956:17728] Display 0: 1600x1200x32@60 17:19:43 INFO - 2016-07-21 17:19:43.689 screenresolution[1957:17731] starting screenresolution argv=screenresolution list 17:19:43 INFO - Available Modes on Display 0 17:19:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:19:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:19:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:19:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:19:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:19:43 INFO - Intel Iris: 17:19:43 INFO - Chipset Model: Intel Iris 17:19:43 INFO - Type: GPU 17:19:43 INFO - Bus: Built-In 17:19:43 INFO - VRAM (Dynamic, Max): 1536 MB 17:19:43 INFO - Vendor: Intel (0x8086) 17:19:43 INFO - Device ID: 0x0a2e 17:19:43 INFO - Revision ID: 0x0009 17:19:43 INFO - Displays: 17:19:43 INFO - Display: 17:19:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:19:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:19:43 INFO - Display Serial Number: 1600x1200 60 17:19:43 INFO - Main Display: Yes 17:19:43 INFO - Mirror: Off 17:19:43 INFO - Online: Yes 17:19:43 INFO - Rotation: Supported 17:19:43 INFO - Return code: 0 17:19:43 INFO - Running post-action listener: _resource_record_post_action 17:19:43 INFO - [mozharness: 2016-07-22 00:19:43.929581Z] Finished run-tests step (success) 17:19:43 INFO - Running post-run listener: _resource_record_post_run 17:19:43 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 679936; Write bytes: 706809344; Read time: 8; Write time: 2348 17:19:43 INFO - TinderboxPrint: CPU usage
53.4% 17:19:43 INFO - TinderboxPrint: I/O read bytes / time
679,936 / 8 17:19:43 INFO - TinderboxPrint: I/O write bytes / time
706,809,344 / 2,348 17:19:43 INFO - TinderboxPrint: CPU idle
2,259.6 (46.6%) 17:19:43 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 17:19:43 INFO - TinderboxPrint: CPU user
1,624.7 (33.5%) 17:19:43 INFO - TinderboxPrint: Swap in / out
407,781,376 / 0 17:19:43 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 401254912; Write bytes: 396682752; Read time: 30792; Write time: 839 17:19:44 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 679936; Write bytes: 294496256; Read time: 8; Write time: 1470 17:19:44 INFO - Running post-run listener: _upload_blobber_files 17:19:44 INFO - Blob upload gear active. 17:19:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:19:44 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 17:19:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:19:44 INFO - (blobuploader) - INFO - Open directory for files ... 17:19:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:19:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:19:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:19:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:19:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:19:45 INFO - (blobuploader) - INFO - Done attempting. 17:19:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:19:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:19:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:19:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:19:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:19:46 INFO - (blobuploader) - INFO - Done attempting. 17:19:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:19:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:19:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:19:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:19:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:19:49 INFO - (blobuploader) - INFO - Done attempting. 17:19:49 INFO - (blobuploader) - INFO - Iteration through files over. 17:19:49 INFO - Return code: 0 17:19:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:19:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:19:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1eaf9685839b568cff63b2b049dcab31c254dac6d12d88471e5fdead426ab44da2654f30be4e9216c44c4ac808d32bca5bf65b70de7bac5cbb77c35d7d39f89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80e449b2c5735aadd146cdf1db9c880735feb70b7cdf2b54533872d719dbcad4733fb78aee071c8cdddd64cea09c13d67e6b365a8bea9d3af6fe355d387523a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bf5c76b6230dfb4490f8ba591e8c354b9c720322c784abd97145c5540a4594f03fe8378066c5bf02aa73a85f4ab6be1a7d33baa832602333af80c60bfde70b4"} 17:19:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:19:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:19:49 INFO - Contents: 17:19:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1eaf9685839b568cff63b2b049dcab31c254dac6d12d88471e5fdead426ab44da2654f30be4e9216c44c4ac808d32bca5bf65b70de7bac5cbb77c35d7d39f89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80e449b2c5735aadd146cdf1db9c880735feb70b7cdf2b54533872d719dbcad4733fb78aee071c8cdddd64cea09c13d67e6b365a8bea9d3af6fe355d387523a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bf5c76b6230dfb4490f8ba591e8c354b9c720322c784abd97145c5540a4594f03fe8378066c5bf02aa73a85f4ab6be1a7d33baa832602333af80c60bfde70b4"} 17:19:49 INFO - Running post-run listener: copy_logs_to_upload_dir 17:19:49 INFO - Copying logs to upload dir... 17:19:49 INFO - mkdir: /builds/slave/test/build/upload/logs 17:19:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.073004 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-07-21 17:19:49.926784) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 17:19:49.930329) ========= 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.utEXqAU3nL/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.ile810U4Ki/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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/Firefox/sha512/b1eaf9685839b568cff63b2b049dcab31c254dac6d12d88471e5fdead426ab44da2654f30be4e9216c44c4ac808d32bca5bf65b70de7bac5cbb77c35d7d39f89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80e449b2c5735aadd146cdf1db9c880735feb70b7cdf2b54533872d719dbcad4733fb78aee071c8cdddd64cea09c13d67e6b365a8bea9d3af6fe355d387523a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bf5c76b6230dfb4490f8ba591e8c354b9c720322c784abd97145c5540a4594f03fe8378066c5bf02aa73a85f4ab6be1a7d33baa832602333af80c60bfde70b4"} build_url:https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010873 build_url: 'https://queue.taskcluster.net/v1/task/BGeJ2HmqRyOXnJ97aD4MTg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1eaf9685839b568cff63b2b049dcab31c254dac6d12d88471e5fdead426ab44da2654f30be4e9216c44c4ac808d32bca5bf65b70de7bac5cbb77c35d7d39f89", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80e449b2c5735aadd146cdf1db9c880735feb70b7cdf2b54533872d719dbcad4733fb78aee071c8cdddd64cea09c13d67e6b365a8bea9d3af6fe355d387523a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bf5c76b6230dfb4490f8ba591e8c354b9c720322c784abd97145c5540a4594f03fe8378066c5bf02aa73a85f4ab6be1a7d33baa832602333af80c60bfde70b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 17:19:49.962653) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 17:19:49.962989) ========= 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.utEXqAU3nL/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.ile810U4Ki/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.005288 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 17:19:50.011741) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 17:19:50.012058) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 17:19:50.012420) ========= ========= Total master_lag: 0.18 =========