builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0362 starttime: 1467849630.5 results: success (0) buildid: 20160706145022 builduid: b7c2214cd62743389d4b4490b6b26b74 revision: b005ddc33470f04b39e054ecca452773ff4f74a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:30.498885) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:30.499320) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:30.499624) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:30.524854) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:30.525217) ========= 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.TPHSuwlKga/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.ObQpK7lkPA/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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-06 17:00:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-07-06 17:00:30 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:30.655232) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:30.655577) ========= 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.TPHSuwlKga/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.ObQpK7lkPA/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.025880 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:30.705070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 17:00:30.705415) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b005ddc33470f04b39e054ecca452773ff4f74a2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b005ddc33470f04b39e054ecca452773ff4f74a2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TPHSuwlKga/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.ObQpK7lkPA/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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-06 17:00:30,784 truncating revision to first 12 chars 2016-07-06 17:00:30,784 Setting DEBUG logging. 2016-07-06 17:00:30,784 attempt 1/10 2016-07-06 17:00:30,784 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b005ddc33470?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 17:00:31,568 unpacking tar archive at: fx-team-b005ddc33470/testing/mozharness/ program finished with exit code 0 elapsedTime=1.070981 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 17:00:31.798500) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:31.798896) ========= script_repo_revision: b005ddc33470f04b39e054ecca452773ff4f74a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:31.799461) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:31.799746) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 17:00:31.817879) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-07-06 17:00:31.818325) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TPHSuwlKga/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.ObQpK7lkPA/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:00:31 INFO - MultiFileLogger online at 20160706 17:00:31 in /builds/slave/test 17:00:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:00:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:00:32 INFO - {'append_to_log': False, 17:00:32 INFO - 'base_work_dir': '/builds/slave/test', 17:00:32 INFO - 'blob_upload_branch': 'Fx-Team', 17:00:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:00:32 INFO - 'branch': 'Fx-Team', 17:00:32 INFO - 'buildbot_json_path': 'buildprops.json', 17:00:32 INFO - 'config_files': ('talos/mac_config.py',), 17:00:32 INFO - 'default_actions': ('clobber', 17:00:32 INFO - 'read-buildbot-config', 17:00:32 INFO - 'download-and-extract', 17:00:32 INFO - 'populate-webroot', 17:00:32 INFO - 'create-virtualenv', 17:00:32 INFO - 'install', 17:00:32 INFO - 'run-tests'), 17:00:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:00:32 INFO - 'download_minidump_stackwalk': True, 17:00:32 INFO - 'download_symbols': 'ondemand', 17:00:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:00:32 INFO - 'tooltool.py': '/tools/tooltool.py', 17:00:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:00:32 INFO - '/tools/misc-python/virtualenv.py')}, 17:00:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:00:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:00:32 INFO - 'installer_path': 'installer.exe', 17:00:32 INFO - 'log_level': 'info', 17:00:32 INFO - 'log_name': 'talos', 17:00:32 INFO - 'log_to_console': True, 17:00:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:00:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:00:32 INFO - 'opt_config_files': (), 17:00:32 INFO - 'pip_index': False, 17:00:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:00:32 INFO - 'cmd': ('bash', 17:00:32 INFO - '-c', 17:00:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:00:32 INFO - 'enabled': True, 17:00:32 INFO - 'halt_on_failure': False, 17:00:32 INFO - 'name': 'check_screen_resolution'},), 17:00:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:00:32 INFO - 'cmd': ('bash', 17:00:32 INFO - '-c', 17:00:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:00:32 INFO - 'enabled': True, 17:00:32 INFO - 'halt_on_failure': False, 17:00:32 INFO - 'name': 'check_screen_resolution'},), 17:00:32 INFO - 'run_cmd_checks_enabled': True, 17:00:32 INFO - 'sps_profile': False, 17:00:32 INFO - 'sps_profile_interval': 0, 17:00:32 INFO - 'suite': 'dromaeojs', 17:00:32 INFO - 'system_bits': '32', 17:00:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:00:32 INFO - 'title': 't-yosemite-r7-0362', 17:00:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:00:32 INFO - 'use_talos_json': True, 17:00:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:00:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:00:32 INFO - 'work_dir': 'build'} 17:00:32 INFO - ##### 17:00:32 INFO - ##### Running clobber step. 17:00:32 INFO - ##### 17:00:32 INFO - Running pre-action listener: _resource_record_pre_action 17:00:32 INFO - Running main action method: clobber 17:00:32 INFO - rmtree: /builds/slave/test/build 17:00:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:00:33 INFO - Running post-action listener: _resource_record_post_action 17:00:33 INFO - ##### 17:00:33 INFO - ##### Finished clobber step (success) 17:00:33 INFO - ##### 17:00:33 INFO - ##### 17:00:33 INFO - ##### Running read-buildbot-config step. 17:00:33 INFO - ##### 17:00:33 INFO - Running pre-action listener: _resource_record_pre_action 17:00:33 INFO - Running main action method: read_buildbot_config 17:00:33 INFO - Using buildbot properties: 17:00:33 INFO - { 17:00:33 INFO - "project": "", 17:00:33 INFO - "product": "firefox", 17:00:33 INFO - "script_repo_revision": "production", 17:00:33 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:00:33 INFO - "repository": "", 17:00:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:00:33 INFO - "buildid": "20160706145022", 17:00:33 INFO - "pgo_build": "False", 17:00:33 INFO - "basedir": "/builds/slave/test", 17:00:33 INFO - "buildnumber": 88, 17:00:33 INFO - "slavename": "t-yosemite-r7-0362", 17:00:33 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:00:33 INFO - "platform": "yosemite_r7", 17:00:33 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:00:33 INFO - "branch": "fx-team", 17:00:33 INFO - "repo_path": "integration/fx-team", 17:00:33 INFO - "revision": "b005ddc33470f04b39e054ecca452773ff4f74a2", 17:00:33 INFO - "stage_platform": "macosx64", 17:00:33 INFO - "builduid": "b7c2214cd62743389d4b4490b6b26b74", 17:00:33 INFO - "slavebuilddir": "test" 17:00:33 INFO - } 17:00:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 17:00:33 INFO - Running post-action listener: _resource_record_post_action 17:00:33 INFO - ##### 17:00:33 INFO - ##### Finished read-buildbot-config step (success) 17:00:33 INFO - ##### 17:00:33 INFO - ##### 17:00:33 INFO - ##### Running download-and-extract step. 17:00:33 INFO - ##### 17:00:33 INFO - Running pre-action listener: _resource_record_pre_action 17:00:33 INFO - Running main action method: download_and_extract 17:00:33 INFO - mkdir: /builds/slave/test/build/tests 17:00:33 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:33 INFO - https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:00:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 17:00:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 17:00:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:35 INFO - Downloaded 1199 bytes. 17:00:35 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 17:00:35 INFO - Using the following test package requirements: 17:00:35 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 17:00:35 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:00:35 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 17:00:35 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:00:35 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 17:00:35 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:00:35 INFO - u'jsshell-mac.zip'], 17:00:35 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:00:35 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 17:00:35 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 17:00:35 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:00:35 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 17:00:35 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:00:35 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 17:00:35 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:00:35 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 17:00:35 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 17:00:35 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 17:00:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:35 INFO - https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:00:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:00:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:00:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:39 INFO - Downloaded 18802648 bytes. 17:00:39 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'] 17:00:39 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 17:00:42 INFO - Return code: 0 17:00:42 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 17:00: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')]} 17:00:42 INFO - https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:00:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:00:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 17:00:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:46 INFO - Downloaded 18802648 bytes. 17:00:46 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'] 17:00:46 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 17:00:50 INFO - Return code: 0 17:00:50 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:50 INFO - https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:00:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 17:00:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 17:00:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:51 INFO - Downloaded 14403333 bytes. 17:00:51 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'] 17:00:51 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 17:00:51 INFO - Return code: 0 17:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:51 INFO - https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:00:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:00:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:00:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 17:00:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OmV9Q09vRRO8gLP9trccQQ/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 17:00:54 INFO - Downloaded 112491695 bytes. 17:00:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:00:54 INFO - mkdir: /builds/slave/test/properties 17:00:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:00:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:00:54 INFO - Contents: 17:00:54 INFO - build_url:https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 17:00:56 INFO - Running post-action listener: _resource_record_post_action 17:00:56 INFO - Running post-action listener: set_extra_try_arguments 17:00:56 INFO - ##### 17:00:56 INFO - ##### Finished download-and-extract step (success) 17:00:56 INFO - ##### 17:00:56 INFO - ##### 17:00:56 INFO - ##### Running populate-webroot step. 17:00:56 INFO - ##### 17:00:56 INFO - Running pre-action listener: _resource_record_pre_action 17:00:56 INFO - Running main action method: populate_webroot 17:00:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:00:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:00:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:00:56 INFO - '--tppagecycles', 17:00:56 INFO - '7'], 17:00:56 INFO - 'tests': ['tsvgm']}}, 17:00:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:00:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:00:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:00:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:00:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:56 INFO - 'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:00:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:56 INFO - 'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['damp', 'tps']}, 17:00:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:56 INFO - 'tests': ['damp', 'tps']}, 17:00:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['dromaeo_dom']}, 17:00:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:00:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['basic_compositor_video']}, 17:00:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:00:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['a11yr', 17:00:56 INFO - 'ts_paint', 17:00:56 INFO - 'tpaint', 17:00:56 INFO - 'sessionrestore', 17:00:56 INFO - 'sessionrestore_no_auto_restore', 17:00:56 INFO - 'tabpaint']}, 17:00:56 INFO - 'other-e10s': {'tests': ['a11yr', 17:00:56 INFO - 'ts_paint', 17:00:56 INFO - 'tpaint', 17:00:56 INFO - 'sessionrestore', 17:00:56 INFO - 'sessionrestore_no_auto_restore', 17:00:56 INFO - 'tabpaint']}, 17:00:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:00:56 INFO - 'ts_paint', 17:00:56 INFO - 'tpaint', 17:00:56 INFO - 'sessionrestore', 17:00:56 INFO - 'sessionrestore_no_auto_restore', 17:00:56 INFO - 'tabpaint']}, 17:00:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:00:56 INFO - 'ts_paint', 17:00:56 INFO - 'tpaint', 17:00:56 INFO - 'sessionrestore', 17:00:56 INFO - 'sessionrestore_no_auto_restore', 17:00:56 INFO - 'tabpaint']}, 17:00:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['a11yr', 17:00:56 INFO - 'ts_paint', 17:00:56 INFO - 'tpaint', 17:00:56 INFO - 'sessionrestore', 17:00:56 INFO - 'sessionrestore_no_auto_restore', 17:00:56 INFO - 'tabpaint']}, 17:00:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['a11yr', 17:00:56 INFO - 'ts_paint', 17:00:56 INFO - 'tpaint', 17:00:56 INFO - 'sessionrestore', 17:00:56 INFO - 'sessionrestore_no_auto_restore', 17:00:56 INFO - 'tabpaint']}, 17:00:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['tsvgx', 17:00:56 INFO - 'tsvgr_opacity', 17:00:56 INFO - 'tart', 17:00:56 INFO - 'tscrollx', 17:00:56 INFO - 'cart']}, 17:00:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:00:56 INFO - 'tsvgr_opacity', 17:00:56 INFO - 'tart', 17:00:56 INFO - 'tscrollx', 17:00:56 INFO - 'cart']}, 17:00:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:56 INFO - 'talos_options': ['--disable-e10s'], 17:00:56 INFO - 'tests': ['tp5o']}, 17:00:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:56 INFO - 'tests': ['tp5o']}, 17:00:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:56 INFO - 'talos_options': ['--disable-e10s', 17:00:56 INFO - '--xperf_path', 17:00:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:56 INFO - 'tests': ['tp5n']}, 17:00:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:00:56 INFO - 'talos_options': ['--xperf_path', 17:00:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:00:56 INFO - 'tests': ['tp5n']}}, 17:00:56 INFO - 'talos.zip': {'path': '', 17:00:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:00:56 INFO - Running post-action listener: _resource_record_post_action 17:00:56 INFO - ##### 17:00:56 INFO - ##### Finished populate-webroot step (success) 17:00:56 INFO - ##### 17:00:56 INFO - ##### 17:00:56 INFO - ##### Running create-virtualenv step. 17:00:56 INFO - ##### 17:00:56 INFO - Running pre-action listener: _resource_record_pre_action 17:00:56 INFO - Running main action method: create_virtualenv 17:00:56 INFO - Creating virtualenv /builds/slave/test/build/venv 17:00:56 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 17:00:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:00:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:00:56 INFO - Using real prefix '/tools/python27' 17:00:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:00:56 INFO - Installing distribute.............................................................................................................................................................................................done. 17:00:59 INFO - Installing pip.................done. 17:00:59 INFO - Return code: 0 17:00:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:00:59 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:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:59 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:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:00:59 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:00:59 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 17:00:59 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 17:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:00:59 INFO - 'HOME': '/Users/cltbld', 17:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:00:59 INFO - 'LOGNAME': 'cltbld', 17:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:00:59 INFO - 'MOZ_NO_REMOTE': '1', 17:00:59 INFO - 'NO_EM_RESTART': '1', 17:00:59 INFO - 'PAGER': '/bin/cat', 17:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:00:59 INFO - 'PWD': '/builds/slave/test', 17:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:00:59 INFO - 'SHELL': '/bin/bash', 17:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:00:59 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:00:59 INFO - 'USER': 'cltbld', 17:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:00:59 INFO - 'XPC_FLAGS': '0x0', 17:00:59 INFO - 'XPC_SERVICE_NAME': '0', 17:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:00 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 17:01:00 INFO - Cleaning up... 17:01:00 INFO - Return code: 0 17:01:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:01:00 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:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:00 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:01:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:00 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:01:00 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 17:01:00 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 17:01:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:01:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:00 INFO - 'HOME': '/Users/cltbld', 17:01:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:00 INFO - 'LOGNAME': 'cltbld', 17:01:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:00 INFO - 'MOZ_NO_REMOTE': '1', 17:01:00 INFO - 'NO_EM_RESTART': '1', 17:01:00 INFO - 'PAGER': '/bin/cat', 17:01:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:00 INFO - 'PWD': '/builds/slave/test', 17:01:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:00 INFO - 'SHELL': '/bin/bash', 17:01:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:01:00 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:01:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:00 INFO - 'USER': 'cltbld', 17:01:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:00 INFO - 'XPC_FLAGS': '0x0', 17:01:00 INFO - 'XPC_SERVICE_NAME': '0', 17:01:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:00 INFO - Downloading/unpacking psutil>=3.1.1 17:01:00 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 17:01:00 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 17:01:00 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 17:01:00 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 17:01:00 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 17:01:00 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 17:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:01:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:01:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:01:02 INFO - Installing collected packages: psutil 17:01:02 INFO - Running setup.py install for psutil 17:01:02 INFO - building 'psutil._psutil_osx' extension 17:01:02 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 17:01:03 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 17:01:03 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 17:01:03 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 17:01:03 INFO - building 'psutil._psutil_posix' extension 17:01:03 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 17:01:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:01:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:01:03 INFO - ^ 17:01:03 INFO - 1 warning generated. 17:01:03 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 17:01:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:01:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:01:04 INFO - Successfully installed psutil 17:01:04 INFO - Cleaning up... 17:01:04 INFO - Return code: 0 17:01:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:01:04 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:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:04 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:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:04 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:01:04 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 17:01:04 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 17:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:04 INFO - 'HOME': '/Users/cltbld', 17:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:04 INFO - 'LOGNAME': 'cltbld', 17:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:04 INFO - 'MOZ_NO_REMOTE': '1', 17:01:04 INFO - 'NO_EM_RESTART': '1', 17:01:04 INFO - 'PAGER': '/bin/cat', 17:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:04 INFO - 'PWD': '/builds/slave/test', 17:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:04 INFO - 'SHELL': '/bin/bash', 17:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:01:04 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:04 INFO - 'USER': 'cltbld', 17:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:04 INFO - 'XPC_FLAGS': '0x0', 17:01:04 INFO - 'XPC_SERVICE_NAME': '0', 17:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:01:04 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 17:01:04 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 17:01:04 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 17:01:04 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 17:01:04 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 17:01:04 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 17:01:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:01:06 INFO - Installing collected packages: mozsystemmonitor 17:01:06 INFO - Running setup.py install for mozsystemmonitor 17:01:07 INFO - Successfully installed mozsystemmonitor 17:01:07 INFO - Cleaning up... 17:01:07 INFO - Return code: 0 17:01:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:01: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')]} 17:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01: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')]} 17:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01: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', '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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:01: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:01: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 jsonschema==2.5.1 17:01:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:01:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:07 INFO - 'HOME': '/Users/cltbld', 17:01:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:07 INFO - 'LOGNAME': 'cltbld', 17:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:07 INFO - 'MOZ_NO_REMOTE': '1', 17:01:07 INFO - 'NO_EM_RESTART': '1', 17:01:07 INFO - 'PAGER': '/bin/cat', 17:01:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:07 INFO - 'PWD': '/builds/slave/test', 17:01:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:07 INFO - 'SHELL': '/bin/bash', 17:01:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:01:07 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:01:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:07 INFO - 'USER': 'cltbld', 17:01:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:07 INFO - 'XPC_FLAGS': '0x0', 17:01:07 INFO - 'XPC_SERVICE_NAME': '0', 17:01:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:07 INFO - Downloading/unpacking jsonschema==2.5.1 17:01: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 17:01: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 17:01: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 17:01: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 17:01: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 17:01: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 17:01:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:01:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:01: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 17:01: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 17:01: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 17:01: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 17:01: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 17:01: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 17:01:11 INFO - Downloading functools32-3.2.3-2.tar.gz 17:01:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:01:11 INFO - warning: no files found matching '*.txt' 17:01:11 INFO - no previously-included directories found matching 'build' 17:01:11 INFO - no previously-included directories found matching 'dist' 17:01:11 INFO - no previously-included directories found matching '.git*' 17:01:11 INFO - Installing collected packages: jsonschema, functools32 17:01:11 INFO - Running setup.py install for functools32 17:01:11 INFO - warning: no files found matching '*.txt' 17:01:11 INFO - no previously-included directories found matching 'build' 17:01:11 INFO - no previously-included directories found matching 'dist' 17:01:11 INFO - no previously-included directories found matching '.git*' 17:01:11 INFO - Successfully installed jsonschema functools32 17:01:11 INFO - Cleaning up... 17:01:11 INFO - Return code: 0 17:01:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:01:11 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:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:11 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:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:11 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:01:11 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 17:01:11 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 17:01:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:01:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:11 INFO - 'HOME': '/Users/cltbld', 17:01:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:11 INFO - 'LOGNAME': 'cltbld', 17:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:11 INFO - 'MOZ_NO_REMOTE': '1', 17:01:11 INFO - 'NO_EM_RESTART': '1', 17:01:11 INFO - 'PAGER': '/bin/cat', 17:01:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:11 INFO - 'PWD': '/builds/slave/test', 17:01:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:11 INFO - 'SHELL': '/bin/bash', 17:01:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:01:11 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:01:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:11 INFO - 'USER': 'cltbld', 17:01:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:11 INFO - 'XPC_FLAGS': '0x0', 17:01:11 INFO - 'XPC_SERVICE_NAME': '0', 17:01:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:11 INFO - Downloading/unpacking blobuploader==1.2.4 17:01:11 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 17:01:11 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 17:01:11 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 17:01:11 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 17:01:11 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 17:01:11 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 17:01:14 INFO - Downloading blobuploader-1.2.4.tar.gz 17:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:01:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:01: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 17:01: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 17:01: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 17:01: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 17:01: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 17:01: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 17:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:01:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:01:15 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 17:01:15 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 17:01:15 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 17:01:15 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 17:01:15 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 17:01:15 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 17:01:15 INFO - Downloading docopt-0.6.1.tar.gz 17:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:01:15 INFO - Installing collected packages: blobuploader, requests, docopt 17:01:15 INFO - Running setup.py install for blobuploader 17:01:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:01:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:01:15 INFO - Running setup.py install for requests 17:01:16 INFO - Running setup.py install for docopt 17:01:16 INFO - Successfully installed blobuploader requests docopt 17:01:16 INFO - Cleaning up... 17:01:16 INFO - Return code: 0 17:01:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:01:16 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:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:16 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:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:16 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:01:16 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 17:01:16 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 17:01:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:01:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:16 INFO - 'HOME': '/Users/cltbld', 17:01:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:16 INFO - 'LOGNAME': 'cltbld', 17:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:16 INFO - 'MOZ_NO_REMOTE': '1', 17:01:16 INFO - 'NO_EM_RESTART': '1', 17:01:16 INFO - 'PAGER': '/bin/cat', 17:01:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:16 INFO - 'PWD': '/builds/slave/test', 17:01:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:16 INFO - 'SHELL': '/bin/bash', 17:01:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:01:16 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:01:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:16 INFO - 'USER': 'cltbld', 17:01:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:16 INFO - 'XPC_FLAGS': '0x0', 17:01:16 INFO - 'XPC_SERVICE_NAME': '0', 17:01:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:01:16 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-SRM8YG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:01:16 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-O9kwD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-l9ywEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Soz2KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-DtRJRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-z3hLkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-CAiH9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-1nmeWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-wibGqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Lpmzsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-BeCf9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:01:17 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-MLnKBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:01:18 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-iHOMtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:01:18 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-FNPhTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:01:18 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-wA6S6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:01:18 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-oMd394-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:01:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:01:18 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Y0TgHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:01:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:01:18 INFO - Running setup.py install for manifestparser 17:01:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:01:18 INFO - Running setup.py install for mozcrash 17:01:18 INFO - Running setup.py install for mozdebug 17:01:18 INFO - Running setup.py install for mozdevice 17:01:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:01:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:01:19 INFO - Running setup.py install for mozfile 17:01:19 INFO - Running setup.py install for mozhttpd 17:01:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:01:19 INFO - Running setup.py install for mozinfo 17:01:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:01:19 INFO - Running setup.py install for mozInstall 17:01:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:01:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:01:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:01:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:01:19 INFO - Running setup.py install for mozleak 17:01:19 INFO - Running setup.py install for mozlog 17:01:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:01:20 INFO - Running setup.py install for moznetwork 17:01:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:01:20 INFO - Running setup.py install for mozprocess 17:01:20 INFO - Running setup.py install for mozprofile 17:01:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:01:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:01:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:01:20 INFO - Running setup.py install for mozrunner 17:01:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:01:20 INFO - Running setup.py install for mozscreenshot 17:01:20 INFO - Running setup.py install for moztest 17:01:20 INFO - Running setup.py install for mozversion 17:01:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:01:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:01:21 INFO - Cleaning up... 17:01:21 INFO - Return code: 0 17:01:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:21 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:01:21 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 17:01:21 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 17:01:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:21 INFO - 'HOME': '/Users/cltbld', 17:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:21 INFO - 'LOGNAME': 'cltbld', 17:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:21 INFO - 'MOZ_NO_REMOTE': '1', 17:01:21 INFO - 'NO_EM_RESTART': '1', 17:01:21 INFO - 'PAGER': '/bin/cat', 17:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:21 INFO - 'PWD': '/builds/slave/test', 17:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:21 INFO - 'SHELL': '/bin/bash', 17:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:01:21 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:21 INFO - 'USER': 'cltbld', 17:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:21 INFO - 'XPC_FLAGS': '0x0', 17:01:21 INFO - 'XPC_SERVICE_NAME': '0', 17:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:01:21 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-YF4K4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:01:21 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)) 17:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:01:21 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-zjmBv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:01:21 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)) 17:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:01:21 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-XSr6Tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:01:21 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)) 17:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:01:21 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-pHoAVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:01:21 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)) 17:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:01:21 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-CyhZ2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:01:21 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)) 17:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:01:21 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-nlM4wj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:01:22 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-KIMu9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:01:22 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-jAJyCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:01:22 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-cKrNiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:01:22 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-62tbl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:01:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Qm8kLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:01:22 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-N38zBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:01:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-bq0tA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:01:22 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-JX1p7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:01:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 17:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:01:22 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-d6xWAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:01:23 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)) 17:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:01:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-2jfhiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:01:23 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)) 17:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:01:23 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-30E9YC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:01:23 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)) 17:01:23 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)) 17:01:23 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)) 17:01:23 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)) 17:01:23 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)) 17:01:23 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)) 17:01:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:01:23 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 17:01:23 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 17:01:23 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 17:01:23 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 17:01:23 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 17:01:23 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 17:01:25 INFO - Downloading blessings-1.6.tar.gz 17:01:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:01:25 INFO - Installing collected packages: blessings 17:01:25 INFO - Running setup.py install for blessings 17:01:25 INFO - Successfully installed blessings 17:01:25 INFO - Cleaning up... 17:01:25 INFO - Return code: 0 17:01:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:01:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:01:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:01:26 INFO - Reading from file tmpfile_stdout 17:01:26 INFO - Current package versions: 17:01:26 INFO - blessings == 1.6 17:01:26 INFO - blobuploader == 1.2.4 17:01:26 INFO - docopt == 0.6.1 17:01:26 INFO - functools32 == 3.2.3-2 17:01:26 INFO - jsonschema == 2.5.1 17:01:26 INFO - manifestparser == 1.1 17:01:26 INFO - mozInstall == 1.12 17:01:26 INFO - mozcrash == 0.17 17:01:26 INFO - mozdebug == 0.1 17:01:26 INFO - mozdevice == 0.48 17:01:26 INFO - mozfile == 1.2 17:01:26 INFO - mozhttpd == 0.7 17:01:26 INFO - mozinfo == 0.9 17:01:26 INFO - mozleak == 0.1 17:01:26 INFO - mozlog == 3.2 17:01:26 INFO - moznetwork == 0.27 17:01:26 INFO - mozprocess == 0.22 17:01:26 INFO - mozprofile == 0.28 17:01:26 INFO - mozrunner == 6.11 17:01:26 INFO - mozscreenshot == 0.1 17:01:26 INFO - mozsystemmonitor == 0.3 17:01:26 INFO - moztest == 0.7 17:01:26 INFO - mozversion == 1.4 17:01:26 INFO - psutil == 3.1.1 17:01:26 INFO - requests == 1.2.3 17:01:26 INFO - wsgiref == 0.1.2 17:01:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:01:26 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:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:26 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:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:26 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:01:26 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 17:01:26 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 17:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:26 INFO - 'HOME': '/Users/cltbld', 17:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:26 INFO - 'LOGNAME': 'cltbld', 17:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:26 INFO - 'MOZ_NO_REMOTE': '1', 17:01:26 INFO - 'NO_EM_RESTART': '1', 17:01:26 INFO - 'PAGER': '/bin/cat', 17:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:26 INFO - 'PWD': '/builds/slave/test', 17:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:26 INFO - 'SHELL': '/bin/bash', 17:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:01:26 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:26 INFO - 'USER': 'cltbld', 17:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:26 INFO - 'XPC_FLAGS': '0x0', 17:01:26 INFO - 'XPC_SERVICE_NAME': '0', 17:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 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)) 17:01:26 INFO - Cleaning up... 17:01:26 INFO - Return code: 0 17:01:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:01:26 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:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:26 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:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:01:26 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 0x1101baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcea2cb4450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, '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.TPHSuwlKga/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.ObQpK7lkPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 17:01:26 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 17:01:26 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 17:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:01:26 INFO - 'HOME': '/Users/cltbld', 17:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:01:26 INFO - 'LOGNAME': 'cltbld', 17:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:01:26 INFO - 'MOZ_NO_REMOTE': '1', 17:01:26 INFO - 'NO_EM_RESTART': '1', 17:01:26 INFO - 'PAGER': '/bin/cat', 17:01:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:01:26 INFO - 'PWD': '/builds/slave/test', 17:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:01:26 INFO - 'SHELL': '/bin/bash', 17:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:01:26 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:01:26 INFO - 'USER': 'cltbld', 17:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:01:26 INFO - 'XPC_FLAGS': '0x0', 17:01:26 INFO - 'XPC_SERVICE_NAME': '0', 17:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:01:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:01:26 INFO - Cleaning up... 17:01:26 INFO - Return code: 0 17:01:26 INFO - Running post-action listener: _resource_record_post_action 17:01:26 INFO - Running post-action listener: _start_resource_monitoring 17:01:26 INFO - Starting resource monitoring. 17:01:26 INFO - ##### 17:01:26 INFO - ##### Finished create-virtualenv step (success) 17:01:26 INFO - ##### 17:01:26 INFO - ##### 17:01:26 INFO - ##### Running install step. 17:01:26 INFO - ##### 17:01:26 INFO - Running pre-action listener: _resource_record_pre_action 17:01:26 INFO - Running main action method: install 17:01:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:01:27 INFO - Reading from file tmpfile_stdout 17:01:27 INFO - Detecting whether we're running mozinstall >=1.0... 17:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:01:27 INFO - Reading from file tmpfile_stdout 17:01:27 INFO - Output received: 17:01:27 INFO - Usage: mozinstall [options] installer 17:01:27 INFO - Options: 17:01:27 INFO - -h, --help show this help message and exit 17:01:27 INFO - -d DEST, --destination=DEST 17:01:27 INFO - Directory to install application into. [default: 17:01:27 INFO - "/builds/slave/test"] 17:01:27 INFO - --app=APP Application being installed. [default: firefox] 17:01:27 INFO - mkdir: /builds/slave/test/build/application 17:01:27 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'] 17:01:27 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:02:02 INFO - Reading from file tmpfile_stdout 17:02:02 INFO - Output received: 17:02:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:02:02 INFO - Running post-action listener: _resource_record_post_action 17:02:02 INFO - ##### 17:02:02 INFO - ##### Finished install step (success) 17:02:02 INFO - ##### 17:02:02 INFO - ##### 17:02:02 INFO - ##### Running run-tests step. 17:02:02 INFO - ##### 17:02:02 INFO - Running pre-action listener: _resource_record_pre_action 17:02:02 INFO - Running main action method: run_tests 17:02:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:02:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:02:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:02:02 INFO - 2016-07-06 17:02:02.319 screenresolution[1656:7682] starting screenresolution argv=screenresolution get 17:02:02 INFO - 2016-07-06 17:02:02.322 screenresolution[1656:7682] Display 0: 1600x1200x32@60 17:02:02 INFO - 2016-07-06 17:02:02.328 screenresolution[1657:7689] starting screenresolution argv=screenresolution list 17:02:02 INFO - Available Modes on Display 0 17:02:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:02:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:02:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:02:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:02:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:02:02 INFO - Intel Iris: 17:02:02 INFO - Chipset Model: Intel Iris 17:02:02 INFO - Type: GPU 17:02:02 INFO - Bus: Built-In 17:02:02 INFO - VRAM (Dynamic, Max): 1536 MB 17:02:02 INFO - Vendor: Intel (0x8086) 17:02:02 INFO - Device ID: 0x0a2e 17:02:02 INFO - Revision ID: 0x0009 17:02:02 INFO - Displays: 17:02:02 INFO - Display: 17:02:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:02:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:02:02 INFO - Display Serial Number: 1600x1200 60 17:02:02 INFO - Main Display: Yes 17:02:02 INFO - Mirror: Off 17:02:02 INFO - Online: Yes 17:02:02 INFO - Rotation: Supported 17:02:02 INFO - Return code: 0 17:02:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:02:02 INFO - Python 2.7.3 17:02:02 INFO - Return code: 0 17:02:02 INFO - grabbing minidump binary from tooltool 17:02:02 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:02:02 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 0x11011ed30>, '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 0x10fe83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110116b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:02:02 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:02:02 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:02:02 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:02:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:02:02 INFO - Return code: 0 17:02:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:02:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:02:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:02:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:02:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:02:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--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:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0362 --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:02:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TPHSuwlKga/Render', 17:02:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:02 INFO - 'HOME': '/Users/cltbld', 17:02:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:02 INFO - 'LOGNAME': 'cltbld', 17:02:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:02:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:02 INFO - 'MOZ_NO_REMOTE': '1', 17:02:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:02:02 INFO - 'NO_EM_RESTART': '1', 17:02:02 INFO - 'PAGER': '/bin/cat', 17:02:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:02 INFO - 'PWD': '/builds/slave/test', 17:02:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:02:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:02 INFO - 'SHELL': '/bin/bash', 17:02:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObQpK7lkPA/Listeners', 17:02:02 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 17:02:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:02 INFO - 'USER': 'cltbld', 17:02:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:02 INFO - 'XPC_FLAGS': '0x0', 17:02:02 INFO - 'XPC_SERVICE_NAME': '0', 17:02:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--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:02:02 INFO - mozversion application_buildid: 20160706145022 17:02:02 INFO - mozversion application_changeset: b005ddc33470f04b39e054ecca452773ff4f74a2 17:02:02 INFO - mozversion application_display_name: Nightly 17:02:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:02:02 INFO - mozversion application_name: Firefox 17:02:02 INFO - mozversion application_remotingname: firefox 17:02:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:02:02 INFO - mozversion application_vendor: Mozilla 17:02:02 INFO - mozversion application_version: 50.0a1 17:02:02 INFO - mozversion platform_buildid: 20160706145022 17:02:02 INFO - mozversion platform_changeset: b005ddc33470f04b39e054ecca452773ff4f74a2 17:02:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:02:02 INFO - mozversion platform_version: 50.0a1 17:02:02 INFO - using testdate: 1467849722 17:02:02 INFO - actual date: 1467849722 17:02:02 INFO - starting webserver on 'localhost:49271' 17:02:02 INFO - SUITE-START | Running 2 tests 17:02:02 INFO - TEST-START | dromaeo_css 17:02:02 INFO - operating with platform_type : mac_ 17:02:02 INFO - Initialising browser for dromaeo_css test... 17:02:02 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpfsisap/profile http://localhost:49271/getInfo.html) 17:02:05 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 17:02:05 INFO - PROCESS | 1667 | colorDepth:24 17:02:05 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 17:02:05 INFO - PROCESS | 1667 | __metrics 17:02:05 INFO - PROCESS | 1667 | 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:02:05 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:02:05 INFO - TEST-INFO | 1667: exit 0 17:02:05 INFO - Browser initialized. 17:02:05 INFO - Running cycle 1/1 for dromaeo_css test... 17:02:05 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpfsisap/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:02:17 INFO - PROCESS | 1669 | RSS: Main: 243912704 17:02:17 INFO - PROCESS | 1669 | 17:03:09 INFO - PROCESS | 1669 | 1467849789625 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:05:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 17:05:21 INFO - PROCESS | 1669 | RSS: Main: 368709632 17:05:21 INFO - PROCESS | 1669 | 17:08:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 17:08:25 INFO - PROCESS | 1669 | RSS: Main: 380096512 17:08:25 INFO - PROCESS | 1669 | 17:11:27 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 17:11:27 INFO - PROCESS | 1669 | RSS: Main: 369745920 17:11:27 INFO - PROCESS | 1669 | 17:14:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 17:14:30 INFO - PROCESS | 1669 | RSS: Main: 372297728 17:14:30 INFO - PROCESS | 1669 | 17:17:34 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 17:17:34 INFO - PROCESS | 1669 | RSS: Main: 342626304 17:17:34 INFO - PROCESS | 1669 | 17:20:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 17:20:38 INFO - PROCESS | 1669 | RSS: Main: 399745024 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - PROCESS | 1669 | __start_tp_report 17:20:38 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 17:20:38 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 17:20:38 INFO - PROCESS | 1669 | |i|pagename|runs| 17:20:38 INFO - PROCESS | 1669 | |0;dojo.html;3282.9507557386637;3283.7044665980056;3297.653746356632;3311.503274508822;3350.430426827439;5500.2849629548155;5516.365617953927;5576.749046292916;5577.8605534861645;5637.4926256636945;5607.102863541834;5617.578681598886;5673.914891276634;5752.913706294409;5763.711814409273;5051.343325367716;5067.467915868836;5104.259882317062;5118.9744051279695;5222.791088356461;5185.833187532813;5253.133233016548;5269.209618557209;5269.420363759983;5290.153575427932;5056.823011194609;5066.949330506684;5101.923471147936;5107.923381149286;5150.768215430315;5095.515925987025;5125.769340379692;5144.228365745131;5223;5225.869353266199;5082.263071854561;5103.872403189913;5115.130427827278;5185.818496352647;5202.739863006835;5483.725813709339;5603.411641777596;5684.857878553069;5691.51622112121;5709.400512946164;6089.4091805406215;6137.223770008885;6145.324014358417;6160.1991741073825;6169.290531588835;6167.4757645600175;6189.257289125334;6233.096201050867;6247.500199983991;6253.968730156365;5603.93525230205;5677.290338707661;5720.11338242573;5758.48173664368;5778.942210577882;4842.878928026827;5957.910631340534;6201.286852011986;6259.96870015647;6279.811605651837;6143.784967526204;6153.261608606996;6156.445919867234;6172.969135154295;6178.41305076017;6057.242844644419;6082.513398928075;6252.124702541648;6253.812385628438;6259.6870156492;6102.908456373158;6113.266408030976;6119.143319935212;6120.326764055951;6133.049377346519;4907.411110666671;6103.42017508336;6130.153553065287;6137.171481849982;6182.969085154546;4839.467658557556;4846.854594362175;4858.489858564871;4884.120858245479;4886.193778026657;4299.290617048152;4333.71830830995;4343.913121737548;4349.391085248068;4422.646188304992;5525.668459892419;5595.7481913313995;5597.944020559742;5599.776008959596;5600.411956744483;6118.938810611926;6143.143438301211;6150.077488376779;6170.167027451236;6171.413715697002;5541.944580554224;5580.5535557156145;5582.051051321285;5620.437956204347;5674.092145256822;5326;5346.331708536433;5348.685488329006;5349.438308977501;5353.652012619167;5189.662671926313;6220.502359811204;6225.595336303126;6253.968730156229;6302.621842689453;6178.443940045418;6200.100985357189;6215.689215539295;6238.251409830849;6240.313565507881;6271.529635277281;6281.434670879643;6292.2134733158355;6312.5265605078885;6317.399847014526;5057.519535644108;5078.365204349457;6005.609635373687;6074.726637301421;6109.847253818512;5069.290299358019;6184.350643182581;6231.189945307262;6237.282712488122;6253.093301471352;6041.728122234422;6046.455818976313;6050.636961782131;6055.969720151371;6107.725152368243;5844.970775146267;5991.490723288395;6022.518198544019;6030.276366836008;6041.365656606169;5895.056790913433;5912.645241285537;5921.733521991607;5945.524358051432;5967.343592204941;5931.792387266425;6066.636001839904;6092.725827337869;6093.102607848001;6105.633661980295;5705.657660540381;5725.5992080555625;5798.768049277982;5820.214271073351;5846.152307915413 17:20:38 INFO - PROCESS | 1669 | |1;ext.html;478.99521004789943;502.8466317773079;520.4223312123543;537.7472587883693;544.7902557515358;29528.852355738218;41379.58620413795;42747.57252427471;42858.57141428585;42862.785686071526;36725.632743672555;44369.778151109196;44717;44757.328640070424;45009.87475313119;8726.171013753716;9702.126808587243;9890.90109098907;9892.901070989306;9897.604095836157;8741.650333986632;11863.110266729987;12134.393280335948;12151.210171338835;12375.504979800797;12078.758424831498;12100.394980250996;12140.635780926586;12147.088968327367;12151;8801;8968.372213945026;8992.910070899272;11973.521059157623;12056.457459414347;8832.425892316978;11688.064954803594;11979.880201197964;11984.64046078619;12233;42149;42494.57505424968;42546.78726606378;42564.787176063925;42595.57404425978;3530.749436476448;3616.710663146942;4078.3066878630407;4275.615194632467;4285.142971405731;31469.37061258762;32330.83834580812;32365.51451728226;32645.836770816233;32666;3210.11721776511;3338.165458635341;3347.581552305962;3360.176756694601;3373.342198271338;459907;528595;528848;529286.4749733779;529430;60232.795344092876;60253.69873150606;60483.69758151181;60576.39423605796;60584.182474525835;64664.353356466774;64864.675676621315;64900;64950;64960.350396496375;16826.41107561254;17095.829041709672;17107.315707371567;17144.914275428542;17148.657026859393;11903.642890713292;12229.877701223051;13048.021398394969;13110.541131060367;13298.401571929286;13392.531261405804;16253.67492650164;16256.43102491407;16269.349226031063;16333.428330008393;7121.750738724119;7184.568925864465;7195.208527062019;7207.531510451804;7262.891056634155;871.0244526130774;913.6208473483563;976.3799987008301;977.5063592885547;1070.978580428387;30600.54099188514;31838.522422163685;32217.677823221937;32322.515162272586;33804;1122.186414849244;1137.3005601555149;1175.529788084773;1183.3195912350434;1222.0162768971063;15327.693446131016;15660.530184094496;15708.371665133267;16076.67846643084;16084.43704470338;679365;679940.2005979877;683081;685459;690235;59132.70433647804;59307.11039334413;59454.70272648609;59494.405055949755;59573.40426595765;1278.7442511497552;1371.6022353517567;1424.3163281624877;1437.2526286331258;1444.9205293708737;30913.918012869442;31225.687743123188;31727.84136079305;31937.882174123795;32193;51622;51827.96344073022;52321.7383913078;52357.429277121744;52384.214236785716;532650;533915;534664;534954;537006;23969.52060958806;26068.8696556516;26517.60223596609;26950;26966.865165674048;47802.04395912132;47845.76077119592;48522.02955940932;48830.75584622054;48952;38571.42142867803;39978.60021399749;40165.59834401619;40188.79905600454;40216.59783402128;38554.61445385622;39679.809605711875;39697.603023969394;40317.39523907202;40498.1900361997;38055;38391.61608383992;38926.80536597299;39170.608293916695;39190.02024949399;32928.012159634294;34903.75729149077;35083.64916350804;35337.469937951464;35511.82244088763;25406.1890716394;25626.743732562434;25698.229053128434;26005.869970650027;26546.734532654427 17:20:38 INFO - PROCESS | 1669 | |2;jquery.html;361.87515307219;381.4373798646996;386.52071431425026;389.1050583657588;411.37060297744443;15662.686746265068;15729.842701572981;21069.367918962456;21099.68350474744;21155.788442115576;21323.57352852942;21881.890590547064;22147.335579932627;22196;22240.888795556;5149.227615857614;5773.422657734224;5971.5222782177325;5981.222441082653;6021.036634138539;5075.720835354054;5898.587098903078;5978.372270911535;6002.459778619924;6019.367966363535;5955.136505206742;6306.180196574439;6651.2018557773135;6662.7668031618905;6723.798286051427;5720.685362305072;5771.567132465061;6273.96863015682;6599.10912026875;6665.233498147702;5861.413858614147;5900.498457631106;5902.586818922677;5914.4381283777975;5920.496757775594;13520.053596248266;18365.449036528924;27082.864585677144;27348.863255683795;27381.04166354188;2087.7912208779;2178.6405243134864;2235.9329220123423;2534.721180670125;2947.8673459694364;10095.798084038352;10459.633912813015;10590.417527036087;11295.887041129567;16619.750703739453;1575.8818088643397;1818.4726429335476;1838.6046999895086;1858.3774435564103;1885.962280754391;110706.44646776715;125564;125929;126060.36969815093;127261.36369318095;9639.807203855884;10288.074073333286;10956.780864382668;11636.476358563885;11762.764744705059;14551.927240363942;14571.562853114423;14673.972821902364;14702.632434189018;14722.705545889237;7246.094238220223;8516.489010659381;8677.349198810114;8766.342524310703;8829.205371516595;7181.569105853666;8764.824703505894;8775.736727898046;8893.377463577617;8894.73315800527;4534.070515544371;4627.686156921539;4637.211674015425;4692.343071969927;4729.030548737501;1756.657451796913;1876.277633111262;1917.3157579388967;1923.980760192408;1926.1909997800958;799.9600019998977;825.8678611422143;930.2000279759537;1020.4795554267229;1059.7297689089387;22138.44653883666;22278.443038923833;22314.665280020814;22329.55340893173;22366;777.8366543026028;960.2318145483586;972.9173020293355;1001.519270750044;1061.082163928212;11149.442527873574;11664.766704665859;11680.357580333164;11700.06399488039;11710.063194944383;534174;536540.4984112398;544786;555544.2222788942;560608;130825;131289;134148;134801;136677;993.5429702337005;1000.229823036258;1034.7930413917247;1060.5121644043672;1060.5545670818274;19174.424767257005;19505.902470487556;19546.90226548915;19573.923434211254;19668.40994770159;43043.56956430521;45274.77362613166;56513.71743141258;56751.71624141853;56994.7150264246;82481;83224.58387708022;86365.13634863571;86787.95257674526;87251.5836153934;7047;7866.292033716993;8148.348132149295;8244.587770611566;8244.83510329802;11712.88287117118;13534.59396218115;13801.378937947615;13866;13874;7391.9651248826285;11741.002014828835;11800.114991375509;11883.643490695293;11936.28382297065;11155.776884462428;11672.182947193955;11740.765184696087;11922.76154476923;12138.02895768354;11507.42462876836;11704.122190835891;11793.941030294794;12132.939335303266;12190.329531875832;7604.505707129019;7664.501807382502;7678.6544605494;7685.4620176587105;7852.882206767017;9890.307678462439;11292;11781.469833857325;12000.919917207493;12072.33602151855 17:20:38 INFO - PROCESS | 1669 | |3;mootools.html;1424.8931330150237;1568.6313716276672;1749.9562510937217;1774.5386199588104;1881.5860510687653;7512.774616761492;8466.492010479367;8765.737027889158;8936.061713520085;8954.55227238638;7766.57283849388;8017.358611311096;8025.598720064003;8108;8724.127587241288;2132.1791110422523;2263.784940430665;2348.54390278027;2361.669366288721;2362.5629258587132;2341.508283260517;2348.5655153796515;2373.7982271506944;2379.1910750344878;2380.4643955110137;2087.6451003329394;2354.176038386568;2369.478714682767;2369.6327069304284;2371.513839662865;2271.068861766676;2343.086196383412;2344.589696803061;2348.2955113466064;2350.6131382484245;2313.9305820825402;2338.6024375855977;2339.812814974798;2341.098677009347;2347.0377145370317;8722.215000649972;8837.116288371175;8838;8846.557672116433;8862.86705699415;5208.218767184952;5350.438203988564;5356.651817631841;5361.517463428271;5399.757010934478;3276.2464633134273;3346.6318704942437;3593.7484376093685;3640.7087433005563;3682.760620559655;1076.6608518316784;1085.5657736905223;1098.6099934523209;1112.227002233448;1113.192935122038;11552.884471155181;11622.825657615143;11631.534738610446;11639.767204655944;11672.766544669059;4491.079328737601;4577.764003719044;4693.366395536627;4699.130660827758;4778.713277203379;9783.559739811726;9795.167410770022;9812.607495700235;9815.901840981642;9820.116189542976;5308.654937429054;5310.070737620901;5318.069337865858;5320.600954928318;5324.8136315228785;4642.628589712815;4822.252550854623;4882.584980276716;4885.780139893714;4887.633427492881;3692.335379631692;3758.830852611639;3763.5295588051495;3864.768113913174;3867.9806600966817;1174.2972689599944;1178.2385333925836;1181.704573856536;1188.423614546944;1189.29831399473;900.909909009107;911.3210658059788;913.6847787513298;928.8890427694593;952.2382094324513;5811.82564523065;5853.2976042875125;5933.317668468181;6052.727627256784;6105.633661980295;434.59148400503426;440.17247574560264;449.74364612171405;452.5451920819555;462.72236658004925;3846.9613204434645;3913.158670885773;4014.7791871446766;4015.357542793139;4092.7135100542678;10217.386956782617;10419.06228439444;10436.128571357838;10626.521806518725;10651.84022239667;10461.633842815465;10586.841197382046;10601.522931468102;10613.628523001813;10616.991385818335;7425.443091768139;7790.337821285254;8186.099529051799;8200.671973121127;8208.83582328362;5752.856178595569;5754.395788442321;5829.6793676346515;5930.881382372242;5943.286805583358;9906.554205060933;9908.306418550921;9912.405255684682;9986.201103911524;10070.19438444908;8875.201231888903;9781.559829807818;9999.600015999278;10136.594536218468;10138.239632027775;6066.423689749643;7098.893516597152;7116.395106416013;7191.676374563052;7202.171750248579;7527.962360188164;8079.990001249844;8277.54473503963;8449.788755281168;8455.450395724258;7474.663640136098;7549.547027178387;7599.544027358376;7673.079230492377;7687.961560192163;7170.677319520739;7424.071991001125;7425.51734137301;7467.962660186664;7564.167941526538;6979.581225126509;6989.0564773754895;7062.223155452793;7076.433885289268;7092.6099064551945;4987.850364489072;4997.5002499749735;5028.798848046037;5033.094043072282;5119.948800512097;5061.468545802638;5067.9239811403595;5102.260172274925;5112.105381558138;5214.739263036909 17:20:38 INFO - PROCESS | 1669 | |4;prototype.html;310.23217536596917;313.68004635272024;318.32991552781385;320.08934581116716;323.77174092264926;4631.490536041033;5262.2108483115735;5380.8923821523595;5411.567074634029;5476.205950137238;4778.068276686047;4902;4984.352034235553;5312.495312945264;5386.084365657838;3405.3359594879007;3532.3288575170764;3675.9231034465574;3928;4077.103037331782;3416.829158542075;4059.4316795648633;4067.4102255172984;4083.9591604084026;4126.752394856303;4050.7772072536;4061.979690101545;4087.1621317629815;4094.97952510237;4109.97945010276;3184.936301273973;3580.03339098442;3648.6135268597764;3980.7412518186225;4035.2534781065597;3538.2569660371355;3541.1324225564645;3553.4492153716214;3564.590072141711;3582.1940063485767;4354.281543545311;5182.533571978503;5198.662086964373;5229.607779416559;5281.603879709037;4142.689298302609;4241.321388577812;4367.432233809616;4515.909681806378;4707.905841883143;4608.677392582521;5075.868135383111;5118.539331460186;5589.832305030855;5610.49505544503;3604.387254166798;3915.725899187058;3918.7060970427333;3927.057506198492;3963.0488682716227;5745.626534275259;5849.473547380671;5868.559858010666;5933;5966.522678185735;3139.3878193752;3921.215756848642;3943.7633741975;3960.1881614269005;3979.5275747973437;3414;4030.9596904030586;4035.7982100894837;4049.9392509112386;4052.209819085309;4224.577542245751;4572.1312950539295;4632.791524381411;4634.559716826896;4681.695689780154;4937.247277216608;5584.190292407659;5614.490943160525;5675.006873797069;5719.428057194247;1342.5634571009123;1388.0006395395299;1389.1387339849357;1391.7355702416507;1392.8258967629047;735.8417940142895;747.1669088965873;834.6452757578005;838.6268110690684;860.9711394883179;4739.464386553177;4978.780198851305;5043.5965122790085;5101.20931255656;5224.190250511177;4969.925451118236;5411.728243862689;5791.391903850119;5805.970970145122;5826.504747096459;4829.9275510867365;4973.5523802857915;5037.395512538519;5271.499207575274;5290.65610735301;3024.349764800578;3060.908172754821;3076.0005996102263;3083.98458007713;3109.720125188744;5405.486478784509;5765.250517432707;5913.881722365529;6033.78881739137;6093.6039157454625;4946.802127914843;5156.690598564098;5638.830835074954;5868.16358941828;5967.837046365434;5999.89002034625;6022.849428764316;6036.215292012098;6046.853367054809;6063.241225242504;5149.528226451399;5487.478689524489;5827.446392592697;5847.590668653153;5851.561132914963;4694.577488026094;4841.322214889848;5076.400933705871;5539.365887063245;5803.912941305799;5073.8731531712;5820.243368362086;6139.815805525842;6179.443850053517;6186.443220110212;3285;3627.6190999944624;3878.8836334909997;3896.220755848785;3915.804209789556;5636.267285252891;5754.4533269339345;5803.361630220588;5809.564282678901;5881.117832325185;4976.5521103099445;5671.638806686448;5680.517156041783;5732.598718089694;5758.596898217084;5348.812791552277;5619.662820230799;5636.633618814764;5645.181448689999;5747.9137812932;4967.3542439482635;5177.663451875616;5511.58663100261;5512.236084452937;5516.310461192351;4779.5220477951925;5420.83737487876;5623.3533143689;5642.3229212493125;5695.345035320991;4643.419572553431;5567.359753628222;5588.245586845724;5621.2411324472305;5624.57815663835 17:20:38 INFO - PROCESS | 1669 | |5;yui.html;731.3454458259854;754.924507549245;779.7193010516213;804.342657896822;823.3372135430976;15493.302801373937;16175.919120404382;16186.595335116599;16193.271302791372;16194.190290485487;12115.454804533794;14649;14796.852031479682;14833.035852669596;14867.256637168153;746.5408773604248;773.9961300193519;918.6784625381114;933.0249888866117;941.2234906202376;944.1974321826427;944.5607792376544;944.6363150187159;945.9905400946005;946.4037656276571;976.0922342221731;990.5245482168564;991.7371896447447;996.2826764729383;996.5714742660689;777.9066512018594;813.3046245460106;815.1807433529265;986.7483791633124;988.9615903301541;602.5781952633174;727.6507276507253;754.5661244784271;758.5676164586188;759.1270039454616;14830.406783728638;14854.257287135708;14855.851441485554;14860.331285092198;14884.553463396116;13723.656908577366;13915.721685566334;13978.860211397858;13983.79024314636;13998.860011399858;12372.81440778389;12518.436670349856;12669.93665031669;12671.429785659668;13080.738385232242;4493.236149854532;4542.685058481551;4544.590986811169;4698.459677137139;4756.405449318835;88634.55682721545;92829.5358523203;106105;108506;109043;11398.202125851276;11770.293782373084;11781.705457363463;11804.493355316657;12160.391980401122;23397.41506462352;23428.531429371316;23436.17973370924;23443;23449;8136.633851476698;8208.343332533383;8338.04112527055;8485.406021578554;8559.828803423896;4383.583559561836;4436.046250056189;4481.35020422037;4500.797464114123;4676.228422310349;3009.5786589877;3541.876034338837;3636.581793093828;3658.9817050914576;3662.285854258447;686.5503095472474;687.3435868745336;688.5627626457135;694.1496666583496;694.9166100067923;584.2346526050802;603.9818805435845;608.8173547935602;671.4694698951284;706.9469789765789;9167.22078623311;9544.379615324982;9599.376040557341;9624.326297159272;9715.319927605142;665.8934570468701;685.1230425055936;687.1239170058216;695.0200217693028;703.4899697719216;4543.4775000875325;4560.156371071363;4645.363364771294;4671.5094915034115;4717.858464246078;92485.075149249;92996;93158.53420732988;118348;126971.74683645534;126977.74644648068;128485.35757321154;128508;129157.06264406041;132551.33724331317;111293.88706112998;112044.99999999837;113551;113796;115551;8829.823403531766;9114.361994660576;9176.724698258797;9188.229224541514;9268.72193834186;64093;65392;65569.67215163894;66092.66953665201;67116.32883671296;93769.5311523438;96688.51655741676;97811;98069.03861922864;100308.49845750724;10094.8485772712;11936.224145430519;11960.282383057045;12152.392380381123;12595.118341715992;10816.783664326827;12830.075488676774;13122;13153.802692959805;13470.393832277767;12028.819567706667;12121.75756484883;12456.252624842538;12941.288229147487;13021.674458138623;12181.390930453264;12410.503579856704;12491.437885295001;12727;12738.745225095632;11892.405379731152;12140.514379424723;12231.571894983634;12355.93822030884;12602.054845886412;10246.692599222035;10266.897331026594;10384.584616615553;10525.368477891045;11172.21794474379;10350.327228730108;10410.427426491615;10516.474176291307;10542.156627469938;10608.363498190134 17:20:38 INFO - PROCESS | 1669 | __end_tp_report 17:20:38 INFO - PROCESS | 1669 | __start_cc_report 17:20:38 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1277 17:20:38 INFO - PROCESS | 1669 | __end_cc_report 17:20:38 INFO - PROCESS | 1669 | __startTimestamp1467850838147__endTimestamp 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - PROCESS | 1669 | ------- Summary: start ------- 17:20:38 INFO - PROCESS | 1669 | Number of tests: 6 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5642.07 Median:5778.94 stddev:645.15 (11.2%) stddev-sans-first:620.02 17:20:38 INFO - PROCESS | 1669 | Values: 3283.0 3283.7 3297.7 3311.5 3350.4 5500.3 5516.4 5576.7 5577.9 5637.5 5607.1 5617.6 5673.9 5752.9 5763.7 5051.3 5067.5 5104.3 5119.0 5222.8 5185.8 5253.1 5269.2 5269.4 5290.2 5056.8 5066.9 5101.9 5107.9 5150.8 5095.5 5125.8 5144.2 5223.0 5225.9 5082.3 5103.9 5115.1 5185.8 5202.7 5483.7 5603.4 5684.9 5691.5 5709.4 6089.4 6137.2 6145.3 6160.2 6169.3 6167.5 6189.3 6233.1 6247.5 6254.0 5603.9 5677.3 5720.1 5758.5 5778.9 4842.9 5957.9 6201.3 6260.0 6279.8 6143.8 6153.3 6156.4 6173.0 6178.4 6057.2 6082.5 6252.1 6253.8 6259.7 6102.9 6113.3 6119.1 6120.3 6133.0 4907.4 6103.4 6130.2 6137.2 6183.0 4839.5 4846.9 4858.5 4884.1 4886.2 4299.3 4333.7 4343.9 4349.4 4422.6 5525.7 5595.7 5597.9 5599.8 5600.4 6118.9 6143.1 6150.1 6170.2 6171.4 5541.9 5580.6 5582.1 5620.4 5674.1 5326.0 5346.3 5348.7 5349.4 5353.7 5189.7 6220.5 6225.6 6254.0 6302.6 6178.4 6200.1 6215.7 6238.3 6240.3 6271.5 6281.4 6292.2 6312.5 6317.4 5057.5 5078.4 6005.6 6074.7 6109.8 5069.3 6184.4 6231.2 6237.3 6253.1 6041.7 6046.5 6050.6 6056.0 6107.7 5845.0 5991.5 6022.5 6030.3 6041.4 5895.1 5912.6 5921.7 5945.5 5967.3 5931.8 6066.6 6092.7 6093.1 6105.6 5705.7 5725.6 5798.8 5820.2 5846.2 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:71657.14 Median:39180.31 stddev:155730.85 (397.5%) stddev-sans-first:156076.02 17:20:38 INFO - PROCESS | 1669 | Values: 479.0 502.8 520.4 537.7 544.8 29528.9 41379.6 42747.6 42858.6 42862.8 36725.6 44369.8 44717.0 44757.3 45009.9 8726.2 9702.1 9890.9 9892.9 9897.6 8741.7 11863.1 12134.4 12151.2 12375.5 12078.8 12100.4 12140.6 12147.1 12151.0 8801.0 8968.4 8992.9 11973.5 12056.5 8832.4 11688.1 11979.9 11984.6 12233.0 42149.0 42494.6 42546.8 42564.8 42595.6 3530.7 3616.7 4078.3 4275.6 4285.1 31469.4 32330.8 32365.5 32645.8 32666.0 3210.1 3338.2 3347.6 3360.2 3373.3 459907.0 528595.0 528848.0 529286.5 529430.0 60232.8 60253.7 60483.7 60576.4 60584.2 64664.4 64864.7 64900.0 64950.0 64960.4 16826.4 17095.8 17107.3 17144.9 17148.7 11903.6 12229.9 13048.0 13110.5 13298.4 13392.5 16253.7 16256.4 16269.3 16333.4 7121.8 7184.6 7195.2 7207.5 7262.9 871.0 913.6 976.4 977.5 1071.0 30600.5 31838.5 32217.7 32322.5 33804.0 1122.2 1137.3 1175.5 1183.3 1222.0 15327.7 15660.5 15708.4 16076.7 16084.4 679365.0 679940.2 683081.0 685459.0 690235.0 59132.7 59307.1 59454.7 59494.4 59573.4 1278.7 1371.6 1424.3 1437.3 1444.9 30913.9 31225.7 31727.8 31937.9 32193.0 51622.0 51828.0 52321.7 52357.4 52384.2 532650.0 533915.0 534664.0 534954.0 537006.0 23969.5 26068.9 26517.6 26950.0 26966.9 47802.0 47845.8 48522.0 48830.8 48952.0 38571.4 39978.6 40165.6 40188.8 40216.6 38554.6 39679.8 39697.6 40317.4 40498.2 38055.0 38391.6 38926.8 39170.6 39190.0 32928.0 34903.8 35083.6 35337.5 35511.8 25406.2 25626.7 25698.2 26005.9 26546.7 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:34172.61 Median:22296.55 stddev:92095.73 (413.0%) stddev-sans-first:92319.09 17:20:38 INFO - PROCESS | 1669 | Values: 361.9 381.4 386.5 389.1 411.4 15662.7 15729.8 21069.4 21099.7 21155.8 21323.6 21881.9 22147.3 22196.0 22240.9 5149.2 5773.4 5971.5 5981.2 6021.0 5075.7 5898.6 5978.4 6002.5 6019.4 5955.1 6306.2 6651.2 6662.8 6723.8 5720.7 5771.6 6274.0 6599.1 6665.2 5861.4 5900.5 5902.6 5914.4 5920.5 13520.1 18365.4 27082.9 27348.9 27381.0 2087.8 2178.6 2235.9 2534.7 2947.9 10095.8 10459.6 10590.4 11295.9 16619.8 1575.9 1818.5 1838.6 1858.4 1886.0 110706.4 125564.0 125929.0 126060.4 127261.4 9639.8 10288.1 10956.8 11636.5 11762.8 14551.9 14571.6 14674.0 14702.6 14722.7 7246.1 8516.5 8677.3 8766.3 8829.2 7181.6 8764.8 8775.7 8893.4 8894.7 4534.1 4627.7 4637.2 4692.3 4729.0 1756.7 1876.3 1917.3 1924.0 1926.2 800.0 825.9 930.2 1020.5 1059.7 22138.4 22278.4 22314.7 22329.6 22366.0 777.8 960.2 972.9 1001.5 1061.1 11149.4 11664.8 11680.4 11700.1 11710.1 534174.0 536540.5 544786.0 555544.2 560608.0 130825.0 131289.0 134148.0 134801.0 136677.0 993.5 1000.2 1034.8 1060.5 1060.6 19174.4 19505.9 19546.9 19573.9 19668.4 43043.6 45274.8 56513.7 56751.7 56994.7 82481.0 83224.6 86365.1 86788.0 87251.6 7047.0 7866.3 8148.3 8244.6 8244.8 11712.9 13534.6 13801.4 13866.0 13874.0 7392.0 11741.0 11800.1 11883.6 11936.3 11155.8 11672.2 11740.8 11922.8 12138.0 11507.4 11704.1 11793.9 12132.9 12190.3 7604.5 7664.5 7678.7 7685.5 7852.9 9890.3 11292.0 11781.5 12000.9 12072.3 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5641.69 Median:5030.95 stddev:3164.64 (62.9%) stddev-sans-first:3157.65 17:20:38 INFO - PROCESS | 1669 | Values: 1424.9 1568.6 1750.0 1774.5 1881.6 7512.8 8466.5 8765.7 8936.1 8954.6 7766.6 8017.4 8025.6 8108.0 8724.1 2132.2 2263.8 2348.5 2361.7 2362.6 2341.5 2348.6 2373.8 2379.2 2380.5 2087.6 2354.2 2369.5 2369.6 2371.5 2271.1 2343.1 2344.6 2348.3 2350.6 2313.9 2338.6 2339.8 2341.1 2347.0 8722.2 8837.1 8838.0 8846.6 8862.9 5208.2 5350.4 5356.7 5361.5 5399.8 3276.2 3346.6 3593.7 3640.7 3682.8 1076.7 1085.6 1098.6 1112.2 1113.2 11552.9 11622.8 11631.5 11639.8 11672.8 4491.1 4577.8 4693.4 4699.1 4778.7 9783.6 9795.2 9812.6 9815.9 9820.1 5308.7 5310.1 5318.1 5320.6 5324.8 4642.6 4822.3 4882.6 4885.8 4887.6 3692.3 3758.8 3763.5 3864.8 3868.0 1174.3 1178.2 1181.7 1188.4 1189.3 900.9 911.3 913.7 928.9 952.2 5811.8 5853.3 5933.3 6052.7 6105.6 434.6 440.2 449.7 452.5 462.7 3847.0 3913.2 4014.8 4015.4 4092.7 10217.4 10419.1 10436.1 10626.5 10651.8 10461.6 10586.8 10601.5 10613.6 10617.0 7425.4 7790.3 8186.1 8200.7 8208.8 5752.9 5754.4 5829.7 5930.9 5943.3 9906.6 9908.3 9912.4 9986.2 10070.2 8875.2 9781.6 9999.6 10136.6 10138.2 6066.4 7098.9 7116.4 7191.7 7202.2 7528.0 8080.0 8277.5 8449.8 8455.5 7474.7 7549.5 7599.5 7673.1 7688.0 7170.7 7424.1 7425.5 7468.0 7564.2 6979.6 6989.1 7062.2 7076.4 7092.6 4987.9 4997.5 5028.8 5033.1 5119.9 5061.5 5067.9 5102.3 5112.1 5214.7 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4544.82 Median:5058.73 stddev:1409.84 (27.9%) stddev-sans-first:1377.50 17:20:38 INFO - PROCESS | 1669 | Values: 310.2 313.7 318.3 320.1 323.8 4631.5 5262.2 5380.9 5411.6 5476.2 4778.1 4902.0 4984.4 5312.5 5386.1 3405.3 3532.3 3675.9 3928.0 4077.1 3416.8 4059.4 4067.4 4084.0 4126.8 4050.8 4062.0 4087.2 4095.0 4110.0 3184.9 3580.0 3648.6 3980.7 4035.3 3538.3 3541.1 3553.4 3564.6 3582.2 4354.3 5182.5 5198.7 5229.6 5281.6 4142.7 4241.3 4367.4 4515.9 4707.9 4608.7 5075.9 5118.5 5589.8 5610.5 3604.4 3915.7 3918.7 3927.1 3963.0 5745.6 5849.5 5868.6 5933.0 5966.5 3139.4 3921.2 3943.8 3960.2 3979.5 3414.0 4031.0 4035.8 4049.9 4052.2 4224.6 4572.1 4632.8 4634.6 4681.7 4937.2 5584.2 5614.5 5675.0 5719.4 1342.6 1388.0 1389.1 1391.7 1392.8 735.8 747.2 834.6 838.6 861.0 4739.5 4978.8 5043.6 5101.2 5224.2 4969.9 5411.7 5791.4 5806.0 5826.5 4829.9 4973.6 5037.4 5271.5 5290.7 3024.3 3060.9 3076.0 3084.0 3109.7 5405.5 5765.3 5913.9 6033.8 6093.6 4946.8 5156.7 5638.8 5868.2 5967.8 5999.9 6022.8 6036.2 6046.9 6063.2 5149.5 5487.5 5827.4 5847.6 5851.6 4694.6 4841.3 5076.4 5539.4 5803.9 5073.9 5820.2 6139.8 6179.4 6186.4 3285.0 3627.6 3878.9 3896.2 3915.8 5636.3 5754.5 5803.4 5809.6 5881.1 4976.6 5671.6 5680.5 5732.6 5758.6 5348.8 5619.7 5636.6 5645.2 5747.9 4967.4 5177.7 5511.6 5512.2 5516.3 4779.5 5420.8 5623.4 5642.3 5695.3 4643.4 5567.4 5588.2 5621.2 5624.6 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:23673.47 Median:4022.93 stddev:36477.03 (906.7%) stddev-sans-first:36538.68 17:20:38 INFO - PROCESS | 1669 | Values: 731.3 754.9 779.7 804.3 823.3 15493.3 16175.9 16186.6 16193.3 16194.2 12115.5 14649.0 14796.9 14833.0 14867.3 746.5 774.0 918.7 933.0 941.2 944.2 944.6 944.6 946.0 946.4 976.1 990.5 991.7 996.3 996.6 777.9 813.3 815.2 986.7 989.0 602.6 727.7 754.6 758.6 759.1 14830.4 14854.3 14855.9 14860.3 14884.6 13723.7 13915.7 13978.9 13983.8 13998.9 12372.8 12518.4 12669.9 12671.4 13080.7 4493.2 4542.7 4544.6 4698.5 4756.4 88634.6 92829.5 106105.0 108506.0 109043.0 11398.2 11770.3 11781.7 11804.5 12160.4 23397.4 23428.5 23436.2 23443.0 23449.0 8136.6 8208.3 8338.0 8485.4 8559.8 4383.6 4436.0 4481.4 4500.8 4676.2 3009.6 3541.9 3636.6 3659.0 3662.3 686.6 687.3 688.6 694.1 694.9 584.2 604.0 608.8 671.5 706.9 9167.2 9544.4 9599.4 9624.3 9715.3 665.9 685.1 687.1 695.0 703.5 4543.5 4560.2 4645.4 4671.5 4717.9 92485.1 92996.0 93158.5 118348.0 126971.7 126977.7 128485.4 128508.0 129157.1 132551.3 111293.9 112045.0 113551.0 113796.0 115551.0 8829.8 9114.4 9176.7 9188.2 9268.7 64093.0 65392.0 65569.7 66092.7 67116.3 93769.5 96688.5 97811.0 98069.0 100308.5 10094.8 11936.2 11960.3 12152.4 12595.1 10816.8 12830.1 13122.0 13153.8 13470.4 12028.8 12121.8 12456.3 12941.3 13021.7 12181.4 12410.5 12491.4 12727.0 12738.7 11892.4 12140.5 12231.6 12355.9 12602.1 10246.7 10266.9 10384.6 10525.4 11172.2 10350.3 10410.4 10516.5 10542.2 10608.4 17:20:38 INFO - PROCESS | 1669 | -------- Summary: end -------- 17:20:38 INFO - PROCESS | 1669 | 17:20:38 INFO - TEST-INFO | 1669: exit 0 17:20:38 INFO - TEST-OK | dromaeo_css | took 1115823ms 17:20:38 INFO - TEST-START | kraken 17:20:38 INFO - operating with platform_type : mac_ 17:20:38 INFO - Initialising browser for kraken test... 17:20:38 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpy0b2X9/profile http://localhost:49271/getInfo.html) 17:20:40 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 17:20:40 INFO - PROCESS | 1678 | colorDepth:24 17:20:40 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 17:20:40 INFO - PROCESS | 1678 | __metrics 17:20:41 INFO - PROCESS | 1678 | 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:20:41 INFO - PROCESS | 1678 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:20:41 INFO - TEST-INFO | 1678: exit 0 17:20:41 INFO - Browser initialized. 17:20:41 INFO - Running cycle 1/1 for kraken test... 17:20:41 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpy0b2X9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:20:54 INFO - PROCESS | 1679 | RSS: Main: 245137408 17:20:54 INFO - PROCESS | 1679 | 17:20:56 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 17:20:57 INFO - PROCESS | 1679 | RSS: Main: 294772736 17:20:57 INFO - PROCESS | 1679 | 17:21:00 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 17:21:00 INFO - PROCESS | 1679 | RSS: Main: 304459776 17:21:00 INFO - PROCESS | 1679 | 17:21:04 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 17:21:04 INFO - PROCESS | 1679 | RSS: Main: 389816320 17:21:04 INFO - PROCESS | 1679 | 17:21:06 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 17:21:07 INFO - PROCESS | 1679 | RSS: Main: 290394112 17:21:07 INFO - PROCESS | 1679 | 17:21:09 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:21:10 INFO - PROCESS | 1679 | RSS: Main: 294027264 17:21:10 INFO - PROCESS | 1679 | 17:21:12 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 17:21:13 INFO - PROCESS | 1679 | RSS: Main: 330285056 17:21:13 INFO - PROCESS | 1679 | 17:21:16 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 17:21:17 INFO - PROCESS | 1679 | RSS: Main: 332185600 17:21:17 INFO - PROCESS | 1679 | 17:21:19 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 17:21:20 INFO - PROCESS | 1679 | RSS: Main: 333123584 17:21:20 INFO - PROCESS | 1679 | 17:21:21 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:21:22 INFO - PROCESS | 1679 | RSS: Main: 312061952 17:21:22 INFO - PROCESS | 1679 | 17:21:24 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:21:25 INFO - PROCESS | 1679 | RSS: Main: 319066112 17:21:25 INFO - PROCESS | 1679 | 17:21:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:21:27 INFO - PROCESS | 1679 | RSS: Main: 316014592 17:21:27 INFO - PROCESS | 1679 | 17:21:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:21:31 INFO - PROCESS | 1679 | RSS: Main: 325054464 17:21:31 INFO - PROCESS | 1679 | 17:21:34 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:21:34 INFO - PROCESS | 1679 | RSS: Main: 326504448 17:21:34 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 17:21:36 INFO - PROCESS | 1679 | RSS: Main: 369876992 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | __start_tp_report 17:21:36 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 17:21:36 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 17:21:36 INFO - PROCESS | 1679 | |i|pagename|runs| 17:21:36 INFO - PROCESS | 1679 | |0;ai-astar;101;112;114;112;113;112;112;112;112;112 17:21:36 INFO - PROCESS | 1679 | |1;audio-beat-detection;147;117;140;196;109;127;129;105;159;108 17:21:36 INFO - PROCESS | 1679 | |2;audio-dft;185;183;183;185;183;185;180;180;184;185 17:21:36 INFO - PROCESS | 1679 | |3;audio-fft;81;74;59;107;55;56;60;63;96;56 17:21:36 INFO - PROCESS | 1679 | |4;audio-oscillator;85;91;91;71;71;68;97;70;68;99 17:21:36 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;87;83;87;83;87;84;87;83;87;84 17:21:36 INFO - PROCESS | 1679 | |6;imaging-darkroom;92;92;93;92;92;93;92;101;140;91 17:21:36 INFO - PROCESS | 1679 | |7;imaging-desaturate;86;87;84;84;95;125;86;86;84;86 17:21:36 INFO - PROCESS | 1679 | |8;json-parse-financial;56;56;57;57;56;56;56;56;52;51 17:21:36 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;66;70;69;73;72;71;70;71;63;69 17:21:36 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;86;82;83;81;82;80;85;84;83;80 17:21:36 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;143;152;136;126;140;151;156;134;184;138 17:21:36 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;164;166;162;162;163;163;160;162;155;141 17:21:36 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;66;67;67;66;67;65;68;69;87 17:21:36 INFO - PROCESS | 1679 | __end_tp_report 17:21:36 INFO - PROCESS | 1679 | __start_cc_report 17:21:36 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2013 17:21:36 INFO - PROCESS | 1679 | __end_cc_report 17:21:36 INFO - PROCESS | 1679 | __startTimestamp1467850896824__endTimestamp 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | ------- Summary: start ------- 17:21:36 INFO - PROCESS | 1679 | Number of tests: 14 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:3.65 (3.3%) stddev-sans-first:0.71 17:21:36 INFO - PROCESS | 1679 | Values: 101.0 112.0 114.0 112.0 113.0 112.0 112.0 112.0 112.0 112.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:134.50 stddev:28.24 (21.0%) stddev-sans-first:29.54 17:21:36 INFO - PROCESS | 1679 | Values: 147.0 117.0 140.0 196.0 109.0 127.0 129.0 105.0 159.0 108.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.50 stddev:1.95 (1.1%) stddev-sans-first:1.96 17:21:36 INFO - PROCESS | 1679 | Values: 185.0 183.0 183.0 185.0 183.0 185.0 180.0 180.0 184.0 185.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:70.70 Median:61.50 stddev:18.45 (30.0%) stddev-sans-first:19.19 17:21:36 INFO - PROCESS | 1679 | Values: 81.0 74.0 59.0 107.0 55.0 56.0 60.0 63.0 96.0 56.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:88.00 stddev:12.71 (14.4%) stddev-sans-first:13.41 17:21:36 INFO - PROCESS | 1679 | Values: 85.0 91.0 91.0 71.0 71.0 68.0 97.0 70.0 68.0 99.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:87.00 stddev:1.93 (2.2%) stddev-sans-first:1.94 17:21:36 INFO - PROCESS | 1679 | Values: 87.0 83.0 87.0 83.0 87.0 84.0 87.0 83.0 87.0 84.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:92.00 stddev:15.10 (16.4%) stddev-sans-first:15.87 17:21:36 INFO - PROCESS | 1679 | Values: 92.0 92.0 93.0 92.0 92.0 93.0 92.0 101.0 140.0 91.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:90.30 Median:86.00 stddev:12.61 (14.7%) stddev-sans-first:13.27 17:21:36 INFO - PROCESS | 1679 | Values: 86.0 87.0 84.0 84.0 95.0 125.0 86.0 86.0 84.0 86.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:2.06 (3.7%) stddev-sans-first:2.17 17:21:36 INFO - PROCESS | 1679 | Values: 56.0 56.0 57.0 57.0 56.0 56.0 56.0 56.0 52.0 51.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:70.50 stddev:2.95 (4.2%) stddev-sans-first:2.86 17:21:36 INFO - PROCESS | 1679 | Values: 66.0 70.0 69.0 73.0 72.0 71.0 70.0 71.0 63.0 69.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.00 stddev:2.01 (2.4%) stddev-sans-first:1.72 17:21:36 INFO - PROCESS | 1679 | Values: 86.0 82.0 83.0 81.0 82.0 80.0 85.0 84.0 83.0 80.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:147.00 stddev:16.19 (11.0%) stddev-sans-first:17.13 17:21:36 INFO - PROCESS | 1679 | Values: 143.0 152.0 136.0 126.0 140.0 151.0 156.0 134.0 184.0 138.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.80 Median:162.50 stddev:7.21 (4.4%) stddev-sans-first:7.48 17:21:36 INFO - PROCESS | 1679 | Values: 164.0 166.0 162.0 162.0 163.0 163.0 160.0 162.0 155.0 141.0 17:21:36 INFO - PROCESS | 1679 | 17:21:36 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:67.00 stddev:6.45 (9.6%) stddev-sans-first:6.81 17:21:36 INFO - PROCESS | 1679 | Values: 67.0 66.0 67.0 67.0 66.0 67.0 65.0 68.0 69.0 87.0 17:21:36 INFO - PROCESS | 1679 | -------- Summary: end -------- 17:21:36 INFO - PROCESS | 1679 | 17:21:37 INFO - TEST-INFO | 1679: exit 0 17:21:37 INFO - TEST-OK | kraken | took 58908ms 17:21:37 INFO - SUITE-END | took 1174s 17:21:37 INFO - Completed test suite (00:19:35) 17:21:37 INFO - javascript benchmark 17:21:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3282.9507557386637, 3283.7044665980056, 3297.653746356632, 3311.503274508822, 3350.430426827439, 5500.2849629548155, 5516.365617953927, 5576.749046292916, 5577.8605534861645, 5637.4926256636945, 5607.102863541834, 5617.578681598886, 5673.914891276634, 5752.913706294409, 5763.711814409273, 5051.343325367716, 5067.467915868836, 5104.259882317062, 5118.9744051279695, 5222.791088356461, 5185.833187532813, 5253.133233016548, 5269.209618557209, 5269.420363759983, 5290.153575427932, 5056.823011194609, 5066.949330506684, 5101.923471147936, 5107.923381149286, 5150.768215430315, 5095.515925987025, 5125.769340379692, 5144.228365745131, 5223.0, 5225.869353266199, 5082.263071854561, 5103.872403189913, 5115.130427827278, 5185.818496352647, 5202.739863006835, 5483.725813709339, 5603.411641777596, 5684.857878553069, 5691.51622112121, 5709.400512946164, 6089.4091805406215, 6137.223770008885, 6145.324014358417, 6160.1991741073825, 6169.290531588835, 6167.4757645600175, 6189.257289125334, 6233.096201050867, 6247.500199983991, 6253.968730156365, 5603.93525230205, 5677.290338707661, 5720.11338242573, 5758.48173664368, 5778.942210577882, 4842.878928026827, 5957.910631340534, 6201.286852011986, 6259.96870015647, 6279.811605651837, 6143.784967526204, 6153.261608606996, 6156.445919867234, 6172.969135154295, 6178.41305076017, 6057.242844644419, 6082.513398928075, 6252.124702541648, 6253.812385628438, 6259.6870156492, 6102.908456373158, 6113.266408030976, 6119.143319935212, 6120.326764055951, 6133.049377346519, 4907.411110666671, 6103.42017508336, 6130.153553065287, 6137.171481849982, 6182.969085154546, 4839.467658557556, 4846.854594362175, 4858.489858564871, 4884.120858245479, 4886.193778026657, 4299.290617048152, 4333.71830830995, 4343.913121737548, 4349.391085248068, 4422.646188304992, 5525.668459892419, 5595.7481913313995, 5597.944020559742, 5599.776008959596, 5600.411956744483, 6118.938810611926, 6143.143438301211, 6150.077488376779, 6170.167027451236, 6171.413715697002, 5541.944580554224, 5580.5535557156145, 5582.051051321285, 5620.437956204347, 5674.092145256822, 5326.0, 5346.331708536433, 5348.685488329006, 5349.438308977501, 5353.652012619167, 5189.662671926313, 6220.502359811204, 6225.595336303126, 6253.968730156229, 6302.621842689453, 6178.443940045418, 6200.100985357189, 6215.689215539295, 6238.251409830849, 6240.313565507881, 6271.529635277281, 6281.434670879643, 6292.2134733158355, 6312.5265605078885, 6317.399847014526, 5057.519535644108, 5078.365204349457, 6005.609635373687, 6074.726637301421, 6109.847253818512, 5069.290299358019, 6184.350643182581, 6231.189945307262, 6237.282712488122, 6253.093301471352, 6041.728122234422, 6046.455818976313, 6050.636961782131, 6055.969720151371, 6107.725152368243, 5844.970775146267, 5991.490723288395, 6022.518198544019, 6030.276366836008, 6041.365656606169, 5895.056790913433, 5912.645241285537, 5921.733521991607, 5945.524358051432, 5967.343592204941, 5931.792387266425, 6066.636001839904, 6092.725827337869, 6093.102607848001, 6105.633661980295, 5705.657660540381, 5725.5992080555625, 5798.768049277982, 5820.214271073351, 5846.152307915413], "value": 5602.208964784925, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.99521004789943, 502.8466317773079, 520.4223312123543, 537.7472587883693, 544.7902557515358, 29528.852355738218, 41379.58620413795, 42747.57252427471, 42858.57141428585, 42862.785686071526, 36725.632743672555, 44369.778151109196, 44717.0, 44757.328640070424, 45009.87475313119, 8726.171013753716, 9702.126808587243, 9890.90109098907, 9892.901070989306, 9897.604095836157, 8741.650333986632, 11863.110266729987, 12134.393280335948, 12151.210171338835, 12375.504979800797, 12078.758424831498, 12100.394980250996, 12140.635780926586, 12147.088968327367, 12151.0, 8801.0, 8968.372213945026, 8992.910070899272, 11973.521059157623, 12056.457459414347, 8832.425892316978, 11688.064954803594, 11979.880201197964, 11984.64046078619, 12233.0, 42149.0, 42494.57505424968, 42546.78726606378, 42564.787176063925, 42595.57404425978, 3530.749436476448, 3616.710663146942, 4078.3066878630407, 4275.615194632467, 4285.142971405731, 31469.37061258762, 32330.83834580812, 32365.51451728226, 32645.836770816233, 32666.0, 3210.11721776511, 3338.165458635341, 3347.581552305962, 3360.176756694601, 3373.342198271338, 459907.0, 528595.0, 528848.0, 529286.4749733779, 529430.0, 60232.795344092876, 60253.69873150606, 60483.69758151181, 60576.39423605796, 60584.182474525835, 64664.353356466774, 64864.675676621315, 64900.0, 64950.0, 64960.350396496375, 16826.41107561254, 17095.829041709672, 17107.315707371567, 17144.914275428542, 17148.657026859393, 11903.642890713292, 12229.877701223051, 13048.021398394969, 13110.541131060367, 13298.401571929286, 13392.531261405804, 16253.67492650164, 16256.43102491407, 16269.349226031063, 16333.428330008393, 7121.750738724119, 7184.568925864465, 7195.208527062019, 7207.531510451804, 7262.891056634155, 871.0244526130774, 913.6208473483563, 976.3799987008301, 977.5063592885547, 1070.978580428387, 30600.54099188514, 31838.522422163685, 32217.677823221937, 32322.515162272586, 33804.0, 1122.186414849244, 1137.3005601555149, 1175.529788084773, 1183.3195912350434, 1222.0162768971063, 15327.693446131016, 15660.530184094496, 15708.371665133267, 16076.67846643084, 16084.43704470338, 679365.0, 679940.2005979877, 683081.0, 685459.0, 690235.0, 59132.70433647804, 59307.11039334413, 59454.70272648609, 59494.405055949755, 59573.40426595765, 1278.7442511497552, 1371.6022353517567, 1424.3163281624877, 1437.2526286331258, 1444.9205293708737, 30913.918012869442, 31225.687743123188, 31727.84136079305, 31937.882174123795, 32193.0, 51622.0, 51827.96344073022, 52321.7383913078, 52357.429277121744, 52384.214236785716, 532650.0, 533915.0, 534664.0, 534954.0, 537006.0, 23969.52060958806, 26068.8696556516, 26517.60223596609, 26950.0, 26966.865165674048, 47802.04395912132, 47845.76077119592, 48522.02955940932, 48830.75584622054, 48952.0, 38571.42142867803, 39978.60021399749, 40165.59834401619, 40188.79905600454, 40216.59783402128, 38554.61445385622, 39679.809605711875, 39697.603023969394, 40317.39523907202, 40498.1900361997, 38055.0, 38391.61608383992, 38926.80536597299, 39170.608293916695, 39190.02024949399, 32928.012159634294, 34903.75729149077, 35083.64916350804, 35337.469937951464, 35511.82244088763, 25406.1890716394, 25626.743732562434, 25698.229053128434, 26005.869970650027, 26546.734532654427], "value": 20712.491091504406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.87515307219, 381.4373798646996, 386.52071431425026, 389.1050583657588, 411.37060297744443, 15662.686746265068, 15729.842701572981, 21069.367918962456, 21099.68350474744, 21155.788442115576, 21323.57352852942, 21881.890590547064, 22147.335579932627, 22196.0, 22240.888795556, 5149.227615857614, 5773.422657734224, 5971.5222782177325, 5981.222441082653, 6021.036634138539, 5075.720835354054, 5898.587098903078, 5978.372270911535, 6002.459778619924, 6019.367966363535, 5955.136505206742, 6306.180196574439, 6651.2018557773135, 6662.7668031618905, 6723.798286051427, 5720.685362305072, 5771.567132465061, 6273.96863015682, 6599.10912026875, 6665.233498147702, 5861.413858614147, 5900.498457631106, 5902.586818922677, 5914.4381283777975, 5920.496757775594, 13520.053596248266, 18365.449036528924, 27082.864585677144, 27348.863255683795, 27381.04166354188, 2087.7912208779, 2178.6405243134864, 2235.9329220123423, 2534.721180670125, 2947.8673459694364, 10095.798084038352, 10459.633912813015, 10590.417527036087, 11295.887041129567, 16619.750703739453, 1575.8818088643397, 1818.4726429335476, 1838.6046999895086, 1858.3774435564103, 1885.962280754391, 110706.44646776715, 125564.0, 125929.0, 126060.36969815093, 127261.36369318095, 9639.807203855884, 10288.074073333286, 10956.780864382668, 11636.476358563885, 11762.764744705059, 14551.927240363942, 14571.562853114423, 14673.972821902364, 14702.632434189018, 14722.705545889237, 7246.094238220223, 8516.489010659381, 8677.349198810114, 8766.342524310703, 8829.205371516595, 7181.569105853666, 8764.824703505894, 8775.736727898046, 8893.377463577617, 8894.73315800527, 4534.070515544371, 4627.686156921539, 4637.211674015425, 4692.343071969927, 4729.030548737501, 1756.657451796913, 1876.277633111262, 1917.3157579388967, 1923.980760192408, 1926.1909997800958, 799.9600019998977, 825.8678611422143, 930.2000279759537, 1020.4795554267229, 1059.7297689089387, 22138.44653883666, 22278.443038923833, 22314.665280020814, 22329.55340893173, 22366.0, 777.8366543026028, 960.2318145483586, 972.9173020293355, 1001.519270750044, 1061.082163928212, 11149.442527873574, 11664.766704665859, 11680.357580333164, 11700.06399488039, 11710.063194944383, 534174.0, 536540.4984112398, 544786.0, 555544.2222788942, 560608.0, 130825.0, 131289.0, 134148.0, 134801.0, 136677.0, 993.5429702337005, 1000.229823036258, 1034.7930413917247, 1060.5121644043672, 1060.5545670818274, 19174.424767257005, 19505.902470487556, 19546.90226548915, 19573.923434211254, 19668.40994770159, 43043.56956430521, 45274.77362613166, 56513.71743141258, 56751.71624141853, 56994.7150264246, 82481.0, 83224.58387708022, 86365.13634863571, 86787.95257674526, 87251.5836153934, 7047.0, 7866.292033716993, 8148.348132149295, 8244.587770611566, 8244.83510329802, 11712.88287117118, 13534.59396218115, 13801.378937947615, 13866.0, 13874.0, 7391.9651248826285, 11741.002014828835, 11800.114991375509, 11883.643490695293, 11936.28382297065, 11155.776884462428, 11672.182947193955, 11740.765184696087, 11922.76154476923, 12138.02895768354, 11507.42462876836, 11704.122190835891, 11793.941030294794, 12132.939335303266, 12190.329531875832, 7604.505707129019, 7664.501807382502, 7678.6544605494, 7685.4620176587105, 7852.882206767017, 9890.307678462439, 11292.0, 11781.469833857325, 12000.919917207493, 12072.33602151855], "value": 9732.533847744124, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.8931330150237, 1568.6313716276672, 1749.9562510937217, 1774.5386199588104, 1881.5860510687653, 7512.774616761492, 8466.492010479367, 8765.737027889158, 8936.061713520085, 8954.55227238638, 7766.57283849388, 8017.358611311096, 8025.598720064003, 8108.0, 8724.127587241288, 2132.1791110422523, 2263.784940430665, 2348.54390278027, 2361.669366288721, 2362.5629258587132, 2341.508283260517, 2348.5655153796515, 2373.7982271506944, 2379.1910750344878, 2380.4643955110137, 2087.6451003329394, 2354.176038386568, 2369.478714682767, 2369.6327069304284, 2371.513839662865, 2271.068861766676, 2343.086196383412, 2344.589696803061, 2348.2955113466064, 2350.6131382484245, 2313.9305820825402, 2338.6024375855977, 2339.812814974798, 2341.098677009347, 2347.0377145370317, 8722.215000649972, 8837.116288371175, 8838.0, 8846.557672116433, 8862.86705699415, 5208.218767184952, 5350.438203988564, 5356.651817631841, 5361.517463428271, 5399.757010934478, 3276.2464633134273, 3346.6318704942437, 3593.7484376093685, 3640.7087433005563, 3682.760620559655, 1076.6608518316784, 1085.5657736905223, 1098.6099934523209, 1112.227002233448, 1113.192935122038, 11552.884471155181, 11622.825657615143, 11631.534738610446, 11639.767204655944, 11672.766544669059, 4491.079328737601, 4577.764003719044, 4693.366395536627, 4699.130660827758, 4778.713277203379, 9783.559739811726, 9795.167410770022, 9812.607495700235, 9815.901840981642, 9820.116189542976, 5308.654937429054, 5310.070737620901, 5318.069337865858, 5320.600954928318, 5324.8136315228785, 4642.628589712815, 4822.252550854623, 4882.584980276716, 4885.780139893714, 4887.633427492881, 3692.335379631692, 3758.830852611639, 3763.5295588051495, 3864.768113913174, 3867.9806600966817, 1174.2972689599944, 1178.2385333925836, 1181.704573856536, 1188.423614546944, 1189.29831399473, 900.909909009107, 911.3210658059788, 913.6847787513298, 928.8890427694593, 952.2382094324513, 5811.82564523065, 5853.2976042875125, 5933.317668468181, 6052.727627256784, 6105.633661980295, 434.59148400503426, 440.17247574560264, 449.74364612171405, 452.5451920819555, 462.72236658004925, 3846.9613204434645, 3913.158670885773, 4014.7791871446766, 4015.357542793139, 4092.7135100542678, 10217.386956782617, 10419.06228439444, 10436.128571357838, 10626.521806518725, 10651.84022239667, 10461.633842815465, 10586.841197382046, 10601.522931468102, 10613.628523001813, 10616.991385818335, 7425.443091768139, 7790.337821285254, 8186.099529051799, 8200.671973121127, 8208.83582328362, 5752.856178595569, 5754.395788442321, 5829.6793676346515, 5930.881382372242, 5943.286805583358, 9906.554205060933, 9908.306418550921, 9912.405255684682, 9986.201103911524, 10070.19438444908, 8875.201231888903, 9781.559829807818, 9999.600015999278, 10136.594536218468, 10138.239632027775, 6066.423689749643, 7098.893516597152, 7116.395106416013, 7191.676374563052, 7202.171750248579, 7527.962360188164, 8079.990001249844, 8277.54473503963, 8449.788755281168, 8455.450395724258, 7474.663640136098, 7549.547027178387, 7599.544027358376, 7673.079230492377, 7687.961560192163, 7170.677319520739, 7424.071991001125, 7425.51734137301, 7467.962660186664, 7564.167941526538, 6979.581225126509, 6989.0564773754895, 7062.223155452793, 7076.433885289268, 7092.6099064551945, 4987.850364489072, 4997.5002499749735, 5028.798848046037, 5033.094043072282, 5119.948800512097, 5061.468545802638, 5067.9239811403595, 5102.260172274925, 5112.105381558138, 5214.739263036909], "value": 4465.700639550237, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.23217536596917, 313.68004635272024, 318.32991552781385, 320.08934581116716, 323.77174092264926, 4631.490536041033, 5262.2108483115735, 5380.8923821523595, 5411.567074634029, 5476.205950137238, 4778.068276686047, 4902.0, 4984.352034235553, 5312.495312945264, 5386.084365657838, 3405.3359594879007, 3532.3288575170764, 3675.9231034465574, 3928.0, 4077.103037331782, 3416.829158542075, 4059.4316795648633, 4067.4102255172984, 4083.9591604084026, 4126.752394856303, 4050.7772072536, 4061.979690101545, 4087.1621317629815, 4094.97952510237, 4109.97945010276, 3184.936301273973, 3580.03339098442, 3648.6135268597764, 3980.7412518186225, 4035.2534781065597, 3538.2569660371355, 3541.1324225564645, 3553.4492153716214, 3564.590072141711, 3582.1940063485767, 4354.281543545311, 5182.533571978503, 5198.662086964373, 5229.607779416559, 5281.603879709037, 4142.689298302609, 4241.321388577812, 4367.432233809616, 4515.909681806378, 4707.905841883143, 4608.677392582521, 5075.868135383111, 5118.539331460186, 5589.832305030855, 5610.49505544503, 3604.387254166798, 3915.725899187058, 3918.7060970427333, 3927.057506198492, 3963.0488682716227, 5745.626534275259, 5849.473547380671, 5868.559858010666, 5933.0, 5966.522678185735, 3139.3878193752, 3921.215756848642, 3943.7633741975, 3960.1881614269005, 3979.5275747973437, 3414.0, 4030.9596904030586, 4035.7982100894837, 4049.9392509112386, 4052.209819085309, 4224.577542245751, 4572.1312950539295, 4632.791524381411, 4634.559716826896, 4681.695689780154, 4937.247277216608, 5584.190292407659, 5614.490943160525, 5675.006873797069, 5719.428057194247, 1342.5634571009123, 1388.0006395395299, 1389.1387339849357, 1391.7355702416507, 1392.8258967629047, 735.8417940142895, 747.1669088965873, 834.6452757578005, 838.6268110690684, 860.9711394883179, 4739.464386553177, 4978.780198851305, 5043.5965122790085, 5101.20931255656, 5224.190250511177, 4969.925451118236, 5411.728243862689, 5791.391903850119, 5805.970970145122, 5826.504747096459, 4829.9275510867365, 4973.5523802857915, 5037.395512538519, 5271.499207575274, 5290.65610735301, 3024.349764800578, 3060.908172754821, 3076.0005996102263, 3083.98458007713, 3109.720125188744, 5405.486478784509, 5765.250517432707, 5913.881722365529, 6033.78881739137, 6093.6039157454625, 4946.802127914843, 5156.690598564098, 5638.830835074954, 5868.16358941828, 5967.837046365434, 5999.89002034625, 6022.849428764316, 6036.215292012098, 6046.853367054809, 6063.241225242504, 5149.528226451399, 5487.478689524489, 5827.446392592697, 5847.590668653153, 5851.561132914963, 4694.577488026094, 4841.322214889848, 5076.400933705871, 5539.365887063245, 5803.912941305799, 5073.8731531712, 5820.243368362086, 6139.815805525842, 6179.443850053517, 6186.443220110212, 3285.0, 3627.6190999944624, 3878.8836334909997, 3896.220755848785, 3915.804209789556, 5636.267285252891, 5754.4533269339345, 5803.361630220588, 5809.564282678901, 5881.117832325185, 4976.5521103099445, 5671.638806686448, 5680.517156041783, 5732.598718089694, 5758.596898217084, 5348.812791552277, 5619.662820230799, 5636.633618814764, 5645.181448689999, 5747.9137812932, 4967.3542439482635, 5177.663451875616, 5511.58663100261, 5512.236084452937, 5516.310461192351, 4779.5220477951925, 5420.83737487876, 5623.3533143689, 5642.3229212493125, 5695.345035320991, 4643.419572553431, 5567.359753628222, 5588.245586845724, 5621.2411324472305, 5624.57815663835], "value": 4094.84040685607, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.3454458259854, 754.924507549245, 779.7193010516213, 804.342657896822, 823.3372135430976, 15493.302801373937, 16175.919120404382, 16186.595335116599, 16193.271302791372, 16194.190290485487, 12115.454804533794, 14649.0, 14796.852031479682, 14833.035852669596, 14867.256637168153, 746.5408773604248, 773.9961300193519, 918.6784625381114, 933.0249888866117, 941.2234906202376, 944.1974321826427, 944.5607792376544, 944.6363150187159, 945.9905400946005, 946.4037656276571, 976.0922342221731, 990.5245482168564, 991.7371896447447, 996.2826764729383, 996.5714742660689, 777.9066512018594, 813.3046245460106, 815.1807433529265, 986.7483791633124, 988.9615903301541, 602.5781952633174, 727.6507276507253, 754.5661244784271, 758.5676164586188, 759.1270039454616, 14830.406783728638, 14854.257287135708, 14855.851441485554, 14860.331285092198, 14884.553463396116, 13723.656908577366, 13915.721685566334, 13978.860211397858, 13983.79024314636, 13998.860011399858, 12372.81440778389, 12518.436670349856, 12669.93665031669, 12671.429785659668, 13080.738385232242, 4493.236149854532, 4542.685058481551, 4544.590986811169, 4698.459677137139, 4756.405449318835, 88634.55682721545, 92829.5358523203, 106105.0, 108506.0, 109043.0, 11398.202125851276, 11770.293782373084, 11781.705457363463, 11804.493355316657, 12160.391980401122, 23397.41506462352, 23428.531429371316, 23436.17973370924, 23443.0, 23449.0, 8136.633851476698, 8208.343332533383, 8338.04112527055, 8485.406021578554, 8559.828803423896, 4383.583559561836, 4436.046250056189, 4481.35020422037, 4500.797464114123, 4676.228422310349, 3009.5786589877, 3541.876034338837, 3636.581793093828, 3658.9817050914576, 3662.285854258447, 686.5503095472474, 687.3435868745336, 688.5627626457135, 694.1496666583496, 694.9166100067923, 584.2346526050802, 603.9818805435845, 608.8173547935602, 671.4694698951284, 706.9469789765789, 9167.22078623311, 9544.379615324982, 9599.376040557341, 9624.326297159272, 9715.319927605142, 665.8934570468701, 685.1230425055936, 687.1239170058216, 695.0200217693028, 703.4899697719216, 4543.4775000875325, 4560.156371071363, 4645.363364771294, 4671.5094915034115, 4717.858464246078, 92485.075149249, 92996.0, 93158.53420732988, 118348.0, 126971.74683645534, 126977.74644648068, 128485.35757321154, 128508.0, 129157.06264406041, 132551.33724331317, 111293.88706112998, 112044.99999999837, 113551.0, 113796.0, 115551.0, 8829.823403531766, 9114.361994660576, 9176.724698258797, 9188.229224541514, 9268.72193834186, 64093.0, 65392.0, 65569.67215163894, 66092.66953665201, 67116.32883671296, 93769.5311523438, 96688.51655741676, 97811.0, 98069.03861922864, 100308.49845750724, 10094.8485772712, 11936.224145430519, 11960.282383057045, 12152.392380381123, 12595.118341715992, 10816.783664326827, 12830.075488676774, 13122.0, 13153.802692959805, 13470.393832277767, 12028.819567706667, 12121.75756484883, 12456.252624842538, 12941.288229147487, 13021.674458138623, 12181.390930453264, 12410.503579856704, 12491.437885295001, 12727.0, 12738.745225095632, 11892.405379731152, 12140.514379424723, 12231.571894983634, 12355.93822030884, 12602.054845886412, 10246.692599222035, 10266.897331026594, 10384.584616615553, 10525.368477891045, 11172.21794474379, 10350.327228730108, 10410.427426491615, 10516.474176291307, 10542.156627469938, 10608.363498190134], "value": 7832.35476283018, "unit": "score"}], "name": "dromaeo_css", "value": 7381.549861632584, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 114.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 140.0, 196.0, 109.0, 127.0, 129.0, 105.0, 159.0, 108.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 183.0, 185.0, 183.0, 185.0, 180.0, 180.0, 184.0, 185.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 59.0, 107.0, 55.0, 56.0, 60.0, 63.0, 96.0, 56.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 91.0, 91.0, 71.0, 71.0, 68.0, 97.0, 70.0, 68.0, 99.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 83.0, 87.0, 84.0, 87.0, 83.0, 87.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 92.0, 92.0, 93.0, 92.0, 101.0, 140.0, 91.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 84.0, 84.0, 95.0, 125.0, 86.0, 86.0, 84.0, 86.0], "value": 90.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 57.0, 56.0, 56.0, 56.0, 56.0, 52.0, 51.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 69.0, 73.0, 72.0, 71.0, 70.0, 71.0, 63.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 83.0, 81.0, 82.0, 80.0, 85.0, 84.0, 83.0, 80.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 152.0, 136.0, 126.0, 140.0, 151.0, 156.0, 134.0, 184.0, 138.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 162.0, 162.0, 163.0, 163.0, 160.0, 162.0, 155.0, 141.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 66.0, 67.0, 65.0, 68.0, 69.0, 87.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1435.3, "alertThreshold": 2.0}]} 17:21:38 INFO - Return code: 0 17:21:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:21:38 INFO - # TBPL SUCCESS # 17:21:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:21:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:21:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:21:38 INFO - 2016-07-06 17:21:38.112 screenresolution[1683:10072] starting screenresolution argv=screenresolution get 17:21:38 INFO - 2016-07-06 17:21:38.114 screenresolution[1683:10072] Display 0: 1600x1200x32@60 17:21:38 INFO - 2016-07-06 17:21:38.120 screenresolution[1684:10075] starting screenresolution argv=screenresolution list 17:21:38 INFO - Available Modes on Display 0 17:21:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:21:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:21:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:21:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:21:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:21:38 INFO - Intel Iris: 17:21:38 INFO - Chipset Model: Intel Iris 17:21:38 INFO - Type: GPU 17:21:38 INFO - Bus: Built-In 17:21:38 INFO - VRAM (Dynamic, Max): 1536 MB 17:21:38 INFO - Vendor: Intel (0x8086) 17:21:38 INFO - Device ID: 0x0a2e 17:21:38 INFO - Revision ID: 0x0009 17:21:38 INFO - Displays: 17:21:38 INFO - Display: 17:21:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:21:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:21:38 INFO - Display Serial Number: 1600x1200 60 17:21:38 INFO - Main Display: Yes 17:21:38 INFO - Mirror: Off 17:21:38 INFO - Online: Yes 17:21:38 INFO - Rotation: Supported 17:21:38 INFO - Return code: 0 17:21:38 INFO - Running post-action listener: _resource_record_post_action 17:21:38 INFO - ##### 17:21:38 INFO - ##### Finished run-tests step (success) 17:21:38 INFO - ##### 17:21:38 INFO - Running post-run listener: _resource_record_post_run 17:21:38 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 36910592; Write bytes: 706479616; Read time: 239; Write time: 2011 17:21:38 INFO - TinderboxPrint: CPU usage
53.4% 17:21:38 INFO - TinderboxPrint: I/O read bytes / time
36,910,592 / 239 17:21:38 INFO - TinderboxPrint: I/O write bytes / time
706,479,616 / 2,011 17:21:38 INFO - TinderboxPrint: CPU idle
2,257.2 (46.6%) 17:21:38 INFO - TinderboxPrint: CPU system
967.2 (20.0%) 17:21:38 INFO - TinderboxPrint: CPU user
1,621.2 (33.5%) 17:21:38 INFO - TinderboxPrint: Swap in / out
443,371,520 / 0 17:21:38 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398670336; Write bytes: 394204672; Read time: 30511; Write time: 862 17:21:38 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 34911744; Write bytes: 294440960; Read time: 221; Write time: 1107 17:21:38 INFO - Running post-run listener: _upload_blobber_files 17:21:38 INFO - Blob upload gear active. 17:21:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:21:38 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:21:38 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:21:38 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:21:39 INFO - (blobuploader) - INFO - Open directory for files ... 17:21:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:21:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:21:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:21:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:21:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:21:39 INFO - (blobuploader) - INFO - Done attempting. 17:21:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:21:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:21:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:21:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:21:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:21:41 INFO - (blobuploader) - INFO - Done attempting. 17:21:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:21:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:21:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:21:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:21:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:21:43 INFO - (blobuploader) - INFO - Done attempting. 17:21:43 INFO - (blobuploader) - INFO - Iteration through files over. 17:21:43 INFO - Return code: 0 17:21:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:21:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:21:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/674dff0899b4401ece2b7fc151541c0103bba5d1669dd3914c4c5f0b71fd0c5bd71e023ff699f867a9ec3c8e66753b12e880019052d2f25a4375eed13ed36e51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b7a034cba27a4953456cd19bc8c634525330465615ee88af135700b85da3d00170d494e6f4d9017d331a43c3742263b00a282804e4e10bf693f53ccc8e3d48f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2f1536c1e7111a5b8f0c3c6c4e6171744b8e40c026764885af47b06b9977418ea3806a2b25570513205c71c2e8048c143db51e357e7327543a7931a60e05a5b"} 17:21:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:21:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:21:43 INFO - Contents: 17:21:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/674dff0899b4401ece2b7fc151541c0103bba5d1669dd3914c4c5f0b71fd0c5bd71e023ff699f867a9ec3c8e66753b12e880019052d2f25a4375eed13ed36e51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b7a034cba27a4953456cd19bc8c634525330465615ee88af135700b85da3d00170d494e6f4d9017d331a43c3742263b00a282804e4e10bf693f53ccc8e3d48f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2f1536c1e7111a5b8f0c3c6c4e6171744b8e40c026764885af47b06b9977418ea3806a2b25570513205c71c2e8048c143db51e357e7327543a7931a60e05a5b"} 17:21:43 INFO - Running post-run listener: copy_logs_to_upload_dir 17:21:43 INFO - Copying logs to upload dir... 17:21:43 INFO - mkdir: /builds/slave/test/build/upload/logs 17:21:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.826154 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-07-06 17:21:43.869895) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 17:21:43.874065) ========= 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.TPHSuwlKga/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.ObQpK7lkPA/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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/Fx-Team/sha512/674dff0899b4401ece2b7fc151541c0103bba5d1669dd3914c4c5f0b71fd0c5bd71e023ff699f867a9ec3c8e66753b12e880019052d2f25a4375eed13ed36e51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b7a034cba27a4953456cd19bc8c634525330465615ee88af135700b85da3d00170d494e6f4d9017d331a43c3742263b00a282804e4e10bf693f53ccc8e3d48f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2f1536c1e7111a5b8f0c3c6c4e6171744b8e40c026764885af47b06b9977418ea3806a2b25570513205c71c2e8048c143db51e357e7327543a7931a60e05a5b"} build_url:https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/OmV9Q09vRRO8gLP9trccQQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/674dff0899b4401ece2b7fc151541c0103bba5d1669dd3914c4c5f0b71fd0c5bd71e023ff699f867a9ec3c8e66753b12e880019052d2f25a4375eed13ed36e51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b7a034cba27a4953456cd19bc8c634525330465615ee88af135700b85da3d00170d494e6f4d9017d331a43c3742263b00a282804e4e10bf693f53ccc8e3d48f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d2f1536c1e7111a5b8f0c3c6c4e6171744b8e40c026764885af47b06b9977418ea3806a2b25570513205c71c2e8048c143db51e357e7327543a7931a60e05a5b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 17:21:43.910968) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 17:21:43.911304) ========= 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.TPHSuwlKga/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.ObQpK7lkPA/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.005143 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 17:21:43.959569) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 17:21:43.962953) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 17:21:43.963313) ========= ========= Total master_lag: 0.36 =========