builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0169 starttime: 1461265844.06 results: success (0) buildid: 20160421095844 builduid: d795cfbb64f6461bae7f11fa73d69ab8 revision: 5ad0f8d2d7dffcf57ff4d935f5dfb4781683091e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 12:10:44.057263) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 12:10:44.057950) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 18 secs) (at 2016-04-21 12:10:44.058420) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 18 secs) (at 2016-04-21 12:11:02.899365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-21 12:11:02.899735) ========= 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.kI1MNlWKli/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.M4vHhB7hSp/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-04-21 12:11:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.3M=0.001s 2016-04-21 12:11:03 (15.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133442 ========= master_lag: 6.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-21 12:11:09.242429) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-21 12:11:09.242755) ========= 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.kI1MNlWKli/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.M4vHhB7hSp/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.027695 ========= master_lag: 3.72 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-21 12:11:12.985673) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 14 secs) (at 2016-04-21 12:11:12.986043) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5ad0f8d2d7dffcf57ff4d935f5dfb4781683091e --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 5ad0f8d2d7dffcf57ff4d935f5dfb4781683091e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kI1MNlWKli/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.M4vHhB7hSp/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-04-21 12:11:13,092 truncating revision to first 12 chars 2016-04-21 12:11:13,092 Setting DEBUG logging. 2016-04-21 12:11:13,092 attempt 1/10 2016-04-21 12:11:13,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5ad0f8d2d7df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 12:11:13,789 unpacking tar archive at: fx-team-5ad0f8d2d7df/testing/mozharness/ program finished with exit code 0 elapsedTime=0.942961 ========= master_lag: 13.83 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 14 secs) (at 2016-04-21 12:11:27.762138) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 12:11:27.762465) ========= script_repo_revision: 5ad0f8d2d7dffcf57ff4d935f5dfb4781683091e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 12:11:27.762886) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 10 secs) (at 2016-04-21 12:11:27.763145) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 10 secs) (at 2016-04-21 12:11:38.241569) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-21 12:11:38.241967) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.kI1MNlWKli/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.M4vHhB7hSp/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:11:38 INFO - MultiFileLogger online at 20160421 12:11:38 in /builds/slave/test 12:11:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:11:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:11:38 INFO - {'append_to_log': False, 12:11:38 INFO - 'base_work_dir': '/builds/slave/test', 12:11:38 INFO - 'blob_upload_branch': 'Fx-Team', 12:11:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:11:38 INFO - 'branch': 'Fx-Team', 12:11:38 INFO - 'buildbot_json_path': 'buildprops.json', 12:11:38 INFO - 'config_files': ('talos/mac_config.py',), 12:11:38 INFO - 'default_actions': ('clobber', 12:11:38 INFO - 'read-buildbot-config', 12:11:38 INFO - 'download-and-extract', 12:11:38 INFO - 'populate-webroot', 12:11:38 INFO - 'create-virtualenv', 12:11:38 INFO - 'install', 12:11:38 INFO - 'run-tests'), 12:11:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:11:38 INFO - 'download_minidump_stackwalk': True, 12:11:38 INFO - 'download_symbols': 'ondemand', 12:11:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:11:38 INFO - 'tooltool.py': '/tools/tooltool.py', 12:11:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:11:38 INFO - '/tools/misc-python/virtualenv.py')}, 12:11:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:11:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:11:38 INFO - 'installer_path': 'installer.exe', 12:11:38 INFO - 'log_level': 'info', 12:11:38 INFO - 'log_name': 'talos', 12:11:38 INFO - 'log_to_console': True, 12:11:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:11:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:11:38 INFO - 'opt_config_files': (), 12:11:38 INFO - 'pip_index': False, 12:11:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:11:38 INFO - 'cmd': ('bash', 12:11:38 INFO - '-c', 12:11:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:11:38 INFO - 'enabled': True, 12:11:38 INFO - 'halt_on_failure': False, 12:11:38 INFO - 'name': 'check_screen_resolution'},), 12:11:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:11:38 INFO - 'cmd': ('bash', 12:11:38 INFO - '-c', 12:11:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:11:38 INFO - 'enabled': True, 12:11:38 INFO - 'halt_on_failure': False, 12:11:38 INFO - 'name': 'check_screen_resolution'},), 12:11:38 INFO - 'run_cmd_checks_enabled': True, 12:11:38 INFO - 'sps_profile': False, 12:11:38 INFO - 'sps_profile_interval': 0, 12:11:38 INFO - 'suite': 'dromaeojs-e10s', 12:11:38 INFO - 'system_bits': '32', 12:11:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:11:38 INFO - 'title': 't-yosemite-r7-0169', 12:11:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:11:38 INFO - 'use_talos_json': True, 12:11:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:11:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:11:38 INFO - 'work_dir': 'build'} 12:11:38 INFO - ##### 12:11:38 INFO - ##### Running clobber step. 12:11:38 INFO - ##### 12:11:38 INFO - Running pre-action listener: _resource_record_pre_action 12:11:38 INFO - Running main action method: clobber 12:11:38 INFO - rmtree: /builds/slave/test/build 12:11:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:11:40 INFO - Running post-action listener: _resource_record_post_action 12:11:40 INFO - ##### 12:11:40 INFO - ##### Running read-buildbot-config step. 12:11:40 INFO - ##### 12:11:40 INFO - Running pre-action listener: _resource_record_pre_action 12:11:40 INFO - Running main action method: read_buildbot_config 12:11:40 INFO - Using buildbot properties: 12:11:40 INFO - { 12:11:40 INFO - "project": "", 12:11:40 INFO - "product": "firefox", 12:11:40 INFO - "script_repo_revision": "production", 12:11:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 12:11:40 INFO - "repository": "", 12:11:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 12:11:40 INFO - "buildid": "20160421095844", 12:11:40 INFO - "pgo_build": "False", 12:11:40 INFO - "basedir": "/builds/slave/test", 12:11:40 INFO - "buildnumber": 617, 12:11:40 INFO - "slavename": "t-yosemite-r7-0169", 12:11:40 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:11:40 INFO - "platform": "yosemite_r7", 12:11:40 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 12:11:40 INFO - "branch": "fx-team", 12:11:40 INFO - "repo_path": "integration/fx-team", 12:11:40 INFO - "revision": "5ad0f8d2d7dffcf57ff4d935f5dfb4781683091e", 12:11:40 INFO - "stage_platform": "macosx64", 12:11:40 INFO - "builduid": "d795cfbb64f6461bae7f11fa73d69ab8", 12:11:40 INFO - "slavebuilddir": "test" 12:11:40 INFO - } 12:11:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 12:11:40 INFO - Running post-action listener: _resource_record_post_action 12:11:40 INFO - ##### 12:11:40 INFO - ##### Running download-and-extract step. 12:11:40 INFO - ##### 12:11:40 INFO - Running pre-action listener: _resource_record_pre_action 12:11:40 INFO - Running main action method: download_and_extract 12:11:40 INFO - mkdir: /builds/slave/test/build/tests 12:11:40 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')]} 12:11:40 INFO - https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:11:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:11:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:11:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:11:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 12:11:41 INFO - Downloaded 1199 bytes. 12:11:41 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:11:41 INFO - Using the following test package requirements: 12:11:41 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:11:41 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:11:41 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 12:11:41 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:11:41 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 12:11:41 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:11:41 INFO - u'jsshell-mac.zip'], 12:11:41 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:11:41 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 12:11:41 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:11:41 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:11:41 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 12:11:41 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:11:41 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 12:11:41 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:11:41 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 12:11:41 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:11:41 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 12:11:41 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:11:41 INFO - https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:11:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:11:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:11:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:11:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:11:43 INFO - Downloaded 18099865 bytes. 12:11:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:11:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:11:46 INFO - Return code: 0 12:11:46 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:11:46 INFO - https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:11:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:11:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:11:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:11:47 INFO - Downloaded 18099865 bytes. 12:11:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:11:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:11:50 INFO - Return code: 0 12:11: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')]} 12:11:50 INFO - https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:11:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:11:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:11:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:11:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:11:52 INFO - Downloaded 10845952 bytes. 12:11:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:11:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:11:52 INFO - Return code: 0 12:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:11:52 INFO - https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:11:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:11:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:11:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 12:11:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 12:11:55 INFO - Downloaded 109954320 bytes. 12:11:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:11:55 INFO - mkdir: /builds/slave/test/properties 12:11:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:11:55 INFO - Writing to file /builds/slave/test/properties/build_url 12:11:55 INFO - Contents: 12:11:55 INFO - build_url:https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:11:56 INFO - Running post-action listener: _resource_record_post_action 12:11:56 INFO - Running post-action listener: set_extra_try_arguments 12:11:56 INFO - ##### 12:11:56 INFO - ##### Running populate-webroot step. 12:11:56 INFO - ##### 12:11:56 INFO - Running pre-action listener: _resource_record_pre_action 12:11:56 INFO - Running main action method: populate_webroot 12:11:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:11:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:11:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:11:56 INFO - '--tppagecycles', 12:11:56 INFO - '7'], 12:11:56 INFO - 'tests': ['tsvgm']}}, 12:11:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:11:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:11:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:11:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:11:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:56 INFO - 'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:11:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:56 INFO - 'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['damp', 'tps']}, 12:11:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:56 INFO - 'tests': ['damp', 'tps']}, 12:11:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['dromaeo_dom']}, 12:11:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:11:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['a11yr', 12:11:56 INFO - 'ts_paint', 12:11:56 INFO - 'tpaint', 12:11:56 INFO - 'sessionrestore', 12:11:56 INFO - 'sessionrestore_no_auto_restore', 12:11:56 INFO - 'tabpaint']}, 12:11:56 INFO - 'other-e10s': {'tests': ['a11yr', 12:11:56 INFO - 'ts_paint', 12:11:56 INFO - 'tpaint', 12:11:56 INFO - 'sessionrestore', 12:11:56 INFO - 'sessionrestore_no_auto_restore', 12:11:56 INFO - 'tabpaint']}, 12:11:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:11:56 INFO - 'ts_paint', 12:11:56 INFO - 'tpaint', 12:11:56 INFO - 'sessionrestore', 12:11:56 INFO - 'sessionrestore_no_auto_restore', 12:11:56 INFO - 'tabpaint']}, 12:11:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:11:56 INFO - 'ts_paint', 12:11:56 INFO - 'tpaint', 12:11:56 INFO - 'sessionrestore', 12:11:56 INFO - 'sessionrestore_no_auto_restore', 12:11:56 INFO - 'tabpaint']}, 12:11:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['a11yr', 12:11:56 INFO - 'ts_paint', 12:11:56 INFO - 'tpaint', 12:11:56 INFO - 'sessionrestore', 12:11:56 INFO - 'sessionrestore_no_auto_restore', 12:11:56 INFO - 'tabpaint']}, 12:11:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['a11yr', 12:11:56 INFO - 'ts_paint', 12:11:56 INFO - 'tpaint', 12:11:56 INFO - 'sessionrestore', 12:11:56 INFO - 'sessionrestore_no_auto_restore', 12:11:56 INFO - 'tabpaint']}, 12:11:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['tsvgx', 12:11:56 INFO - 'tsvgr_opacity', 12:11:56 INFO - 'tart', 12:11:56 INFO - 'tscrollx', 12:11:56 INFO - 'cart']}, 12:11:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:11:56 INFO - 'tsvgr_opacity', 12:11:56 INFO - 'tart', 12:11:56 INFO - 'tscrollx', 12:11:56 INFO - 'cart']}, 12:11:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:56 INFO - 'talos_options': ['--disable-e10s'], 12:11:56 INFO - 'tests': ['tp5o']}, 12:11:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:56 INFO - 'tests': ['tp5o']}, 12:11:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:56 INFO - 'talos_options': ['--disable-e10s', 12:11:56 INFO - '--xperf_path', 12:11:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:56 INFO - 'tests': ['tp5n']}, 12:11:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:11:56 INFO - 'talos_options': ['--xperf_path', 12:11:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:11:56 INFO - 'tests': ['tp5n']}}, 12:11:56 INFO - 'talos.zip': {'path': '', 12:11:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:11:56 INFO - Running post-action listener: _resource_record_post_action 12:11:56 INFO - ##### 12:11:56 INFO - ##### Running create-virtualenv step. 12:11:56 INFO - ##### 12:11:56 INFO - Running pre-action listener: _resource_record_pre_action 12:11:56 INFO - Running main action method: create_virtualenv 12:11:56 INFO - Creating virtualenv /builds/slave/test/build/venv 12:11: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 12:11:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:11:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:11:57 INFO - Using real prefix '/tools/python27' 12:11:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:11:57 INFO - Installing distribute.............................................................................................................................................................................................done. 12:12:00 INFO - Installing pip.................done. 12:12:00 INFO - Return code: 0 12:12:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:12: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')]} 12:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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')]} 12:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12: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', 'pip>=1.5'] in /builds/slave/test/build 12:12: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 pip>=1.5 12:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:00 INFO - 'HOME': '/Users/cltbld', 12:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:00 INFO - 'LOGNAME': 'cltbld', 12:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:00 INFO - 'MOZ_NO_REMOTE': '1', 12:12:00 INFO - 'NO_EM_RESTART': '1', 12:12:00 INFO - 'PAGER': '/bin/cat', 12:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:00 INFO - 'PWD': '/builds/slave/test', 12:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:00 INFO - 'SHELL': '/bin/bash', 12:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:00 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:00 INFO - 'USER': 'cltbld', 12:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:00 INFO - 'XPC_FLAGS': '0x0', 12:12:00 INFO - 'XPC_SERVICE_NAME': '0', 12:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:01 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 12:12:01 INFO - Cleaning up... 12:12:01 INFO - Return code: 0 12:12:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:12:01 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')]} 12:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:01 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')]} 12:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:01 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12:01 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>=0.7.1'] in /builds/slave/test/build 12:12:01 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>=0.7.1 12:12:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:01 INFO - 'HOME': '/Users/cltbld', 12:12:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:01 INFO - 'LOGNAME': 'cltbld', 12:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:01 INFO - 'MOZ_NO_REMOTE': '1', 12:12:01 INFO - 'NO_EM_RESTART': '1', 12:12:01 INFO - 'PAGER': '/bin/cat', 12:12:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:01 INFO - 'PWD': '/builds/slave/test', 12:12:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:01 INFO - 'SHELL': '/bin/bash', 12:12:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:01 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:01 INFO - 'USER': 'cltbld', 12:12:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:01 INFO - 'XPC_FLAGS': '0x0', 12:12:01 INFO - 'XPC_SERVICE_NAME': '0', 12:12:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:01 INFO - Downloading/unpacking psutil>=0.7.1 12:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:12:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:12:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:12:04 INFO - Installing collected packages: psutil 12:12:04 INFO - Running setup.py install for psutil 12:12:04 INFO - building 'psutil._psutil_osx' extension 12:12:04 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 12:12:05 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 12:12:05 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 12:12:05 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 12:12:05 INFO - building 'psutil._psutil_posix' extension 12:12:05 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 12:12:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:12:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:12:05 INFO - ^ 12:12:05 INFO - 1 warning generated. 12:12:05 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 12:12:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:12:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:12:05 INFO - Successfully installed psutil 12:12:05 INFO - Cleaning up... 12:12:05 INFO - Return code: 0 12:12:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:05 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12:05 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.0.0'] in /builds/slave/test/build 12:12:05 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.0.0 12:12:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:05 INFO - 'HOME': '/Users/cltbld', 12:12:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:05 INFO - 'LOGNAME': 'cltbld', 12:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:05 INFO - 'MOZ_NO_REMOTE': '1', 12:12:05 INFO - 'NO_EM_RESTART': '1', 12:12:05 INFO - 'PAGER': '/bin/cat', 12:12:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:05 INFO - 'PWD': '/builds/slave/test', 12:12:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:05 INFO - 'SHELL': '/bin/bash', 12:12:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:05 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:05 INFO - 'USER': 'cltbld', 12:12:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:05 INFO - 'XPC_FLAGS': '0x0', 12:12:05 INFO - 'XPC_SERVICE_NAME': '0', 12:12:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:12:05 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 12:12:05 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 12:12:05 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 12:12:05 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 12:12:05 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 12:12:05 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 12:12:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:12:08 INFO - Installing collected packages: mozsystemmonitor 12:12:08 INFO - Running setup.py install for mozsystemmonitor 12:12:08 INFO - Successfully installed mozsystemmonitor 12:12:08 INFO - Cleaning up... 12:12:08 INFO - Return code: 0 12:12:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:08 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 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12:08 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 12:12:08 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 12:12:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:08 INFO - 'HOME': '/Users/cltbld', 12:12:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:08 INFO - 'LOGNAME': 'cltbld', 12:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:08 INFO - 'MOZ_NO_REMOTE': '1', 12:12:08 INFO - 'NO_EM_RESTART': '1', 12:12:08 INFO - 'PAGER': '/bin/cat', 12:12:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:08 INFO - 'PWD': '/builds/slave/test', 12:12:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:08 INFO - 'SHELL': '/bin/bash', 12:12:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:08 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:08 INFO - 'USER': 'cltbld', 12:12:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:08 INFO - 'XPC_FLAGS': '0x0', 12:12:08 INFO - 'XPC_SERVICE_NAME': '0', 12:12:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:08 INFO - Downloading/unpacking blobuploader==1.2.4 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:08 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 12:12:11 INFO - Downloading blobuploader-1.2.4.tar.gz 12:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:12:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:12:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12:12 INFO - Downloading docopt-0.6.1.tar.gz 12:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:12:12 INFO - Installing collected packages: blobuploader, requests, docopt 12:12:12 INFO - Running setup.py install for blobuploader 12:12:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:12:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:12:12 INFO - Running setup.py install for requests 12:12:13 INFO - Running setup.py install for docopt 12:12:13 INFO - Successfully installed blobuploader requests docopt 12:12:13 INFO - Cleaning up... 12:12:13 INFO - Return code: 0 12:12:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:13 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 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12:13 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 12:12:13 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 12:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:13 INFO - 'HOME': '/Users/cltbld', 12:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:13 INFO - 'LOGNAME': 'cltbld', 12:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:13 INFO - 'MOZ_NO_REMOTE': '1', 12:12:13 INFO - 'NO_EM_RESTART': '1', 12:12:13 INFO - 'PAGER': '/bin/cat', 12:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:13 INFO - 'PWD': '/builds/slave/test', 12:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:13 INFO - 'SHELL': '/bin/bash', 12:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:13 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:13 INFO - 'USER': 'cltbld', 12:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:13 INFO - 'XPC_FLAGS': '0x0', 12:12:13 INFO - 'XPC_SERVICE_NAME': '0', 12:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:12:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-7zhWLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:12:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-USxqPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:12:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-mKYhfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:12:13 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Kp9cZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-7Ad9cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-cK6m1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ognyzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-BAG9TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-mlg0ka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-vPZxjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-TAMEZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-VQyUYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:12:14 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-DFF3tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:12:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-h3kU6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-o6TRyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:12:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-GMslUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:12:15 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-UecnDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:12:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:12:15 INFO - Running setup.py install for manifestparser 12:12:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:12:15 INFO - Running setup.py install for mozcrash 12:12:15 INFO - Running setup.py install for mozdebug 12:12:15 INFO - Running setup.py install for mozdevice 12:12:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Running setup.py install for mozfile 12:12:16 INFO - Running setup.py install for mozhttpd 12:12:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Running setup.py install for mozinfo 12:12:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Running setup.py install for mozInstall 12:12:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Running setup.py install for mozleak 12:12:16 INFO - Running setup.py install for mozlog 12:12:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:12:16 INFO - Running setup.py install for moznetwork 12:12:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:12:17 INFO - Running setup.py install for mozprocess 12:12:17 INFO - Running setup.py install for mozprofile 12:12:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:12:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:12:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:12:17 INFO - Running setup.py install for mozrunner 12:12:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:12:17 INFO - Running setup.py install for mozscreenshot 12:12:17 INFO - Running setup.py install for moztest 12:12:17 INFO - Running setup.py install for mozversion 12:12:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:12:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:12:17 INFO - Cleaning up... 12:12:17 INFO - Return code: 0 12:12:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12:17 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')]} 12:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:17 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')]} 12:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:17 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 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12:17 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 12:12:17 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 12:12:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:17 INFO - 'HOME': '/Users/cltbld', 12:12:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:17 INFO - 'LOGNAME': 'cltbld', 12:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:17 INFO - 'MOZ_NO_REMOTE': '1', 12:12:17 INFO - 'NO_EM_RESTART': '1', 12:12:17 INFO - 'PAGER': '/bin/cat', 12:12:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:17 INFO - 'PWD': '/builds/slave/test', 12:12:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:17 INFO - 'SHELL': '/bin/bash', 12:12:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:17 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:17 INFO - 'USER': 'cltbld', 12:12:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:17 INFO - 'XPC_FLAGS': '0x0', 12:12:17 INFO - 'XPC_SERVICE_NAME': '0', 12:12:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:12:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-MAKMjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:12:18 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)) 12:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:12:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-_QltAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:12:18 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)) 12:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:12:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-VIQ58v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:12:18 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)) 12:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:12:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-sJRiZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:12:18 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)) 12:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:12:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-DGVIgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:12:18 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)) 12:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:12:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-ePhpxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:12:18 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)) 12:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:12:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-mZJZ9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:12:18 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)) 12:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:12:18 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-kYqjlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:12:19 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-Q2Ly0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:12:19 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-lowmj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-7xfrr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:12:19 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-wOqiIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:12:19 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-MbkPdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:12:19 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-e8BBpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:12:19 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-W0wNil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:12:19 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-o7mMfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:12:19 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)) 12:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:12:19 INFO - Running setup.py (path:/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/pip-xnuiDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:12:20 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)) 12:12:20 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)) 12:12:20 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)) 12:12:20 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)) 12:12:20 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)) 12:12:20 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)) 12:12:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:12:20 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 12:12:20 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 12:12:20 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 12:12:20 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 12:12:20 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 12:12:20 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 12:12:22 INFO - Downloading blessings-1.6.tar.gz 12:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:12:22 INFO - Installing collected packages: blessings 12:12:22 INFO - Running setup.py install for blessings 12:12:22 INFO - Successfully installed blessings 12:12:22 INFO - Cleaning up... 12:12:22 INFO - Return code: 0 12:12:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:12:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:12:23 INFO - Reading from file tmpfile_stdout 12:12:23 INFO - Current package versions: 12:12:23 INFO - blessings == 1.6 12:12:23 INFO - blobuploader == 1.2.4 12:12:23 INFO - docopt == 0.6.1 12:12:23 INFO - manifestparser == 1.1 12:12:23 INFO - mozInstall == 1.12 12:12:23 INFO - mozcrash == 0.17 12:12:23 INFO - mozdebug == 0.1 12:12:23 INFO - mozdevice == 0.48 12:12:23 INFO - mozfile == 1.2 12:12:23 INFO - mozhttpd == 0.7 12:12:23 INFO - mozinfo == 0.9 12:12:23 INFO - mozleak == 0.1 12:12:23 INFO - mozlog == 3.1 12:12:23 INFO - moznetwork == 0.27 12:12:23 INFO - mozprocess == 0.22 12:12:23 INFO - mozprofile == 0.28 12:12:23 INFO - mozrunner == 6.11 12:12:23 INFO - mozscreenshot == 0.1 12:12:23 INFO - mozsystemmonitor == 0.0 12:12:23 INFO - moztest == 0.7 12:12:23 INFO - mozversion == 1.4 12:12:23 INFO - psutil == 3.1.1 12:12:23 INFO - requests == 1.2.3 12:12:23 INFO - wsgiref == 0.1.2 12:12:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:23 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 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12:23 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 12:12:23 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 12:12:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:23 INFO - 'HOME': '/Users/cltbld', 12:12:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:23 INFO - 'LOGNAME': 'cltbld', 12:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:23 INFO - 'MOZ_NO_REMOTE': '1', 12:12:23 INFO - 'NO_EM_RESTART': '1', 12:12:23 INFO - 'PAGER': '/bin/cat', 12:12:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:23 INFO - 'PWD': '/builds/slave/test', 12:12:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:23 INFO - 'SHELL': '/bin/bash', 12:12:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:23 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:23 INFO - 'USER': 'cltbld', 12:12:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:23 INFO - 'XPC_FLAGS': '0x0', 12:12:23 INFO - 'XPC_SERVICE_NAME': '0', 12:12:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 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)) 12:12:23 INFO - Cleaning up... 12:12:23 INFO - Return code: 0 12:12:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12:23 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 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12:23 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 12:12:23 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 12:12:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:23 INFO - 'HOME': '/Users/cltbld', 12:12:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:23 INFO - 'LOGNAME': 'cltbld', 12:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:23 INFO - 'MOZ_NO_REMOTE': '1', 12:12:23 INFO - 'NO_EM_RESTART': '1', 12:12:23 INFO - 'PAGER': '/bin/cat', 12:12:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:23 INFO - 'PWD': '/builds/slave/test', 12:12:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:23 INFO - 'SHELL': '/bin/bash', 12:12:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:23 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:23 INFO - 'USER': 'cltbld', 12:12:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:23 INFO - 'XPC_FLAGS': '0x0', 12:12:23 INFO - 'XPC_SERVICE_NAME': '0', 12:12:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:23 INFO - Downloading/unpacking jsonschema 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12: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 12:12:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:12:25 INFO - Downloading/unpacking functools32 (from jsonschema) 12:12:25 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 12:12:25 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 12:12:25 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 12:12:25 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 12:12:25 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 12:12:25 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 12:12:26 INFO - Downloading functools32-3.2.3-2.tar.gz 12:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:12:26 INFO - warning: no files found matching '*.txt' 12:12:26 INFO - no previously-included directories found matching 'build' 12:12:26 INFO - no previously-included directories found matching 'dist' 12:12:26 INFO - no previously-included directories found matching '.git*' 12:12:26 INFO - Installing collected packages: jsonschema, functools32 12:12:26 INFO - Running setup.py install for functools32 12:12:26 INFO - warning: no files found matching '*.txt' 12:12:26 INFO - no previously-included directories found matching 'build' 12:12:26 INFO - no previously-included directories found matching 'dist' 12:12:26 INFO - no previously-included directories found matching '.git*' 12:12:26 INFO - Successfully installed jsonschema functools32 12:12:26 INFO - Cleaning up... 12:12:26 INFO - Return code: 0 12:12:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:12: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')]} 12:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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')]} 12:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:12: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c08da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c09d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb1bc5c040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, '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.kI1MNlWKli/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.M4vHhB7hSp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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 12:12: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', 'flake8'] in /builds/slave/test/build 12:12: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 flake8 12:12:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:12:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:12:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:12:26 INFO - 'HOME': '/Users/cltbld', 12:12:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:12:26 INFO - 'LOGNAME': 'cltbld', 12:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:26 INFO - 'MOZ_NO_REMOTE': '1', 12:12:26 INFO - 'NO_EM_RESTART': '1', 12:12:26 INFO - 'PAGER': '/bin/cat', 12:12:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:26 INFO - 'PWD': '/builds/slave/test', 12:12:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:12:26 INFO - 'SHELL': '/bin/bash', 12:12:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:12:26 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:12:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:12:26 INFO - 'USER': 'cltbld', 12:12:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:12:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:26 INFO - 'XPC_FLAGS': '0x0', 12:12:26 INFO - 'XPC_SERVICE_NAME': '0', 12:12:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:12:26 INFO - Downloading/unpacking flake8 12:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:12:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:12:29 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 12:12:29 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 12:12:29 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 12:12:29 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 12:12:29 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 12:12:29 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 12:12:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:12:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:12:29 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 12:12:29 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 12:12:29 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 12:12:29 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 12:12:29 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 12:12:29 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 12:12:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:12:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:12:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:12:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:12:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:12:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:12:30 INFO - no previously-included directories found matching 'docs/_build' 12:12:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:12:30 INFO - Running setup.py install for pep8 12:12:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:12:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:12:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:12:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:12:30 INFO - no previously-included directories found matching 'docs/_build' 12:12:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:12:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:12:30 INFO - Cleaning up... 12:12:31 INFO - Return code: 0 12:12:31 INFO - Running post-action listener: _resource_record_post_action 12:12:31 INFO - Running post-action listener: _start_resource_monitoring 12:12:31 INFO - Starting resource monitoring. 12:12:31 INFO - ##### 12:12:31 INFO - ##### Running install step. 12:12:31 INFO - ##### 12:12:31 INFO - Running pre-action listener: _resource_record_pre_action 12:12:31 INFO - Running main action method: install 12:12:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:12:31 INFO - Reading from file tmpfile_stdout 12:12:31 INFO - Detecting whether we're running mozinstall >=1.0... 12:12:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:12:31 INFO - Reading from file tmpfile_stdout 12:12:31 INFO - Output received: 12:12:31 INFO - Usage: mozinstall [options] installer 12:12:31 INFO - Options: 12:12:31 INFO - -h, --help show this help message and exit 12:12:31 INFO - -d DEST, --destination=DEST 12:12:31 INFO - Directory to install application into. [default: 12:12:31 INFO - "/builds/slave/test"] 12:12:31 INFO - --app=APP Application being installed. [default: firefox] 12:12:31 INFO - mkdir: /builds/slave/test/build/application 12:12:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:12:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:13:04 INFO - Reading from file tmpfile_stdout 12:13:04 INFO - Output received: 12:13:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:13:04 INFO - Running post-action listener: _resource_record_post_action 12:13:04 INFO - ##### 12:13:04 INFO - ##### Running run-tests step. 12:13:04 INFO - ##### 12:13:04 INFO - Running pre-action listener: _resource_record_pre_action 12:13:04 INFO - Running main action method: run_tests 12:13:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:13:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:13:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:13:04 INFO - 2016-04-21 12:13:04.693 screenresolution[1644:8563] starting screenresolution argv=screenresolution get 12:13:04 INFO - 2016-04-21 12:13:04.696 screenresolution[1644:8563] Display 0: 1600x1200x32@60 12:13:04 INFO - 2016-04-21 12:13:04.701 screenresolution[1645:8565] starting screenresolution argv=screenresolution list 12:13:04 INFO - Available Modes on Display 0 12:13:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:13:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:13:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:13:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:13:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:13:04 INFO - Intel Iris: 12:13:04 INFO - Chipset Model: Intel Iris 12:13:04 INFO - Type: GPU 12:13:04 INFO - Bus: Built-In 12:13:04 INFO - VRAM (Dynamic, Max): 1536 MB 12:13:04 INFO - Vendor: Intel (0x8086) 12:13:04 INFO - Device ID: 0x0a2e 12:13:04 INFO - Revision ID: 0x0009 12:13:04 INFO - Displays: 12:13:04 INFO - Display: 12:13:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:13:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:13:04 INFO - Display Serial Number: 1600x1200 60 12:13:04 INFO - Main Display: Yes 12:13:04 INFO - Mirror: Off 12:13:04 INFO - Online: Yes 12:13:04 INFO - Rotation: Supported 12:13:04 INFO - Return code: 0 12:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:13:04 INFO - Python 2.7.3 12:13:04 INFO - Return code: 0 12:13:04 INFO - grabbing minidump binary from tooltool 12:13: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')]} 12:13:04 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2ce00>, '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 0x10bd95db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf27b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:13:04 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 12:13:04 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 12:13:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:13:05 INFO - Return code: 0 12:13:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:13:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:13:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:13:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:13:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:13:06 INFO - Return code: 0 12:13:06 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:13:06 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0169 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:13:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kI1MNlWKli/Render', 12:13:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:06 INFO - 'HOME': '/Users/cltbld', 12:13:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:06 INFO - 'LOGNAME': 'cltbld', 12:13:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:13:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:06 INFO - 'MOZ_NO_REMOTE': '1', 12:13:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:13:06 INFO - 'NO_EM_RESTART': '1', 12:13:06 INFO - 'PAGER': '/bin/cat', 12:13:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:06 INFO - 'PWD': '/builds/slave/test', 12:13:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:13:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:06 INFO - 'SHELL': '/bin/bash', 12:13:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M4vHhB7hSp/Listeners', 12:13:06 INFO - 'TMPDIR': '/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/', 12:13:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:06 INFO - 'USER': 'cltbld', 12:13:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:06 INFO - 'XPC_FLAGS': '0x0', 12:13:06 INFO - 'XPC_SERVICE_NAME': '0', 12:13:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0169', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:13:06 INFO - mozversion application_buildid: 20160421095844 12:13:06 INFO - mozversion application_changeset: 5ad0f8d2d7dffcf57ff4d935f5dfb4781683091e 12:13:06 INFO - mozversion application_display_name: Nightly 12:13:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:13:06 INFO - mozversion application_name: Firefox 12:13:06 INFO - mozversion application_remotingname: firefox 12:13:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:13:06 INFO - mozversion application_vendor: Mozilla 12:13:06 INFO - mozversion application_version: 48.0a1 12:13:06 INFO - mozversion platform_buildid: 20160421095844 12:13:06 INFO - mozversion platform_changeset: 5ad0f8d2d7dffcf57ff4d935f5dfb4781683091e 12:13:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:13:06 INFO - mozversion platform_version: 48.0a1 12:13:06 INFO - using testdate: 1461265986 12:13:06 INFO - actual date: 1461265986 12:13:06 INFO - starting webserver on 'localhost:49278' 12:13:06 INFO - SUITE-START | Running 2 tests 12:13:06 INFO - TEST-START | dromaeo_css 12:13:06 INFO - operating with platform_type : mac_ 12:13:06 INFO - Initialising browser for dromaeo_css test... 12:13:06 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpSSupte/profile http://localhost:49278/getInfo.html) 12:13:09 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 12:13:09 INFO - PROCESS | 1663 | colorDepth:24 12:13:09 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 12:13:09 INFO - PROCESS | 1663 | __metrics 12:13:09 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 12:13:10 INFO - TEST-INFO | 1663: exit 0 12:13:10 INFO - Browser initialized. 12:13:10 INFO - Running cycle 1/1 for dromaeo_css test... 12:13:10 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpSSupte/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:13:22 INFO - PROCESS | 1667 | RSS: Main: 247668736 12:13:22 INFO - PROCESS | 1667 | 12:14:11 INFO - PROCESS | 1667 | 1461266051920 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] 12:16:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 12:16:24 INFO - PROCESS | 1667 | RSS: Main: 260063232 12:16:24 INFO - PROCESS | 1667 | 12:19:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 12:19:26 INFO - PROCESS | 1667 | RSS: Main: 272408576 12:19:26 INFO - PROCESS | 1667 | 12:22:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 12:22:29 INFO - PROCESS | 1667 | RSS: Main: 273698816 12:22:29 INFO - PROCESS | 1667 | 12:25:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 12:25:31 INFO - PROCESS | 1667 | RSS: Main: 275251200 12:25:31 INFO - PROCESS | 1667 | 12:28:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 12:28:34 INFO - PROCESS | 1667 | RSS: Main: 278253568 12:28:34 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 12:31:37 INFO - PROCESS | 1667 | RSS: Main: 279232512 12:31:37 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | __start_tp_report 12:31:37 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 12:31:37 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 12:31:37 INFO - PROCESS | 1667 | |i|pagename|runs| 12:31:37 INFO - PROCESS | 1667 | |0;dojo.html;3463.497792820041;3470.65293470653;3511.5083888255663;3513.4554144107665;3535.9469607955884;4953.331300274462;5734.082546792514;5779.595428320014;5805.100209467534;5827.271591051119;5663.2637757091625;5762.683052432125;5808.099744539592;5866.120081987704;5925.170476133345;5204.401493828202;5220.164773636219;5242.213667949819;5252.921206181911;5293.602979776513;73.16790249038556;4007.438958545791;5303.28405665236;5315.282436871031;5321.547668448186;5210.557102646242;5247.632665713382;5260.8684782880355;5262.7631756571045;5296.841094767163;5359.579711376488;5361.142217245261;5380.058489764315;5411.350637923435;5416.133418652997;5139.997700448414;5157.948420515821;5167.819126330561;5210.452902445227;5254.97372513135;4480.327950807405;5706.4864162225595;5713.685747283897;5725.2843394575675;5737.598368114233;5244.9737751310995;6108.797423689706;6135.32511423743;6283.7800676976085;6300.243970723497;5806.506446952014;6204.782832600882;6275.3724627536885;6278.937210627881;6306.369363063703;5861.589688721749;5865.765369385262;5891.1457838614015;5895.616784908967;5928.703564821742;6226.25284965807;6332.271788744261;6368.044793280952;6411.358864113551;6417.197850268716;6110.801781737226;6159.630422174684;6160.014397696347;6166.630002199882;6201.410865967726;6181.629102253789;6197.194364732556;6241.906371404432;6285.402886725754;6301.40136687014;6106.72519736613;6139.171211886427;6144.986077297196;6192.947198976185;6226;6119.4798442132915;6142.6314421134875;6158.7228574714245;6167.982282923357;6209.875802484015;4921.458639549646;5003.124453220672;5008.148614735431;5008.674436161711;5009.198528235538;3994.4807175067053;4265.232258193555;4375.190589740908;4504.369388285642;4514.751688657089;4794.880127996758;5821.301443826768;5871.647701137946;5924.407559244128;5949.8215053548465;6277.340879207709;6294.118823364733;6315.778947736807;6321;6335.65153916539;5751.1085781703905;5843.853903652358;5914.319853216935;5962.731677074542;5977.521798256129;5436.238926550286;5488;5489.313835770528;5511;5513.283273174462;6463.903041454382;6464.870702585922;6471.644059576674;6473.805785826433;6476.158099447041;6204.534659900616;6212.0371342440185;6249.687515624291;6289;6293.056041593614;6508.60948343082;6517.348265173445;6517.674116294072;6533.346665333429;6735.898961515483;6099.309783606499;6258.12386265914;6305.211848518935;6314.936850631435;6342.302346741762;6188.381161883775;6236.158118653923;6263.498920086473;6278.905816412666;6285.905711414424;5149.871253218699;6000.969995150171;6106.297775755667;6161.383861613982;6200.689965501797;5294.23233631129;5609.663420194802;6081.331053584014;6191.226096737908;6199.845003874939;5896.9115463268945;5962.522998160223;5998.130271110752;6071.3625069368845;6075.36208698098;5362.410134885237;5812.505936995402;6175.382461753788;6188.721507532262;6189.659568723583;5991.730372133177;6049.153118563493;6061.6666083365835;6090.3605121461605;6116.724747386467 12:31:37 INFO - PROCESS | 1667 | |1;ext.html;509.4905094905095;511.16169482049423;577.1659951370272;581.4838915117197;618.9628622282663;33497.83251083744;38068.80965595168;38090.85727428178;38153;38310.425343619834;39309;39627;39735.403968940416;39957;40253;9360.485173315483;9536.284778641595;9559.426434413957;9610.807783844319;9624.422534647943;9035.367524273304;9245.844269466317;9472.289578281621;9731.562079706395;9746.707598772047;9694.563744631474;9773.657921972732;9781.168600668952;9809.411435313905;9834.164096051843;9693.854592181124;9695.369800962986;9742.512874356325;9748.025197480196;9756.756081097994;9547.61809527625;9639.325247232697;9655.372400793925;9663;9674.661386851427;31369.90205342825;37710.868673939825;38056.80971595152;38069.23861522754;38201.617983820084;2555.642210090589;3057.8318192499405;3460.965390346115;3622.2031153146268;4002;26588.867055664792;27577.86211068932;27618.171454856387;27839.721602783917;28068.438631227466;2672.719364466742;2689.811713180078;2714.891404343824;2716.090109813215;2823.237725814039;327877.9999999976;329415;329654;330285;332631;34914.65085349165;35237.99999999949;35963.820180898925;58736.41263587395;59177.11234331488;29440.41119177647;34997.82501087478;35108.64891351054;35174.296514069574;35399.82300088534;12482.625521234377;12750.61748147557;15023.849761502244;15267.160306183045;15274.15992120444;12431.502739890304;12709.491620335268;12822.35888205586;12930.935345323212;12944.935275323563;6973.5118541701595;7981.68073277074;8149.633266503021;8193.754187374388;8194;3050;3365.730741540671;3376.712979396779;3393.372226138172;3437.8796742114405;876.8816209811594;975.9508528332071;1040.6201736366322;1041.5000799616228;1042.4162469017374;26631.600525992126;28338.433231335257;28390.290242743682;30111.397772044438;30981.760729570564;1044.7283706236433;1083.2525557365393;1084.4144162152506;1133.2690414682647;1181.9231749936228;11691.473883675255;14580.270986450634;14751.63120921964;14831.110133391816;14867.92566037163;520096;527933;530942;532237;534838;55201.44798552043;57315.42684573184;57357.13964290539;57425.85148297011;57529;1470.852914708542;1472.1608683050558;1482.6515768794325;1490.6795039066653;1491.112787891203;28896.855515722287;28948.565771513022;29056.353017002468;29746.85126574353;29818.70181298246;51076.74461627817;51962;52011.699707507614;52032.47967520276;52040.219396709836;569492;593946;595680;599761;601644;26209;26289.474210515302;26318.9472421101;26629.733702662725;26655.067072652364;43118.628475434154;43652.162609430285;43947;44514;44569;36572.45141322829;36751.26497470089;36879.26241475209;37336;37704.81147594244;31308;35609.75365862183;36329.72845950379;36451.63548364482;37052;27742.583861241696;35406.46890296699;35562;36729.816350918074;37149;31687;31823.045308640776;31992.840035799672;32085.870904347987;32251.516227257078;21025.89487052555;21406.357809265748;23781.52436951286;23884;23914.282571522883 12:31:37 INFO - PROCESS | 1667 | |2;jquery.html;395.2865078533248;399.3271337795814;402.895247235719;404.7915323608342;412.3052656274176;19753.802461975374;20508.077136528856;20575.76545407275;20638.793612063873;21672.916354182307;20669.896650516763;20870.37388878336;21335.93320333981;21466.463338416546;21577.813220272878;5768.336641286253;5972.850678733023;5980;5989.520838332923;5997.340292567836;5687.914681279784;5910;5919.615225010382;5986.820395388146;6002.069679199731;5904.2029326040865;5935.3471118177185;5935.64386136831;5945.554083443737;5956.731947062371;5614.29821272341;5902.262217222804;5902.409759024106;5914.053751399777;5926.88146237075;5135.845924622267;5158.92261616076;5163.845084647466;5171.844844654629;5177.094008548489;21129.788702112935;21175.36473905785;25567.48865022708;26711.065112721157;26759.331016724544;2255.616545187322;2306.527161931817;2357.26924653358;2400.8919598618104;2512.522620702061;12443.253404795742;14814.370419253873;15135;15497.992630478982;15627.296771645304;1657.7430498272788;1721.7073097573443;1737.6177241006958;1809.6561653285964;1815.2013114229696;75645.24354756437;86402;104645.47677261641;107933.46033269863;126106.36946815299;9827.557759900821;11538.711532211762;11744.70638234051;11830.17188796776;11972.700682482833;15065.849341506664;15337.156456395005;15376.769348459782;15398.846011539741;15422.614434639;7538.472306938571;8602.87095693565;8667;8810.471371717718;8921.107889211027;7153.6780844862105;8669.869951950726;8691.782705432415;8695.913040869636;8804.779880503038;4576.199165146153;4692.882677933011;4703.600193983481;4706.929396059062;4747.240441529339;1977.7923318051398;1995.6806910894188;2002.119067610247;2010.477275908245;2043.7343145391003;922.1838672774643;929.4748467116058;970.4225985538593;994.9751256218508;1082.6481393547128;15662.295196716175;20465.48836279075;20625.349972002205;20716.482087947923;21080.683789743165;1000.2598077422655;1004.9547770350351;1017.8320577104843;1019.6788011776267;1019.9847002294972;10700.401662256136;11023.44882755859;11024.283421577571;11048.895110488887;11051.778964420666;582789.086054567;607821;609127;620380;626493;65685.67157164279;91150;97672.05215560796;101409;117202.8279717209;976.1068622210739;985.522021819417;998.6531650620983;1002.0280328081749;1008.495752123938;14587.489437869624;16597.83815132929;17417.47747567575;17500.387486437914;18160.455186344432;33537.82617608372;47189.52810471851;50358;51166;52913.20630190627;76532.61733691295;76623;76763.61618191874;77931.61034194793;79806.2019379822;7780.883286750816;7980.720674776355;8047.557384343917;8278.296344810518;8563.357748168788;9471.526423678652;10119.089281964658;12607.495700172258;12910.80633790475;13161.47354105825;10975.725606859893;11175.552977880794;11303.556972525581;11306.321620702784;11464.541418343173;5254;10363.222758293308;10553.155747540332;10999.670009899715;11324.150688698546;11200.327980321206;11213.831792523282;11557.884421156017;11803.881961180277;11941.104417168573;8287.171282871665;8298.460600060977;8325.333973282244;8442.071372149181;8717.346199034972;11490.368029758443;11520.711982200512;11537.82693259585;11779.998700110244;11843.644690659294 12:31:37 INFO - PROCESS | 1667 | |3;mootools.html;1495.22248430816;1614.3300530279935;1879.2952642758964;1979.079727926514;1994.3119623729808;8107.067687215972;8697.956510217447;8712.172343627346;8971.282297416208;9012.459252444849;8731.781705457366;8769.429987050837;8848.203661670448;8855.335849811274;8883.777905552364;2082.8437867159946;2366.6095094309417;2392.688950436441;2408.0247499762622;2425.63615457681;2008.337248707923;2434.2332165367943;2495.3761559610098;2496.213692686798;2499.687539057618;2462.101333013446;2469.234537293436;2477.876106194683;2490.2778194323623;2521.936951576216;2072.4197224777085;2473.0973194783865;2473.9609364067137;2479.3925488255313;2480.1815400917653;2406.2781165650235;2431.1369463840438;2440.1215562397524;2443.3769388805918;2454.2759885833543;8963.820723585557;9032.412893161923;9047.32145089121;9115.680951166743;9118.224950879183;4306.224879521685;5292.497212764782;5360.169173778032;5402.189671549259;5429.511343979021;3530.064532898784;3586.8565257389673;3604.6395360464007;3644.523967793048;3656.542932133483;1012.3217444312328;1048.1509976918721;1049.1344640671477;1072.9892701073045;1086.6739978006644;11463.254888432226;11465.656030319104;11633.767324653545;11645;11667.416629168592;4693.20215563355;4779.503791504058;4893.584045356113;4906.411230652345;4932;9977.900220997843;9997.950010249902;10048.995100489892;10053.447060411601;10080.949595251977;5429.131338985743;5433.538149257279;5433.891322173535;5468.699221542774;5493.093639549509;4361.912761744811;4968.850934471971;4968.8757781055765;4985.853253751585;4999.650024498323;3441.6214216436174;3956.584558621303;3998.480197574355;4092.9386059209137;4134.524529679125;1165.3648761425043;1370.5340184337374;1380.3512349195862;1384.0865029080758;1391.8956078293966;736.2453485177736;812.2770734046703;877.7673916412156;923.9445633261892;946.8485042393322;4775.785089670903;5952.226210592682;6092.634441933498;6101.389861013863;6108.450239478469;930.3905941608258;963.8650588917557;969.520087556664;989.604158336671;997.8403455447094;3713.220223752988;3952.446657467957;4010.458588090629;4024.678025757932;4088.9386659200136;8846.646134154562;9081.68214112503;10563.102136318344;10715.303505272132;10854.240203185702;10621.415822129702;10730.678079657622;10816.351018938889;10820.56717731282;10856.511456984297;7047.365737083687;7981.760547183594;7989.480683755537;8054.556999364974;8175.918240817635;5130.435652078343;5694.231278777312;6018.157457955802;6031.065184896348;6111.663858487648;9987.60049598008;10060.195184385086;10076.748081298027;10187.7453063674;10187.7453063674;9740.366876153028;10078.949605251926;10365.274430789772;10383.428911409945;10408;6970.546914450544;7419.851602968019;7641.2740789624895;7658.846823063396;7790.493617914817;7384.37232835215;7415.332620064004;7634.351080158249;8358.247757701836;8461.365397595328;7258.419326453976;7388.335049845539;7399.3340599344165;7484.550926944401;7563.697452102075;7278.163011253562;7348.889766653398;7357.374623157091;7360.77917662471;7452.143003554661;7115.359617634438;7189.388901943394;7214.206437291999;7220.0613920190035;7223.2415596361625;4904.068227036852;5009.474005229398;5053.797848086182;5077.263796749525;5245.501677340647;4985.376827896513;5005.199168133081;5068.037072956126;5122.282880396673;5269.314989051399 12:31:37 INFO - PROCESS | 1667 | |4;prototype.html;313.4326868368254;324.1458756177472;330.3113009375452;331.3968577189515;332.27398135074856;5184.481551844819;6039.305479869817;6201.844953876144;6279.874402511947;6404.580839220935;5739.397363276858;6179.735982172309;6319.431251187392;6326.905096423558;6333.524985626073;4687.601553867926;4692.090903639748;4704.929426058612;4726.432828060638;4743.786529606159;4430.357598148266;4663.27719203524;4685.695429797052;4735.526447355254;4749.786259618309;4564;4582.129395414877;4592.74739889306;4622.930656040146;4654.790534425959;4101.302778527657;4447.510773814894;4684.320773487842;4699.459562150362;4711.905761884777;3930.7641541507314;4005.5794141615293;4005.7796821174825;4028.2879776095338;4049.5950404959563;4878.780454879539;5182.922256166161;5799.942000579982;6016.398360163992;6024.493876530867;5486.835394938158;5489.533389661883;5510.641808282448;5514.670983278107;5516.227728118027;5746.741396637161;6439.420452159328;6619.536632435731;6662;6663.700133493956;3617.656322649344;4464.352668863029;4480.48474425442;4481.977590112061;4503.662225333081;6217.720202590895;6687.264400915896;6750.864982700318;6775.796726098225;6997.265287144827;4099;4216.936745948813;4353.216421044242;4605.40129783126;4619.099275641218;4680.251159814414;4689.366935463669;4690.319903613957;4695.483496815347;4714.811407543729;5275.366955965309;5356.384015838151;5438.483344082306;5485.61600687948;5520.972395137998;6042.0332746760305;6273.65494897785;6318.240646815138;6495.415412612888;6688.331166883273;1585.8493443122884;1601.927913243907;1609.9758503622454;1611.58904479357;1617.9352825886833;1038.5949479702922;1049.1639630646368;1060.3054998975683;1069.5240617925103;1084.0785332467497;4898;5201.557867581298;5221.112410890082;6178.598391104564;6371.33101024395;5180.912008478186;6464.933286007756;6815.171703742891;6886.931130688729;6969.372756451842;6000.969995149996;6196;6207.875842483216;6232.3144454110015;6239.095331176954;3359.4960755886323;3641.7450778445254;3665.7983810890655;3675.9632403676155;3689.557253129588;5792.701136838705;6341.55609107358;7152.356287934111;7192.7122915083855;7277;6238.844028899223;7042.577445353295;7096.893546596805;7136.464765142635;7151;6065.7876974307455;6948.791536253921;7244.637768111574;7280.745173918887;7281.490295679358;5190.039842629126;6233;6773.848445764331;6945.548539344926;6960.756373526902;4864.391951006124;6470.644114573555;6528.563715982476;6764.661766911733;6774.525783195129;6608.579155481594;7032.753853615099;7150.284971503016;7195.424366050808;7226.421886248982;3900.4344370065614;4516.232240519185;4589.334546490673;4638.889305366118;4651.767411629337;5311.893762124813;6326.588771729823;6704.183753903927;6733.966330168514;6893.20728116253;6365.840853978502;6690.397864192246;6714.865702686016;6721.697523611351;6768.357006084414;5877.676727780014;6445.871972404904;6723.697433615401;6752.561083529555;6855.4172895304455;6565.618706366321;6573.211214654272;6577.473802095725;6587.901181482186;6616.139901812733;6706.631135287606;6722.529422940543;6732.360425759545;6755.560888542229;6825.624590647562;6276.1213430118905;6515.837104072436;6594.439472644879;6604.028187315823;6616.073749675145 12:31:37 INFO - PROCESS | 1667 | |5;yui.html;633.458393073922;670.2627110178803;691.9792406227814;746.779699988503;751.7744676597019;14489.710205795891;14706.044107133055;14936.25318734063;14946.701065978674;15075.321610527526;13867.583972480816;13879.375428105734;13915.930420347884;13952.232627205525;13959.720805583882;728.7012324946746;774.4307933668749;906.8005038891433;930.8091841172577;946.5976959792096;915.9542022898862;938.4979036215626;942.6088173408027;943.575391074016;947.6020071569923;949.5964215208543;979.4221409368471;982.4694664880956;985.8619793228954;987.1214618989105;953.2374100719466;976.838821594436;979.4319294808994;982.9459379734112;982.9606815727398;693.7779910428665;699.8425354295243;712.4371746320401;720.387670480093;727.2545640718306;15006;15019.924900375428;15024.09855408679;15288.617784555476;15323.31045102962;12634.936825315906;12714;12714.682132946658;12783;12920.54778082772;9948.602055917754;10204.285700001003;10215.89784102157;10289.48552572376;10443.530041148166;3372.814495202763;3481.9477707834403;3492.7729697569575;3497.510348551205;3521.7710848795004;66933.32666683324;70116.29883701149;80365;81571;81707.59146204291;9051.864222036675;9252.907470925205;9269.397489163182;9441.15029647335;9538.95230523843;20675.379738607866;20680.414948131485;20721.792782072287;21025.68461473079;21183.14027606284;7268.781936541912;7342.889856652155;7349.228331025272;7603.885941710879;7691.461597688108;4002.8198731057173;4063.553009168989;4068.816903239361;4069.654079403225;4070.2063097695664;3029.2881172924344;3538.3454060998797;3610.6389361063684;3618.7466877318334;3619.6380361963593;557.9665220086727;640.1837656987378;653.0758976048933;654.358728446116;663.151166506872;526.7471613625481;541.8618252345626;551.4044831581882;602.0103557741619;615.8368032471399;7472.813179670443;7823.843523129505;7848.3328917041545;7915.920840791634;8534.74395768128;570.8715539003691;585.7803323753592;588.7870985769329;595.4313630482878;603.7705671844759;3717.070732316921;3848.6728628066367;3856.686103061132;3882.708796840192;3890.5331360236387;88614.55692721494;89691;90455;90823;91162.54418727997;62472.687636562434;77503.06242343874;79015;79848.60075699585;79934;54054;68472.65763671149;68523.49026842442;71250.2874971254;71868;7498.550086994798;7524.924750752422;7676.385889128968;7683.577403242875;7774;35645.82177089098;44044;44893.102137957714;45094.97072631805;45776.54223457723;59352;60012.69993650004;60195.39804601898;63827.680861597255;64528.0482035697;9918.752031199278;9928.262554053157;9943.950280248553;9993.600255989679;10247.846282305622;10810;10888.128949684165;10901.563937442414;11218.273351860918;11246.325220486797;10152;10377.84433233487;10409.1672666185;10673.199510036933;10712.839307410239;10007.749806254902;10145.239107067147;10212.774437176598;10536.051755342056;11226.270292430965;9977.251706121924;10077.395356278646;10182.541785619516;10324.638637647646;10371.377717336984;8566.614502347285;8581.098984606526;8740.650373985223;8832.29341652679;8912.821743565222;7230.855382892418;8803.691870784492;8888.288936885163;9005;9049.959254685533 12:31:37 INFO - PROCESS | 1667 | __end_tp_report 12:31:37 INFO - PROCESS | 1667 | __start_cc_report 12:31:37 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,391 12:31:37 INFO - PROCESS | 1667 | __end_cc_report 12:31:37 INFO - PROCESS | 1667 | __startTimestamp1461267097640__endTimestamp 12:31:37 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | ------- Summary: start ------- 12:31:37 INFO - PROCESS | 1667 | Number of tests: 6 12:31:37 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5724.44 Median:5977.52 stddev:801.14 (13.4%) stddev-sans-first:783.72 12:31:37 INFO - PROCESS | 1667 | Values: 3463.5 3470.7 3511.5 3513.5 3535.9 4953.3 5734.1 5779.6 5805.1 5827.3 5663.3 5762.7 5808.1 5866.1 5925.2 5204.4 5220.2 5242.2 5252.9 5293.6 73.2 4007.4 5303.3 5315.3 5321.5 5210.6 5247.6 5260.9 5262.8 5296.8 5359.6 5361.1 5380.1 5411.4 5416.1 5140.0 5157.9 5167.8 5210.5 5255.0 4480.3 5706.5 5713.7 5725.3 5737.6 5245.0 6108.8 6135.3 6283.8 6300.2 5806.5 6204.8 6275.4 6278.9 6306.4 5861.6 5865.8 5891.1 5895.6 5928.7 6226.3 6332.3 6368.0 6411.4 6417.2 6110.8 6159.6 6160.0 6166.6 6201.4 6181.6 6197.2 6241.9 6285.4 6301.4 6106.7 6139.2 6145.0 6192.9 6226.0 6119.5 6142.6 6158.7 6168.0 6209.9 4921.5 5003.1 5008.1 5008.7 5009.2 3994.5 4265.2 4375.2 4504.4 4514.8 4794.9 5821.3 5871.6 5924.4 5949.8 6277.3 6294.1 6315.8 6321.0 6335.7 5751.1 5843.9 5914.3 5962.7 5977.5 5436.2 5488.0 5489.3 5511.0 5513.3 6463.9 6464.9 6471.6 6473.8 6476.2 6204.5 6212.0 6249.7 6289.0 6293.1 6508.6 6517.3 6517.7 6533.3 6735.9 6099.3 6258.1 6305.2 6314.9 6342.3 6188.4 6236.2 6263.5 6278.9 6285.9 5149.9 6001.0 6106.3 6161.4 6200.7 5294.2 5609.7 6081.3 6191.2 6199.8 5896.9 5962.5 5998.1 6071.4 6075.4 5362.4 5812.5 6175.4 6188.7 6189.7 5991.7 6049.2 6061.7 6090.4 6116.7 12:31:37 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:60583.53 Median:19793.01 stddev:132941.41 (671.7%) stddev-sans-first:133237.83 12:31:37 INFO - PROCESS | 1667 | Values: 509.5 511.2 577.2 581.5 619.0 33497.8 38068.8 38090.9 38153.0 38310.4 39309.0 39627.0 39735.4 39957.0 40253.0 9360.5 9536.3 9559.4 9610.8 9624.4 9035.4 9245.8 9472.3 9731.6 9746.7 9694.6 9773.7 9781.2 9809.4 9834.2 9693.9 9695.4 9742.5 9748.0 9756.8 9547.6 9639.3 9655.4 9663.0 9674.7 31369.9 37710.9 38056.8 38069.2 38201.6 2555.6 3057.8 3461.0 3622.2 4002.0 26588.9 27577.9 27618.2 27839.7 28068.4 2672.7 2689.8 2714.9 2716.1 2823.2 327878.0 329415.0 329654.0 330285.0 332631.0 34914.7 35238.0 35963.8 58736.4 59177.1 29440.4 34997.8 35108.6 35174.3 35399.8 12482.6 12750.6 15023.8 15267.2 15274.2 12431.5 12709.5 12822.4 12930.9 12944.9 6973.5 7981.7 8149.6 8193.8 8194.0 3050.0 3365.7 3376.7 3393.4 3437.9 876.9 976.0 1040.6 1041.5 1042.4 26631.6 28338.4 28390.3 30111.4 30981.8 1044.7 1083.3 1084.4 1133.3 1181.9 11691.5 14580.3 14751.6 14831.1 14867.9 520096.0 527933.0 530942.0 532237.0 534838.0 55201.4 57315.4 57357.1 57425.9 57529.0 1470.9 1472.2 1482.7 1490.7 1491.1 28896.9 28948.6 29056.4 29746.9 29818.7 51076.7 51962.0 52011.7 52032.5 52040.2 569492.0 593946.0 595680.0 599761.0 601644.0 26209.0 26289.5 26318.9 26629.7 26655.1 43118.6 43652.2 43947.0 44514.0 44569.0 36572.5 36751.3 36879.3 37336.0 37704.8 31308.0 35609.8 36329.7 36451.6 37052.0 27742.6 35406.5 35562.0 36729.8 37149.0 31687.0 31823.0 31992.8 32085.9 32251.5 21025.9 21406.4 23781.5 23884.0 23914.3 12:31:37 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:33774.93 Median:2456.71 stddev:100591.59 (4094.6%) stddev-sans-first:100842.55 12:31:37 INFO - PROCESS | 1667 | Values: 395.3 399.3 402.9 404.8 412.3 19753.8 20508.1 20575.8 20638.8 21672.9 20669.9 20870.4 21335.9 21466.5 21577.8 5768.3 5972.9 5980.0 5989.5 5997.3 5687.9 5910.0 5919.6 5986.8 6002.1 5904.2 5935.3 5935.6 5945.6 5956.7 5614.3 5902.3 5902.4 5914.1 5926.9 5135.8 5158.9 5163.8 5171.8 5177.1 21129.8 21175.4 25567.5 26711.1 26759.3 2255.6 2306.5 2357.3 2400.9 2512.5 12443.3 14814.4 15135.0 15498.0 15627.3 1657.7 1721.7 1737.6 1809.7 1815.2 75645.2 86402.0 104645.5 107933.5 126106.4 9827.6 11538.7 11744.7 11830.2 11972.7 15065.8 15337.2 15376.8 15398.8 15422.6 7538.5 8602.9 8667.0 8810.5 8921.1 7153.7 8669.9 8691.8 8695.9 8804.8 4576.2 4692.9 4703.6 4706.9 4747.2 1977.8 1995.7 2002.1 2010.5 2043.7 922.2 929.5 970.4 995.0 1082.6 15662.3 20465.5 20625.3 20716.5 21080.7 1000.3 1005.0 1017.8 1019.7 1020.0 10700.4 11023.4 11024.3 11048.9 11051.8 582789.1 607821.0 609127.0 620380.0 626493.0 65685.7 91150.0 97672.1 101409.0 117202.8 976.1 985.5 998.7 1002.0 1008.5 14587.5 16597.8 17417.5 17500.4 18160.5 33537.8 47189.5 50358.0 51166.0 52913.2 76532.6 76623.0 76763.6 77931.6 79806.2 7780.9 7980.7 8047.6 8278.3 8563.4 9471.5 10119.1 12607.5 12910.8 13161.5 10975.7 11175.6 11303.6 11306.3 11464.5 5254.0 10363.2 10553.2 10999.7 11324.2 11200.3 11213.8 11557.9 11803.9 11941.1 8287.2 8298.5 8325.3 8442.1 8717.3 11490.4 11520.7 11537.8 11780.0 11843.6 12:31:37 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5722.84 Median:4985.62 stddev:3166.31 (63.5%) stddev-sans-first:3159.26 12:31:37 INFO - PROCESS | 1667 | Values: 1495.2 1614.3 1879.3 1979.1 1994.3 8107.1 8698.0 8712.2 8971.3 9012.5 8731.8 8769.4 8848.2 8855.3 8883.8 2082.8 2366.6 2392.7 2408.0 2425.6 2008.3 2434.2 2495.4 2496.2 2499.7 2462.1 2469.2 2477.9 2490.3 2521.9 2072.4 2473.1 2474.0 2479.4 2480.2 2406.3 2431.1 2440.1 2443.4 2454.3 8963.8 9032.4 9047.3 9115.7 9118.2 4306.2 5292.5 5360.2 5402.2 5429.5 3530.1 3586.9 3604.6 3644.5 3656.5 1012.3 1048.2 1049.1 1073.0 1086.7 11463.3 11465.7 11633.8 11645.0 11667.4 4693.2 4779.5 4893.6 4906.4 4932.0 9977.9 9998.0 10049.0 10053.4 10080.9 5429.1 5433.5 5433.9 5468.7 5493.1 4361.9 4968.9 4968.9 4985.9 4999.7 3441.6 3956.6 3998.5 4092.9 4134.5 1165.4 1370.5 1380.4 1384.1 1391.9 736.2 812.3 877.8 923.9 946.8 4775.8 5952.2 6092.6 6101.4 6108.5 930.4 963.9 969.5 989.6 997.8 3713.2 3952.4 4010.5 4024.7 4088.9 8846.6 9081.7 10563.1 10715.3 10854.2 10621.4 10730.7 10816.4 10820.6 10856.5 7047.4 7981.8 7989.5 8054.6 8175.9 5130.4 5694.2 6018.2 6031.1 6111.7 9987.6 10060.2 10076.7 10187.7 10187.7 9740.4 10078.9 10365.3 10383.4 10408.0 6970.5 7419.9 7641.3 7658.8 7790.5 7384.4 7415.3 7634.4 8358.2 8461.4 7258.4 7388.3 7399.3 7484.6 7563.7 7278.2 7348.9 7357.4 7360.8 7452.1 7115.4 7189.4 7214.2 7220.1 7223.2 4904.1 5009.5 5053.8 5077.3 5245.5 4985.4 5005.2 5068.0 5122.3 5269.3 12:31:37 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5365.39 Median:6008.68 stddev:1671.66 (27.8%) stddev-sans-first:1632.78 12:31:37 INFO - PROCESS | 1667 | Values: 313.4 324.1 330.3 331.4 332.3 5184.5 6039.3 6201.8 6279.9 6404.6 5739.4 6179.7 6319.4 6326.9 6333.5 4687.6 4692.1 4704.9 4726.4 4743.8 4430.4 4663.3 4685.7 4735.5 4749.8 4564.0 4582.1 4592.7 4622.9 4654.8 4101.3 4447.5 4684.3 4699.5 4711.9 3930.8 4005.6 4005.8 4028.3 4049.6 4878.8 5182.9 5799.9 6016.4 6024.5 5486.8 5489.5 5510.6 5514.7 5516.2 5746.7 6439.4 6619.5 6662.0 6663.7 3617.7 4464.4 4480.5 4482.0 4503.7 6217.7 6687.3 6750.9 6775.8 6997.3 4099.0 4216.9 4353.2 4605.4 4619.1 4680.3 4689.4 4690.3 4695.5 4714.8 5275.4 5356.4 5438.5 5485.6 5521.0 6042.0 6273.7 6318.2 6495.4 6688.3 1585.8 1601.9 1610.0 1611.6 1617.9 1038.6 1049.2 1060.3 1069.5 1084.1 4898.0 5201.6 5221.1 6178.6 6371.3 5180.9 6464.9 6815.2 6886.9 6969.4 6001.0 6196.0 6207.9 6232.3 6239.1 3359.5 3641.7 3665.8 3676.0 3689.6 5792.7 6341.6 7152.4 7192.7 7277.0 6238.8 7042.6 7096.9 7136.5 7151.0 6065.8 6948.8 7244.6 7280.7 7281.5 5190.0 6233.0 6773.8 6945.5 6960.8 4864.4 6470.6 6528.6 6764.7 6774.5 6608.6 7032.8 7150.3 7195.4 7226.4 3900.4 4516.2 4589.3 4638.9 4651.8 5311.9 6326.6 6704.2 6734.0 6893.2 6365.8 6690.4 6714.9 6721.7 6768.4 5877.7 6445.9 6723.7 6752.6 6855.4 6565.6 6573.2 6577.5 6587.9 6616.1 6706.6 6722.5 6732.4 6755.6 6825.6 6276.1 6515.8 6594.4 6604.0 6616.1 12:31:37 INFO - PROCESS | 1667 | 12:31:37 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17355.25 Median:32230.57 stddev:24475.49 (75.9%) stddev-sans-first:24511.95 12:31:37 INFO - PROCESS | 1667 | Values: 633.5 670.3 692.0 746.8 751.8 14489.7 14706.0 14936.3 14946.7 15075.3 13867.6 13879.4 13915.9 13952.2 13959.7 728.7 774.4 906.8 930.8 946.6 916.0 938.5 942.6 943.6 947.6 949.6 979.4 982.5 985.9 987.1 953.2 976.8 979.4 982.9 983.0 693.8 699.8 712.4 720.4 727.3 15006.0 15019.9 15024.1 15288.6 15323.3 12634.9 12714.0 12714.7 12783.0 12920.5 9948.6 10204.3 10215.9 10289.5 10443.5 3372.8 3481.9 3492.8 3497.5 3521.8 66933.3 70116.3 80365.0 81571.0 81707.6 9051.9 9252.9 9269.4 9441.2 9539.0 20675.4 20680.4 20721.8 21025.7 21183.1 7268.8 7342.9 7349.2 7603.9 7691.5 4002.8 4063.6 4068.8 4069.7 4070.2 3029.3 3538.3 3610.6 3618.7 3619.6 558.0 640.2 653.1 654.4 663.2 526.7 541.9 551.4 602.0 615.8 7472.8 7823.8 7848.3 7915.9 8534.7 570.9 585.8 588.8 595.4 603.8 3717.1 3848.7 3856.7 3882.7 3890.5 88614.6 89691.0 90455.0 90823.0 91162.5 62472.7 77503.1 79015.0 79848.6 79934.0 54054.0 68472.7 68523.5 71250.3 71868.0 7498.6 7524.9 7676.4 7683.6 7774.0 35645.8 44044.0 44893.1 45095.0 45776.5 59352.0 60012.7 60195.4 63827.7 64528.0 9918.8 9928.3 9944.0 9993.6 10247.8 10810.0 10888.1 10901.6 11218.3 11246.3 10152.0 10377.8 10409.2 10673.2 10712.8 10007.7 10145.2 10212.8 10536.1 11226.3 9977.3 10077.4 10182.5 10324.6 10371.4 8566.6 8581.1 8740.7 8832.3 8912.8 7230.9 8803.7 8888.3 9005.0 9050.0 12:31:37 INFO - PROCESS | 1667 | -------- Summary: end -------- 12:31:37 INFO - PROCESS | 1667 | 12:31:38 INFO - TEST-INFO | 1667: exit 0 12:31:38 INFO - TEST-OK | dromaeo_css | took 1111721ms 12:31:38 INFO - TEST-START | kraken 12:31:38 INFO - operating with platform_type : mac_ 12:31:38 INFO - Initialising browser for kraken test... 12:31:38 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpxwTKyw/profile http://localhost:49278/getInfo.html) 12:31:40 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 12:31:40 INFO - PROCESS | 1675 | colorDepth:24 12:31:40 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 12:31:40 INFO - PROCESS | 1675 | __metrics 12:31:40 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 12:31:41 INFO - TEST-INFO | 1675: exit 0 12:31:41 INFO - Browser initialized. 12:31:41 INFO - Running cycle 1/1 for kraken test... 12:31:41 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/T/tmpxwTKyw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:31:53 INFO - PROCESS | 1678 | RSS: Main: 248221696 12:31:53 INFO - PROCESS | 1678 | 12:31:56 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 12:31:57 INFO - PROCESS | 1678 | RSS: Main: 260046848 12:31:57 INFO - PROCESS | 1678 | 12:31:59 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 12:32:00 INFO - PROCESS | 1678 | RSS: Main: 269025280 12:32:00 INFO - PROCESS | 1678 | 12:32:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 12:32:04 INFO - PROCESS | 1678 | RSS: Main: 272179200 12:32:04 INFO - PROCESS | 1678 | 12:32:06 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 12:32:07 INFO - PROCESS | 1678 | RSS: Main: 274210816 12:32:07 INFO - PROCESS | 1678 | 12:32:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:32:10 INFO - PROCESS | 1678 | RSS: Main: 274579456 12:32:10 INFO - PROCESS | 1678 | 12:32:12 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 12:32:13 INFO - PROCESS | 1678 | RSS: Main: 274698240 12:32:13 INFO - PROCESS | 1678 | 12:32:16 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 12:32:17 INFO - PROCESS | 1678 | RSS: Main: 274935808 12:32:17 INFO - PROCESS | 1678 | 12:32:19 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 12:32:20 INFO - PROCESS | 1678 | RSS: Main: 275488768 12:32:20 INFO - PROCESS | 1678 | 12:32:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:32:22 INFO - PROCESS | 1678 | RSS: Main: 274882560 12:32:22 INFO - PROCESS | 1678 | 12:32:24 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:32:25 INFO - PROCESS | 1678 | RSS: Main: 275021824 12:32:25 INFO - PROCESS | 1678 | 12:32:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:32:28 INFO - PROCESS | 1678 | RSS: Main: 275427328 12:32:28 INFO - PROCESS | 1678 | 12:32:30 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:32:31 INFO - PROCESS | 1678 | RSS: Main: 275574784 12:32:31 INFO - PROCESS | 1678 | 12:32:34 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:32:35 INFO - PROCESS | 1678 | RSS: Main: 275599360 12:32:35 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 12:32:37 INFO - PROCESS | 1678 | RSS: Main: 277323776 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | __start_tp_report 12:32:37 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 12:32:37 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 12:32:37 INFO - PROCESS | 1678 | |i|pagename|runs| 12:32:37 INFO - PROCESS | 1678 | |0;ai-astar;115;128;168;168;116;128;117;120;115;116 12:32:37 INFO - PROCESS | 1678 | |1;audio-beat-detection;153;121;149;222;121;138;137;123;168;122 12:32:37 INFO - PROCESS | 1678 | |2;audio-dft;220;210;229;264;271;243;194;201;201;196 12:32:37 INFO - PROCESS | 1678 | |3;audio-fft;66;81;84;150;82;54;63;65;89;57 12:32:37 INFO - PROCESS | 1678 | |4;audio-oscillator;77;87;125;97;119;115;130;78;79;125 12:32:37 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;136;131;87;75;136;124;89;86;88;88 12:32:37 INFO - PROCESS | 1678 | |6;imaging-darkroom;94;97;96;94;95;96;96;96;94;94 12:32:37 INFO - PROCESS | 1678 | |7;imaging-desaturate;150;132;96;96;94;95;96;92;93;92 12:32:37 INFO - PROCESS | 1678 | |8;json-parse-financial;61;63;61;60;61;63;63;81;64;59 12:32:37 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;67;64;70;66;70;67;67;67;66;66 12:32:37 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;84;83;84;86;83;85;85;85;85;85 12:32:37 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;139;149;165;164;162;158;140;140;204;197 12:32:37 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;175;215;206;236;230;157;229;232;159;171 12:32:37 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;67;70;65;87;86;59;71;63;60;93 12:32:37 INFO - PROCESS | 1678 | __end_tp_report 12:32:37 INFO - PROCESS | 1678 | __start_cc_report 12:32:37 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1020 12:32:37 INFO - PROCESS | 1678 | __end_cc_report 12:32:37 INFO - PROCESS | 1678 | __startTimestamp1461267157473__endTimestamp 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | ------- Summary: start ------- 12:32:37 INFO - PROCESS | 1678 | Number of tests: 14 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:129.10 Median:124.00 stddev:21.08 (17.0%) stddev-sans-first:21.73 12:32:37 INFO - PROCESS | 1678 | Values: 115.0 128.0 168.0 168.0 116.0 128.0 117.0 120.0 115.0 116.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:143.50 stddev:31.30 (21.8%) stddev-sans-first:33.07 12:32:37 INFO - PROCESS | 1678 | Values: 153.0 121.0 149.0 222.0 121.0 138.0 137.0 123.0 168.0 122.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:222.90 Median:224.50 stddev:28.16 (12.5%) stddev-sans-first:29.85 12:32:37 INFO - PROCESS | 1678 | Values: 220.0 210.0 229.0 264.0 271.0 243.0 194.0 201.0 201.0 196.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:79.10 Median:73.50 stddev:27.71 (37.7%) stddev-sans-first:28.98 12:32:37 INFO - PROCESS | 1678 | Values: 66.0 81.0 84.0 150.0 82.0 54.0 63.0 65.0 89.0 57.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:77.50 stddev:21.77 (28.1%) stddev-sans-first:20.93 12:32:37 INFO - PROCESS | 1678 | Values: 77.0 87.0 125.0 97.0 119.0 115.0 130.0 78.0 79.0 125.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:104.00 Median:86.50 stddev:24.42 (28.2%) stddev-sans-first:23.00 12:32:37 INFO - PROCESS | 1678 | Values: 136.0 131.0 87.0 75.0 136.0 124.0 89.0 86.0 88.0 88.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:96.00 stddev:1.14 (1.2%) stddev-sans-first:1.12 12:32:37 INFO - PROCESS | 1678 | Values: 94.0 97.0 96.0 94.0 95.0 96.0 96.0 96.0 94.0 94.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:103.60 Median:94.50 stddev:20.22 (21.4%) stddev-sans-first:12.69 12:32:37 INFO - PROCESS | 1678 | Values: 150.0 132.0 96.0 96.0 94.0 95.0 96.0 92.0 93.0 92.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:63.00 stddev:6.31 (10.0%) stddev-sans-first:6.62 12:32:37 INFO - PROCESS | 1678 | Values: 61.0 63.0 61.0 60.0 61.0 63.0 63.0 81.0 64.0 59.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:67.00 stddev:1.83 (2.7%) stddev-sans-first:1.94 12:32:37 INFO - PROCESS | 1678 | Values: 67.0 64.0 70.0 66.0 70.0 67.0 67.0 67.0 66.0 66.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:1.01 12:32:37 INFO - PROCESS | 1678 | Values: 84.0 83.0 84.0 86.0 83.0 85.0 85.0 85.0 85.0 85.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:161.80 Median:163.00 stddev:22.81 (14.0%) stddev-sans-first:22.66 12:32:37 INFO - PROCESS | 1678 | Values: 139.0 149.0 165.0 164.0 162.0 158.0 140.0 140.0 204.0 197.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.00 Median:222.00 stddev:32.16 (14.5%) stddev-sans-first:32.70 12:32:37 INFO - PROCESS | 1678 | Values: 175.0 215.0 206.0 236.0 230.0 157.0 229.0 232.0 159.0 171.0 12:32:37 INFO - PROCESS | 1678 | 12:32:37 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:70.50 stddev:12.18 (17.3%) stddev-sans-first:12.78 12:32:37 INFO - PROCESS | 1678 | Values: 67.0 70.0 65.0 87.0 86.0 59.0 71.0 63.0 60.0 93.0 12:32:37 INFO - PROCESS | 1678 | -------- Summary: end -------- 12:32:37 INFO - PROCESS | 1678 | 12:32:38 INFO - TEST-INFO | 1678: exit 0 12:32:38 INFO - TEST-OK | kraken | took 60048ms 12:32:38 INFO - SUITE-END | took 1171s 12:32:38 INFO - Completed test suite (00:19:32) 12:32:38 INFO - javascript benchmark 12:32:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3463.497792820041, 3470.65293470653, 3511.5083888255663, 3513.4554144107665, 3535.9469607955884, 4953.331300274462, 5734.082546792514, 5779.595428320014, 5805.100209467534, 5827.271591051119, 5663.2637757091625, 5762.683052432125, 5808.099744539592, 5866.120081987704, 5925.170476133345, 5204.401493828202, 5220.164773636219, 5242.213667949819, 5252.921206181911, 5293.602979776513, 73.16790249038556, 4007.438958545791, 5303.28405665236, 5315.282436871031, 5321.547668448186, 5210.557102646242, 5247.632665713382, 5260.8684782880355, 5262.7631756571045, 5296.841094767163, 5359.579711376488, 5361.142217245261, 5380.058489764315, 5411.350637923435, 5416.133418652997, 5139.997700448414, 5157.948420515821, 5167.819126330561, 5210.452902445227, 5254.97372513135, 4480.327950807405, 5706.4864162225595, 5713.685747283897, 5725.2843394575675, 5737.598368114233, 5244.9737751310995, 6108.797423689706, 6135.32511423743, 6283.7800676976085, 6300.243970723497, 5806.506446952014, 6204.782832600882, 6275.3724627536885, 6278.937210627881, 6306.369363063703, 5861.589688721749, 5865.765369385262, 5891.1457838614015, 5895.616784908967, 5928.703564821742, 6226.25284965807, 6332.271788744261, 6368.044793280952, 6411.358864113551, 6417.197850268716, 6110.801781737226, 6159.630422174684, 6160.014397696347, 6166.630002199882, 6201.410865967726, 6181.629102253789, 6197.194364732556, 6241.906371404432, 6285.402886725754, 6301.40136687014, 6106.72519736613, 6139.171211886427, 6144.986077297196, 6192.947198976185, 6226.0, 6119.4798442132915, 6142.6314421134875, 6158.7228574714245, 6167.982282923357, 6209.875802484015, 4921.458639549646, 5003.124453220672, 5008.148614735431, 5008.674436161711, 5009.198528235538, 3994.4807175067053, 4265.232258193555, 4375.190589740908, 4504.369388285642, 4514.751688657089, 4794.880127996758, 5821.301443826768, 5871.647701137946, 5924.407559244128, 5949.8215053548465, 6277.340879207709, 6294.118823364733, 6315.778947736807, 6321.0, 6335.65153916539, 5751.1085781703905, 5843.853903652358, 5914.319853216935, 5962.731677074542, 5977.521798256129, 5436.238926550286, 5488.0, 5489.313835770528, 5511.0, 5513.283273174462, 6463.903041454382, 6464.870702585922, 6471.644059576674, 6473.805785826433, 6476.158099447041, 6204.534659900616, 6212.0371342440185, 6249.687515624291, 6289.0, 6293.056041593614, 6508.60948343082, 6517.348265173445, 6517.674116294072, 6533.346665333429, 6735.898961515483, 6099.309783606499, 6258.12386265914, 6305.211848518935, 6314.936850631435, 6342.302346741762, 6188.381161883775, 6236.158118653923, 6263.498920086473, 6278.905816412666, 6285.905711414424, 5149.871253218699, 6000.969995150171, 6106.297775755667, 6161.383861613982, 6200.689965501797, 5294.23233631129, 5609.663420194802, 6081.331053584014, 6191.226096737908, 6199.845003874939, 5896.9115463268945, 5962.522998160223, 5998.130271110752, 6071.3625069368845, 6075.36208698098, 5362.410134885237, 5812.505936995402, 6175.382461753788, 6188.721507532262, 6189.659568723583, 5991.730372133177, 6049.153118563493, 6061.6666083365835, 6090.3605121461605, 6116.724747386467], "value": 5675.615768722285, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.4905094905095, 511.16169482049423, 577.1659951370272, 581.4838915117197, 618.9628622282663, 33497.83251083744, 38068.80965595168, 38090.85727428178, 38153.0, 38310.425343619834, 39309.0, 39627.0, 39735.403968940416, 39957.0, 40253.0, 9360.485173315483, 9536.284778641595, 9559.426434413957, 9610.807783844319, 9624.422534647943, 9035.367524273304, 9245.844269466317, 9472.289578281621, 9731.562079706395, 9746.707598772047, 9694.563744631474, 9773.657921972732, 9781.168600668952, 9809.411435313905, 9834.164096051843, 9693.854592181124, 9695.369800962986, 9742.512874356325, 9748.025197480196, 9756.756081097994, 9547.61809527625, 9639.325247232697, 9655.372400793925, 9663.0, 9674.661386851427, 31369.90205342825, 37710.868673939825, 38056.80971595152, 38069.23861522754, 38201.617983820084, 2555.642210090589, 3057.8318192499405, 3460.965390346115, 3622.2031153146268, 4002.0, 26588.867055664792, 27577.86211068932, 27618.171454856387, 27839.721602783917, 28068.438631227466, 2672.719364466742, 2689.811713180078, 2714.891404343824, 2716.090109813215, 2823.237725814039, 327877.9999999976, 329415.0, 329654.0, 330285.0, 332631.0, 34914.65085349165, 35237.99999999949, 35963.820180898925, 58736.41263587395, 59177.11234331488, 29440.41119177647, 34997.82501087478, 35108.64891351054, 35174.296514069574, 35399.82300088534, 12482.625521234377, 12750.61748147557, 15023.849761502244, 15267.160306183045, 15274.15992120444, 12431.502739890304, 12709.491620335268, 12822.35888205586, 12930.935345323212, 12944.935275323563, 6973.5118541701595, 7981.68073277074, 8149.633266503021, 8193.754187374388, 8194.0, 3050.0, 3365.730741540671, 3376.712979396779, 3393.372226138172, 3437.8796742114405, 876.8816209811594, 975.9508528332071, 1040.6201736366322, 1041.5000799616228, 1042.4162469017374, 26631.600525992126, 28338.433231335257, 28390.290242743682, 30111.397772044438, 30981.760729570564, 1044.7283706236433, 1083.2525557365393, 1084.4144162152506, 1133.2690414682647, 1181.9231749936228, 11691.473883675255, 14580.270986450634, 14751.63120921964, 14831.110133391816, 14867.92566037163, 520096.0, 527933.0, 530942.0, 532237.0, 534838.0, 55201.44798552043, 57315.42684573184, 57357.13964290539, 57425.85148297011, 57529.0, 1470.852914708542, 1472.1608683050558, 1482.6515768794325, 1490.6795039066653, 1491.112787891203, 28896.855515722287, 28948.565771513022, 29056.353017002468, 29746.85126574353, 29818.70181298246, 51076.74461627817, 51962.0, 52011.699707507614, 52032.47967520276, 52040.219396709836, 569492.0, 593946.0, 595680.0, 599761.0, 601644.0, 26209.0, 26289.474210515302, 26318.9472421101, 26629.733702662725, 26655.067072652364, 43118.628475434154, 43652.162609430285, 43947.0, 44514.0, 44569.0, 36572.45141322829, 36751.26497470089, 36879.26241475209, 37336.0, 37704.81147594244, 31308.0, 35609.75365862183, 36329.72845950379, 36451.63548364482, 37052.0, 27742.583861241696, 35406.46890296699, 35562.0, 36729.816350918074, 37149.0, 31687.0, 31823.045308640776, 31992.840035799672, 32085.870904347987, 32251.516227257078, 21025.89487052555, 21406.357809265748, 23781.52436951286, 23884.0, 23914.282571522883], "value": 17803.365828090617, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.2865078533248, 399.3271337795814, 402.895247235719, 404.7915323608342, 412.3052656274176, 19753.802461975374, 20508.077136528856, 20575.76545407275, 20638.793612063873, 21672.916354182307, 20669.896650516763, 20870.37388878336, 21335.93320333981, 21466.463338416546, 21577.813220272878, 5768.336641286253, 5972.850678733023, 5980.0, 5989.520838332923, 5997.340292567836, 5687.914681279784, 5910.0, 5919.615225010382, 5986.820395388146, 6002.069679199731, 5904.2029326040865, 5935.3471118177185, 5935.64386136831, 5945.554083443737, 5956.731947062371, 5614.29821272341, 5902.262217222804, 5902.409759024106, 5914.053751399777, 5926.88146237075, 5135.845924622267, 5158.92261616076, 5163.845084647466, 5171.844844654629, 5177.094008548489, 21129.788702112935, 21175.36473905785, 25567.48865022708, 26711.065112721157, 26759.331016724544, 2255.616545187322, 2306.527161931817, 2357.26924653358, 2400.8919598618104, 2512.522620702061, 12443.253404795742, 14814.370419253873, 15135.0, 15497.992630478982, 15627.296771645304, 1657.7430498272788, 1721.7073097573443, 1737.6177241006958, 1809.6561653285964, 1815.2013114229696, 75645.24354756437, 86402.0, 104645.47677261641, 107933.46033269863, 126106.36946815299, 9827.557759900821, 11538.711532211762, 11744.70638234051, 11830.17188796776, 11972.700682482833, 15065.849341506664, 15337.156456395005, 15376.769348459782, 15398.846011539741, 15422.614434639, 7538.472306938571, 8602.87095693565, 8667.0, 8810.471371717718, 8921.107889211027, 7153.6780844862105, 8669.869951950726, 8691.782705432415, 8695.913040869636, 8804.779880503038, 4576.199165146153, 4692.882677933011, 4703.600193983481, 4706.929396059062, 4747.240441529339, 1977.7923318051398, 1995.6806910894188, 2002.119067610247, 2010.477275908245, 2043.7343145391003, 922.1838672774643, 929.4748467116058, 970.4225985538593, 994.9751256218508, 1082.6481393547128, 15662.295196716175, 20465.48836279075, 20625.349972002205, 20716.482087947923, 21080.683789743165, 1000.2598077422655, 1004.9547770350351, 1017.8320577104843, 1019.6788011776267, 1019.9847002294972, 10700.401662256136, 11023.44882755859, 11024.283421577571, 11048.895110488887, 11051.778964420666, 582789.086054567, 607821.0, 609127.0, 620380.0, 626493.0, 65685.67157164279, 91150.0, 97672.05215560796, 101409.0, 117202.8279717209, 976.1068622210739, 985.522021819417, 998.6531650620983, 1002.0280328081749, 1008.495752123938, 14587.489437869624, 16597.83815132929, 17417.47747567575, 17500.387486437914, 18160.455186344432, 33537.82617608372, 47189.52810471851, 50358.0, 51166.0, 52913.20630190627, 76532.61733691295, 76623.0, 76763.61618191874, 77931.61034194793, 79806.2019379822, 7780.883286750816, 7980.720674776355, 8047.557384343917, 8278.296344810518, 8563.357748168788, 9471.526423678652, 10119.089281964658, 12607.495700172258, 12910.80633790475, 13161.47354105825, 10975.725606859893, 11175.552977880794, 11303.556972525581, 11306.321620702784, 11464.541418343173, 5254.0, 10363.222758293308, 10553.155747540332, 10999.670009899715, 11324.150688698546, 11200.327980321206, 11213.831792523282, 11557.884421156017, 11803.881961180277, 11941.104417168573, 8287.171282871665, 8298.460600060977, 8325.333973282244, 8442.071372149181, 8717.346199034972, 11490.368029758443, 11520.711982200512, 11537.82693259585, 11779.998700110244, 11843.644690659294], "value": 9520.079259910177, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.22248430816, 1614.3300530279935, 1879.2952642758964, 1979.079727926514, 1994.3119623729808, 8107.067687215972, 8697.956510217447, 8712.172343627346, 8971.282297416208, 9012.459252444849, 8731.781705457366, 8769.429987050837, 8848.203661670448, 8855.335849811274, 8883.777905552364, 2082.8437867159946, 2366.6095094309417, 2392.688950436441, 2408.0247499762622, 2425.63615457681, 2008.337248707923, 2434.2332165367943, 2495.3761559610098, 2496.213692686798, 2499.687539057618, 2462.101333013446, 2469.234537293436, 2477.876106194683, 2490.2778194323623, 2521.936951576216, 2072.4197224777085, 2473.0973194783865, 2473.9609364067137, 2479.3925488255313, 2480.1815400917653, 2406.2781165650235, 2431.1369463840438, 2440.1215562397524, 2443.3769388805918, 2454.2759885833543, 8963.820723585557, 9032.412893161923, 9047.32145089121, 9115.680951166743, 9118.224950879183, 4306.224879521685, 5292.497212764782, 5360.169173778032, 5402.189671549259, 5429.511343979021, 3530.064532898784, 3586.8565257389673, 3604.6395360464007, 3644.523967793048, 3656.542932133483, 1012.3217444312328, 1048.1509976918721, 1049.1344640671477, 1072.9892701073045, 1086.6739978006644, 11463.254888432226, 11465.656030319104, 11633.767324653545, 11645.0, 11667.416629168592, 4693.20215563355, 4779.503791504058, 4893.584045356113, 4906.411230652345, 4932.0, 9977.900220997843, 9997.950010249902, 10048.995100489892, 10053.447060411601, 10080.949595251977, 5429.131338985743, 5433.538149257279, 5433.891322173535, 5468.699221542774, 5493.093639549509, 4361.912761744811, 4968.850934471971, 4968.8757781055765, 4985.853253751585, 4999.650024498323, 3441.6214216436174, 3956.584558621303, 3998.480197574355, 4092.9386059209137, 4134.524529679125, 1165.3648761425043, 1370.5340184337374, 1380.3512349195862, 1384.0865029080758, 1391.8956078293966, 736.2453485177736, 812.2770734046703, 877.7673916412156, 923.9445633261892, 946.8485042393322, 4775.785089670903, 5952.226210592682, 6092.634441933498, 6101.389861013863, 6108.450239478469, 930.3905941608258, 963.8650588917557, 969.520087556664, 989.604158336671, 997.8403455447094, 3713.220223752988, 3952.446657467957, 4010.458588090629, 4024.678025757932, 4088.9386659200136, 8846.646134154562, 9081.68214112503, 10563.102136318344, 10715.303505272132, 10854.240203185702, 10621.415822129702, 10730.678079657622, 10816.351018938889, 10820.56717731282, 10856.511456984297, 7047.365737083687, 7981.760547183594, 7989.480683755537, 8054.556999364974, 8175.918240817635, 5130.435652078343, 5694.231278777312, 6018.157457955802, 6031.065184896348, 6111.663858487648, 9987.60049598008, 10060.195184385086, 10076.748081298027, 10187.7453063674, 10187.7453063674, 9740.366876153028, 10078.949605251926, 10365.274430789772, 10383.428911409945, 10408.0, 6970.546914450544, 7419.851602968019, 7641.2740789624895, 7658.846823063396, 7790.493617914817, 7384.37232835215, 7415.332620064004, 7634.351080158249, 8358.247757701836, 8461.365397595328, 7258.419326453976, 7388.335049845539, 7399.3340599344165, 7484.550926944401, 7563.697452102075, 7278.163011253562, 7348.889766653398, 7357.374623157091, 7360.77917662471, 7452.143003554661, 7115.359617634438, 7189.388901943394, 7214.206437291999, 7220.0613920190035, 7223.2415596361625, 4904.068227036852, 5009.474005229398, 5053.797848086182, 5077.263796749525, 5245.501677340647, 4985.376827896513, 5005.199168133081, 5068.037072956126, 5122.282880396673, 5269.314989051399], "value": 4626.608212335615, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.4326868368254, 324.1458756177472, 330.3113009375452, 331.3968577189515, 332.27398135074856, 5184.481551844819, 6039.305479869817, 6201.844953876144, 6279.874402511947, 6404.580839220935, 5739.397363276858, 6179.735982172309, 6319.431251187392, 6326.905096423558, 6333.524985626073, 4687.601553867926, 4692.090903639748, 4704.929426058612, 4726.432828060638, 4743.786529606159, 4430.357598148266, 4663.27719203524, 4685.695429797052, 4735.526447355254, 4749.786259618309, 4564.0, 4582.129395414877, 4592.74739889306, 4622.930656040146, 4654.790534425959, 4101.302778527657, 4447.510773814894, 4684.320773487842, 4699.459562150362, 4711.905761884777, 3930.7641541507314, 4005.5794141615293, 4005.7796821174825, 4028.2879776095338, 4049.5950404959563, 4878.780454879539, 5182.922256166161, 5799.942000579982, 6016.398360163992, 6024.493876530867, 5486.835394938158, 5489.533389661883, 5510.641808282448, 5514.670983278107, 5516.227728118027, 5746.741396637161, 6439.420452159328, 6619.536632435731, 6662.0, 6663.700133493956, 3617.656322649344, 4464.352668863029, 4480.48474425442, 4481.977590112061, 4503.662225333081, 6217.720202590895, 6687.264400915896, 6750.864982700318, 6775.796726098225, 6997.265287144827, 4099.0, 4216.936745948813, 4353.216421044242, 4605.40129783126, 4619.099275641218, 4680.251159814414, 4689.366935463669, 4690.319903613957, 4695.483496815347, 4714.811407543729, 5275.366955965309, 5356.384015838151, 5438.483344082306, 5485.61600687948, 5520.972395137998, 6042.0332746760305, 6273.65494897785, 6318.240646815138, 6495.415412612888, 6688.331166883273, 1585.8493443122884, 1601.927913243907, 1609.9758503622454, 1611.58904479357, 1617.9352825886833, 1038.5949479702922, 1049.1639630646368, 1060.3054998975683, 1069.5240617925103, 1084.0785332467497, 4898.0, 5201.557867581298, 5221.112410890082, 6178.598391104564, 6371.33101024395, 5180.912008478186, 6464.933286007756, 6815.171703742891, 6886.931130688729, 6969.372756451842, 6000.969995149996, 6196.0, 6207.875842483216, 6232.3144454110015, 6239.095331176954, 3359.4960755886323, 3641.7450778445254, 3665.7983810890655, 3675.9632403676155, 3689.557253129588, 5792.701136838705, 6341.55609107358, 7152.356287934111, 7192.7122915083855, 7277.0, 6238.844028899223, 7042.577445353295, 7096.893546596805, 7136.464765142635, 7151.0, 6065.7876974307455, 6948.791536253921, 7244.637768111574, 7280.745173918887, 7281.490295679358, 5190.039842629126, 6233.0, 6773.848445764331, 6945.548539344926, 6960.756373526902, 4864.391951006124, 6470.644114573555, 6528.563715982476, 6764.661766911733, 6774.525783195129, 6608.579155481594, 7032.753853615099, 7150.284971503016, 7195.424366050808, 7226.421886248982, 3900.4344370065614, 4516.232240519185, 4589.334546490673, 4638.889305366118, 4651.767411629337, 5311.893762124813, 6326.588771729823, 6704.183753903927, 6733.966330168514, 6893.20728116253, 6365.840853978502, 6690.397864192246, 6714.865702686016, 6721.697523611351, 6768.357006084414, 5877.676727780014, 6445.871972404904, 6723.697433615401, 6752.561083529555, 6855.4172895304455, 6565.618706366321, 6573.211214654272, 6577.473802095725, 6587.901181482186, 6616.139901812733, 6706.631135287606, 6722.529422940543, 6732.360425759545, 6755.560888542229, 6825.624590647562, 6276.1213430118905, 6515.837104072436, 6594.439472644879, 6604.028187315823, 6616.073749675145], "value": 4826.308147877945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.458393073922, 670.2627110178803, 691.9792406227814, 746.779699988503, 751.7744676597019, 14489.710205795891, 14706.044107133055, 14936.25318734063, 14946.701065978674, 15075.321610527526, 13867.583972480816, 13879.375428105734, 13915.930420347884, 13952.232627205525, 13959.720805583882, 728.7012324946746, 774.4307933668749, 906.8005038891433, 930.8091841172577, 946.5976959792096, 915.9542022898862, 938.4979036215626, 942.6088173408027, 943.575391074016, 947.6020071569923, 949.5964215208543, 979.4221409368471, 982.4694664880956, 985.8619793228954, 987.1214618989105, 953.2374100719466, 976.838821594436, 979.4319294808994, 982.9459379734112, 982.9606815727398, 693.7779910428665, 699.8425354295243, 712.4371746320401, 720.387670480093, 727.2545640718306, 15006.0, 15019.924900375428, 15024.09855408679, 15288.617784555476, 15323.31045102962, 12634.936825315906, 12714.0, 12714.682132946658, 12783.0, 12920.54778082772, 9948.602055917754, 10204.285700001003, 10215.89784102157, 10289.48552572376, 10443.530041148166, 3372.814495202763, 3481.9477707834403, 3492.7729697569575, 3497.510348551205, 3521.7710848795004, 66933.32666683324, 70116.29883701149, 80365.0, 81571.0, 81707.59146204291, 9051.864222036675, 9252.907470925205, 9269.397489163182, 9441.15029647335, 9538.95230523843, 20675.379738607866, 20680.414948131485, 20721.792782072287, 21025.68461473079, 21183.14027606284, 7268.781936541912, 7342.889856652155, 7349.228331025272, 7603.885941710879, 7691.461597688108, 4002.8198731057173, 4063.553009168989, 4068.816903239361, 4069.654079403225, 4070.2063097695664, 3029.2881172924344, 3538.3454060998797, 3610.6389361063684, 3618.7466877318334, 3619.6380361963593, 557.9665220086727, 640.1837656987378, 653.0758976048933, 654.358728446116, 663.151166506872, 526.7471613625481, 541.8618252345626, 551.4044831581882, 602.0103557741619, 615.8368032471399, 7472.813179670443, 7823.843523129505, 7848.3328917041545, 7915.920840791634, 8534.74395768128, 570.8715539003691, 585.7803323753592, 588.7870985769329, 595.4313630482878, 603.7705671844759, 3717.070732316921, 3848.6728628066367, 3856.686103061132, 3882.708796840192, 3890.5331360236387, 88614.55692721494, 89691.0, 90455.0, 90823.0, 91162.54418727997, 62472.687636562434, 77503.06242343874, 79015.0, 79848.60075699585, 79934.0, 54054.0, 68472.65763671149, 68523.49026842442, 71250.2874971254, 71868.0, 7498.550086994798, 7524.924750752422, 7676.385889128968, 7683.577403242875, 7774.0, 35645.82177089098, 44044.0, 44893.102137957714, 45094.97072631805, 45776.54223457723, 59352.0, 60012.69993650004, 60195.39804601898, 63827.680861597255, 64528.0482035697, 9918.752031199278, 9928.262554053157, 9943.950280248553, 9993.600255989679, 10247.846282305622, 10810.0, 10888.128949684165, 10901.563937442414, 11218.273351860918, 11246.325220486797, 10152.0, 10377.84433233487, 10409.1672666185, 10673.199510036933, 10712.839307410239, 10007.749806254902, 10145.239107067147, 10212.774437176598, 10536.051755342056, 11226.270292430965, 9977.251706121924, 10077.395356278646, 10182.541785619516, 10324.638637647646, 10371.377717336984, 8566.614502347285, 8581.098984606526, 8740.650373985223, 8832.29341652679, 8912.821743565222, 7230.855382892418, 8803.691870784492, 8888.288936885163, 9005.0, 9049.959254685533], "value": 6668.39764637584, "unit": "score"}], "name": "dromaeo_css", "value": 7233.498003165956, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 128.0, 168.0, 168.0, 116.0, 128.0, 117.0, 120.0, 115.0, 116.0], "value": 129.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 121.0, 149.0, 222.0, 121.0, 138.0, 137.0, 123.0, 168.0, 122.0], "value": 145.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 210.0, 229.0, 264.0, 271.0, 243.0, 194.0, 201.0, 201.0, 196.0], "value": 222.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 81.0, 84.0, 150.0, 82.0, 54.0, 63.0, 65.0, 89.0, 57.0], "value": 79.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 87.0, 125.0, 97.0, 119.0, 115.0, 130.0, 78.0, 79.0, 125.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 131.0, 87.0, 75.0, 136.0, 124.0, 89.0, 86.0, 88.0, 88.0], "value": 104.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 96.0, 94.0, 95.0, 96.0, 96.0, 96.0, 94.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 132.0, 96.0, 96.0, 94.0, 95.0, 96.0, 92.0, 93.0, 92.0], "value": 103.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 60.0, 61.0, 63.0, 63.0, 81.0, 64.0, 59.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 70.0, 66.0, 70.0, 67.0, 67.0, 67.0, 66.0, 66.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 86.0, 83.0, 85.0, 85.0, 85.0, 85.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 149.0, 165.0, 164.0, 162.0, 158.0, 140.0, 140.0, 204.0, 197.0], "value": 161.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 215.0, 206.0, 236.0, 230.0, 157.0, 229.0, 232.0, 159.0, 171.0], "value": 201.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 65.0, 87.0, 86.0, 59.0, 71.0, 63.0, 60.0, 93.0], "value": 72.1, "unit": "score"}], "name": "kraken", "value": 1632.5, "alertThreshold": 2.0}]} 12:32:38 INFO - Return code: 0 12:32:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:32:38 ERROR - # TBPL SUCCESS # 12:32:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:32:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:32:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:32:38 INFO - 2016-04-21 12:32:38.785 screenresolution[1683:11150] starting screenresolution argv=screenresolution get 12:32:38 INFO - 2016-04-21 12:32:38.788 screenresolution[1683:11150] Display 0: 1600x1200x32@60 12:32:38 INFO - 2016-04-21 12:32:38.794 screenresolution[1684:11153] starting screenresolution argv=screenresolution list 12:32:38 INFO - Available Modes on Display 0 12:32:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:32:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:32:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:32:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:32:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:32:39 INFO - Intel Iris: 12:32:39 INFO - Chipset Model: Intel Iris 12:32:39 INFO - Type: GPU 12:32:39 INFO - Bus: Built-In 12:32:39 INFO - VRAM (Dynamic, Max): 1536 MB 12:32:39 INFO - Vendor: Intel (0x8086) 12:32:39 INFO - Device ID: 0x0a2e 12:32:39 INFO - Revision ID: 0x0009 12:32:39 INFO - Displays: 12:32:39 INFO - Display: 12:32:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:32:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:32:39 INFO - Display Serial Number: 1600x1200 60 12:32:39 INFO - Main Display: Yes 12:32:39 INFO - Mirror: Off 12:32:39 INFO - Online: Yes 12:32:39 INFO - Rotation: Supported 12:32:39 INFO - Return code: 0 12:32:39 INFO - Running post-action listener: _resource_record_post_action 12:32:39 INFO - Running post-run listener: _resource_record_post_run 12:32:40 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 32079360; Write bytes: 698737152; Read time: 318; Write time: 2263 12:32:40 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381985280; Write bytes: 377059328; Read time: 28458; Write time: 807 12:32:40 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 30322176; Write bytes: 299801088; Read time: 300; Write time: 1406 12:32:40 INFO - Running post-run listener: _upload_blobber_files 12:32:40 INFO - Blob upload gear active. 12:32:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:32:40 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 12:32:40 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'] 12:32:40 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 12:32:40 INFO - (blobuploader) - INFO - Open directory for files ... 12:32:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:32:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:32:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:41 INFO - (blobuploader) - INFO - Done attempting. 12:32:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:32:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:32:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:32:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:32:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:32:42 INFO - (blobuploader) - INFO - Done attempting. 12:32:42 INFO - (blobuploader) - INFO - Iteration through files over. 12:32:42 INFO - Return code: 0 12:32:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:32:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:32:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d7766a6350e0abb973347a9f4f16ca6a40c29426265498774e48144c9516cfd77c5f96a1cf9ee71ee77b7627c2b1d754489bd7fb305101f712604773516c159", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/688e86dc463495a25397a1c18fd9690673a7db2da98ea5fb08878c185de3b66c73be02b61c311f77fc59354b54e0c042f0a0ce4c1f749e7aadd8d83b7dd2d23b"} 12:32:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:32:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:32:42 INFO - Contents: 12:32:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d7766a6350e0abb973347a9f4f16ca6a40c29426265498774e48144c9516cfd77c5f96a1cf9ee71ee77b7627c2b1d754489bd7fb305101f712604773516c159", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/688e86dc463495a25397a1c18fd9690673a7db2da98ea5fb08878c185de3b66c73be02b61c311f77fc59354b54e0c042f0a0ce4c1f749e7aadd8d83b7dd2d23b"} 12:32:42 INFO - Running post-run listener: copy_logs_to_upload_dir 12:32:42 INFO - Copying logs to upload dir... 12:32:42 INFO - mkdir: /builds/slave/test/build/upload/logs 12:32:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.273929 ========= master_lag: 3.87 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-21 12:32:46.383212) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-21 12:32:46.387420) ========= 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.kI1MNlWKli/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.M4vHhB7hSp/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d7766a6350e0abb973347a9f4f16ca6a40c29426265498774e48144c9516cfd77c5f96a1cf9ee71ee77b7627c2b1d754489bd7fb305101f712604773516c159", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/688e86dc463495a25397a1c18fd9690673a7db2da98ea5fb08878c185de3b66c73be02b61c311f77fc59354b54e0c042f0a0ce4c1f749e7aadd8d83b7dd2d23b"} build_url:https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010984 build_url: 'https://queue.taskcluster.net/v1/task/XPVUukcfRBadM1Ho6_wHdg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d7766a6350e0abb973347a9f4f16ca6a40c29426265498774e48144c9516cfd77c5f96a1cf9ee71ee77b7627c2b1d754489bd7fb305101f712604773516c159", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/688e86dc463495a25397a1c18fd9690673a7db2da98ea5fb08878c185de3b66c73be02b61c311f77fc59354b54e0c042f0a0ce4c1f749e7aadd8d83b7dd2d23b"}' ========= master_lag: 1.31 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-21 12:32:47.706114) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 12:32:47.706502) ========= 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.kI1MNlWKli/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.M4vHhB7hSp/Listeners TMPDIR=/var/folders/cg/wrlbrw9n1wqgrfyf5k_pzmxr00000w/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.005362 ========= master_lag: 0.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 12:32:47.884602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 12:32:47.888996) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 12:32:47.913064) ========= ========= Total master_lag: 29.11 =========