builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0136 starttime: 1464426137.42 results: success (0) buildid: 20160528004028 builduid: da1abc8da55c4cd895a18af21495c016 revision: 138121c338c75865135a001fc51f7465e36f4740 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:17.416122) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:17.416528) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:17.416817) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:17.509546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:17.509909) ========= 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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-05-28 02:02:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.0M=0.001s 2016-05-28 02:02:18 (10.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.594218 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:18.125048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:18.125322) ========= 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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.020952 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:18.173740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 02:02:18.174045) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 138121c338c75865135a001fc51f7465e36f4740 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 138121c338c75865135a001fc51f7465e36f4740 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mwkkbBpP7U/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.bYvq8FhKCI/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-05-28 02:02:18,243 truncating revision to first 12 chars 2016-05-28 02:02:18,244 Setting DEBUG logging. 2016-05-28 02:02:18,244 attempt 1/10 2016-05-28 02:02:18,244 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/138121c338c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-28 02:02:19,398 unpacking tar archive at: mozilla-aurora-138121c338c7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.404104 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-28 02:02:19.597404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:19.597719) ========= script_repo_revision: 138121c338c75865135a001fc51f7465e36f4740 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:19.598097) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:19.598361) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-28 02:02:19.617886) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2016-05-28 02:02:19.618265) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mwkkbBpP7U/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.bYvq8FhKCI/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:02:19 INFO - MultiFileLogger online at 20160528 02:02:19 in /builds/slave/test 02:02:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:02:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:02:19 INFO - {'append_to_log': False, 02:02:19 INFO - 'base_work_dir': '/builds/slave/test', 02:02:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:02:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:02:19 INFO - 'branch': 'Mozilla-Aurora', 02:02:19 INFO - 'buildbot_json_path': 'buildprops.json', 02:02:19 INFO - 'config_files': ('talos/mac_config.py',), 02:02:19 INFO - 'default_actions': ('clobber', 02:02:19 INFO - 'read-buildbot-config', 02:02:19 INFO - 'download-and-extract', 02:02:19 INFO - 'populate-webroot', 02:02:19 INFO - 'create-virtualenv', 02:02:19 INFO - 'install', 02:02:19 INFO - 'run-tests'), 02:02:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:02:19 INFO - 'download_minidump_stackwalk': True, 02:02:19 INFO - 'download_symbols': 'ondemand', 02:02:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:02:19 INFO - 'tooltool.py': '/tools/tooltool.py', 02:02:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:02:19 INFO - '/tools/misc-python/virtualenv.py')}, 02:02:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:02:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:02:19 INFO - 'installer_path': 'installer.exe', 02:02:19 INFO - 'log_level': 'info', 02:02:19 INFO - 'log_name': 'talos', 02:02:19 INFO - 'log_to_console': True, 02:02:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:02:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:02:19 INFO - 'opt_config_files': (), 02:02:19 INFO - 'pip_index': False, 02:02:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:02:19 INFO - 'cmd': ('bash', 02:02:19 INFO - '-c', 02:02:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:02:19 INFO - 'enabled': True, 02:02:19 INFO - 'halt_on_failure': False, 02:02:19 INFO - 'name': 'check_screen_resolution'},), 02:02:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:02:19 INFO - 'cmd': ('bash', 02:02:19 INFO - '-c', 02:02:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:02:19 INFO - 'enabled': True, 02:02:19 INFO - 'halt_on_failure': False, 02:02:19 INFO - 'name': 'check_screen_resolution'},), 02:02:19 INFO - 'run_cmd_checks_enabled': True, 02:02:19 INFO - 'sps_profile': False, 02:02:19 INFO - 'sps_profile_interval': 0, 02:02:19 INFO - 'suite': 'dromaeojs-e10s', 02:02:19 INFO - 'system_bits': '32', 02:02:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:02:19 INFO - 'title': 't-yosemite-r7-0136', 02:02:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:02:19 INFO - 'use_talos_json': True, 02:02:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:02:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:02:19 INFO - 'work_dir': 'build'} 02:02:19 INFO - ##### 02:02:19 INFO - ##### Running clobber step. 02:02:19 INFO - ##### 02:02:19 INFO - Running pre-action listener: _resource_record_pre_action 02:02:19 INFO - Running main action method: clobber 02:02:19 INFO - rmtree: /builds/slave/test/build 02:02:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:02:21 INFO - Running post-action listener: _resource_record_post_action 02:02:21 INFO - ##### 02:02:21 INFO - ##### Running read-buildbot-config step. 02:02:21 INFO - ##### 02:02:21 INFO - Running pre-action listener: _resource_record_pre_action 02:02:21 INFO - Running main action method: read_buildbot_config 02:02:21 INFO - Using buildbot properties: 02:02:21 INFO - { 02:02:21 INFO - "project": "", 02:02:21 INFO - "product": "firefox", 02:02:21 INFO - "script_repo_revision": "production", 02:02:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:02:21 INFO - "repository": "", 02:02:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:02:21 INFO - "buildid": "20160528004028", 02:02:21 INFO - "stage_platform": "macosx64", 02:02:21 INFO - "basedir": "/builds/slave/test", 02:02:21 INFO - "nightly_build": "True", 02:02:21 INFO - "buildnumber": 10, 02:02:21 INFO - "slavename": "t-yosemite-r7-0136", 02:02:21 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:02:21 INFO - "platform": "yosemite_r7", 02:02:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:02:21 INFO - "branch": "mozilla-aurora", 02:02:21 INFO - "repo_path": "releases/mozilla-aurora", 02:02:21 INFO - "revision": "138121c338c75865135a001fc51f7465e36f4740", 02:02:21 INFO - "pgo_build": "False", 02:02:21 INFO - "builduid": "da1abc8da55c4cd895a18af21495c016", 02:02:21 INFO - "slavebuilddir": "test" 02:02:21 INFO - } 02:02:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:02:21 INFO - Running post-action listener: _resource_record_post_action 02:02:21 INFO - ##### 02:02:21 INFO - ##### Running download-and-extract step. 02:02:21 INFO - ##### 02:02:21 INFO - Running pre-action listener: _resource_record_pre_action 02:02:21 INFO - Running main action method: download_and_extract 02:02:21 INFO - mkdir: /builds/slave/test/build/tests 02:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:21 INFO - https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:02:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:02:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:02:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:02:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:02:51 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 02:02:51 INFO - retry: attempt #1 caught exception: timed out 02:02:51 INFO - retry: Failed, sleeping 30 seconds before retrying 02:03:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 02:03:29 INFO - Downloaded 1199 bytes. 02:03:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:03:29 INFO - Using the following test package requirements: 02:03:29 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:03:29 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:29 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:03:29 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:29 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:03:29 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:29 INFO - u'jsshell-mac.zip'], 02:03:29 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:29 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:03:29 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:03:29 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:29 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:03:29 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:29 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:03:29 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:29 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:03:29 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:03:29 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:03:29 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:29 INFO - https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:03:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:03:31 INFO - Downloaded 18425625 bytes. 02:03:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:03:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:03:34 INFO - Return code: 0 02:03:34 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:03:34 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')]} 02:03:34 INFO - https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:03:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:03:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:03:37 INFO - Downloaded 18425625 bytes. 02:03:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:03:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:03:40 INFO - Return code: 0 02:03: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')]} 02:03:40 INFO - https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:03:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:03:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:03:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:03:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:03:42 INFO - Downloaded 10840145 bytes. 02:03:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:03:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:03:42 INFO - Return code: 0 02:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:42 INFO - https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:03:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:03:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:03:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:03:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:03:49 INFO - Downloaded 114231254 bytes. 02:03:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:03:49 INFO - mkdir: /builds/slave/test/properties 02:03:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:03:49 INFO - Writing to file /builds/slave/test/properties/build_url 02:03:49 INFO - Contents: 02:03:49 INFO - build_url:https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:03:50 INFO - Running post-action listener: _resource_record_post_action 02:03:50 INFO - Running post-action listener: set_extra_try_arguments 02:03:50 INFO - ##### 02:03:50 INFO - ##### Running populate-webroot step. 02:03:50 INFO - ##### 02:03:50 INFO - Running pre-action listener: _resource_record_pre_action 02:03:50 INFO - Running main action method: populate_webroot 02:03:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:03:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:03:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:03:50 INFO - '--tppagecycles', 02:03:50 INFO - '7'], 02:03:50 INFO - 'tests': ['tsvgm']}}, 02:03:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:03:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:03:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:03:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:03:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:50 INFO - 'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:03:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:03:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:50 INFO - 'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['damp', 'tps']}, 02:03:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:50 INFO - 'tests': ['damp', 'tps']}, 02:03:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['dromaeo_dom']}, 02:03:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:03:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['a11yr', 02:03:50 INFO - 'ts_paint', 02:03:50 INFO - 'tpaint', 02:03:50 INFO - 'sessionrestore', 02:03:50 INFO - 'sessionrestore_no_auto_restore', 02:03:50 INFO - 'tabpaint']}, 02:03:50 INFO - 'other-e10s': {'tests': ['a11yr', 02:03:50 INFO - 'ts_paint', 02:03:50 INFO - 'tpaint', 02:03:50 INFO - 'sessionrestore', 02:03:50 INFO - 'sessionrestore_no_auto_restore', 02:03:50 INFO - 'tabpaint']}, 02:03:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:03:50 INFO - 'ts_paint', 02:03:50 INFO - 'tpaint', 02:03:50 INFO - 'sessionrestore', 02:03:50 INFO - 'sessionrestore_no_auto_restore', 02:03:50 INFO - 'tabpaint']}, 02:03:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:03:50 INFO - 'ts_paint', 02:03:50 INFO - 'tpaint', 02:03:50 INFO - 'sessionrestore', 02:03:50 INFO - 'sessionrestore_no_auto_restore', 02:03:50 INFO - 'tabpaint']}, 02:03:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['a11yr', 02:03:50 INFO - 'ts_paint', 02:03:50 INFO - 'tpaint', 02:03:50 INFO - 'sessionrestore', 02:03:50 INFO - 'sessionrestore_no_auto_restore', 02:03:50 INFO - 'tabpaint']}, 02:03:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['a11yr', 02:03:50 INFO - 'ts_paint', 02:03:50 INFO - 'tpaint', 02:03:50 INFO - 'sessionrestore', 02:03:50 INFO - 'sessionrestore_no_auto_restore', 02:03:50 INFO - 'tabpaint']}, 02:03:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['tsvgx', 02:03:50 INFO - 'tsvgr_opacity', 02:03:50 INFO - 'tart', 02:03:50 INFO - 'tscrollx', 02:03:50 INFO - 'cart']}, 02:03:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:03:50 INFO - 'tsvgr_opacity', 02:03:50 INFO - 'tart', 02:03:50 INFO - 'tscrollx', 02:03:50 INFO - 'cart']}, 02:03:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:50 INFO - 'talos_options': ['--disable-e10s'], 02:03:50 INFO - 'tests': ['tp5o']}, 02:03:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:50 INFO - 'tests': ['tp5o']}, 02:03:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:50 INFO - 'talos_options': ['--disable-e10s', 02:03:50 INFO - '--xperf_path', 02:03:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:50 INFO - 'tests': ['tp5n']}, 02:03:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:03:50 INFO - 'talos_options': ['--xperf_path', 02:03:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:50 INFO - 'tests': ['tp5n']}}, 02:03:50 INFO - 'talos.zip': {'path': '', 02:03:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:03:50 INFO - Running post-action listener: _resource_record_post_action 02:03:50 INFO - ##### 02:03:50 INFO - ##### Running create-virtualenv step. 02:03:50 INFO - ##### 02:03:50 INFO - Running pre-action listener: _resource_record_pre_action 02:03:50 INFO - Running main action method: create_virtualenv 02:03:50 INFO - Creating virtualenv /builds/slave/test/build/venv 02:03:50 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 02:03:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:03:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:03:50 INFO - Using real prefix '/tools/python27' 02:03:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:03:51 INFO - Installing distribute.............................................................................................................................................................................................done. 02:03:53 INFO - Installing pip.................done. 02:03:53 INFO - Return code: 0 02:03:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:03:53 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')]} 02:03:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:53 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')]} 02:03:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:53 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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:03:53 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 02:03:53 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 02:03:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:03:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:53 INFO - 'HOME': '/Users/cltbld', 02:03:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:53 INFO - 'LOGNAME': 'cltbld', 02:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:53 INFO - 'MOZ_NO_REMOTE': '1', 02:03:53 INFO - 'NO_EM_RESTART': '1', 02:03:53 INFO - 'PAGER': '/bin/cat', 02:03:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:53 INFO - 'PWD': '/builds/slave/test', 02:03:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:53 INFO - 'SHELL': '/bin/bash', 02:03:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:03:53 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:03:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:53 INFO - 'USER': 'cltbld', 02:03:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:53 INFO - 'XPC_FLAGS': '0x0', 02:03:53 INFO - 'XPC_SERVICE_NAME': '0', 02:03:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:54 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 02:03:54 INFO - Cleaning up... 02:03:54 INFO - Return code: 0 02:03:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:54 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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:03:54 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 02:03:54 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 02:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:54 INFO - 'HOME': '/Users/cltbld', 02:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:54 INFO - 'LOGNAME': 'cltbld', 02:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:54 INFO - 'MOZ_NO_REMOTE': '1', 02:03:54 INFO - 'NO_EM_RESTART': '1', 02:03:54 INFO - 'PAGER': '/bin/cat', 02:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:54 INFO - 'PWD': '/builds/slave/test', 02:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:54 INFO - 'SHELL': '/bin/bash', 02:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:03:54 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:54 INFO - 'USER': 'cltbld', 02:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:54 INFO - 'XPC_FLAGS': '0x0', 02:03:54 INFO - 'XPC_SERVICE_NAME': '0', 02:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:54 INFO - Downloading/unpacking psutil>=0.7.1 02:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:03:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:03:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:03:57 INFO - Installing collected packages: psutil 02:03:57 INFO - Running setup.py install for psutil 02:03:57 INFO - building 'psutil._psutil_osx' extension 02:03:57 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 02:03:58 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 02:03:58 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 02:03:58 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 02:03:58 INFO - building 'psutil._psutil_posix' extension 02:03:58 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 02:03:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:03:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:03:58 INFO - ^ 02:03:58 INFO - 1 warning generated. 02:03:58 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 02:03:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:03:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:03:58 INFO - Successfully installed psutil 02:03:58 INFO - Cleaning up... 02:03:58 INFO - Return code: 0 02:03:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:03:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:03:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:03:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:03:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:03:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:58 INFO - 'HOME': '/Users/cltbld', 02:03:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:58 INFO - 'LOGNAME': 'cltbld', 02:03:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:58 INFO - 'MOZ_NO_REMOTE': '1', 02:03:58 INFO - 'NO_EM_RESTART': '1', 02:03:58 INFO - 'PAGER': '/bin/cat', 02:03:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:58 INFO - 'PWD': '/builds/slave/test', 02:03:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:58 INFO - 'SHELL': '/bin/bash', 02:03:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:03:58 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:03:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:58 INFO - 'USER': 'cltbld', 02:03:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:58 INFO - 'XPC_FLAGS': '0x0', 02:03:58 INFO - 'XPC_SERVICE_NAME': '0', 02:03:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:03:58 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 02:03:58 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 02:03:58 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 02:03:58 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 02:03:58 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 02:03:58 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 02:04:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:04:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:04:01 INFO - Installing collected packages: mozsystemmonitor 02:04:01 INFO - Running setup.py install for mozsystemmonitor 02:04:01 INFO - Successfully installed mozsystemmonitor 02:04:01 INFO - Cleaning up... 02:04:01 INFO - Return code: 0 02:04:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:04: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')]} 02:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04: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')]} 02:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04: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', '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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:04: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:04: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 blobuploader==1.2.4 02:04:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:04:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:01 INFO - 'HOME': '/Users/cltbld', 02:04:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:01 INFO - 'LOGNAME': 'cltbld', 02:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:01 INFO - 'MOZ_NO_REMOTE': '1', 02:04:01 INFO - 'NO_EM_RESTART': '1', 02:04:01 INFO - 'PAGER': '/bin/cat', 02:04:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:01 INFO - 'PWD': '/builds/slave/test', 02:04:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:01 INFO - 'SHELL': '/bin/bash', 02:04:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:04:01 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:04:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:01 INFO - 'USER': 'cltbld', 02:04:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:01 INFO - 'XPC_FLAGS': '0x0', 02:04:01 INFO - 'XPC_SERVICE_NAME': '0', 02:04:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:01 INFO - Downloading/unpacking blobuploader==1.2.4 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04:04 INFO - Downloading blobuploader-1.2.4.tar.gz 02:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:04:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:04:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04:05 INFO - Downloading docopt-0.6.1.tar.gz 02:04:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:04:05 INFO - Installing collected packages: blobuploader, requests, docopt 02:04:05 INFO - Running setup.py install for blobuploader 02:04:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:04:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:04:05 INFO - Running setup.py install for requests 02:04:06 INFO - Running setup.py install for docopt 02:04:06 INFO - Successfully installed blobuploader requests docopt 02:04:06 INFO - Cleaning up... 02:04:06 INFO - Return code: 0 02:04:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04:06 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')]} 02:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:06 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')]} 02:04:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:06 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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:04:06 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 02:04:06 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 02:04:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:04:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:06 INFO - 'HOME': '/Users/cltbld', 02:04:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:06 INFO - 'LOGNAME': 'cltbld', 02:04:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:06 INFO - 'MOZ_NO_REMOTE': '1', 02:04:06 INFO - 'NO_EM_RESTART': '1', 02:04:06 INFO - 'PAGER': '/bin/cat', 02:04:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:06 INFO - 'PWD': '/builds/slave/test', 02:04:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:06 INFO - 'SHELL': '/bin/bash', 02:04:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:04:06 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:04:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:06 INFO - 'USER': 'cltbld', 02:04:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:06 INFO - 'XPC_FLAGS': '0x0', 02:04:06 INFO - 'XPC_SERVICE_NAME': '0', 02:04:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:04:06 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-LCMYkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:04:06 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-TBuEOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:04:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:04:06 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-c0mDge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-xicSw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-pGI3gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-nsBMKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-zotjs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-_s1W74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-P7pJ01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-EQ7qR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-gS9xqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:04:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:04:07 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-NvhZry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:04:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-zUIaSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:04:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-WjafZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-plBW9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:04:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-EI1bY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:04:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:04:08 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ErEdWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:04:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:04:08 INFO - Running setup.py install for manifestparser 02:04:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:04:08 INFO - Running setup.py install for mozcrash 02:04:08 INFO - Running setup.py install for mozdebug 02:04:08 INFO - Running setup.py install for mozdevice 02:04:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:04:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:04:09 INFO - Running setup.py install for mozfile 02:04:09 INFO - Running setup.py install for mozhttpd 02:04:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:04:09 INFO - Running setup.py install for mozinfo 02:04:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:04:09 INFO - Running setup.py install for mozInstall 02:04:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:04:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:04:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:04:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:04:09 INFO - Running setup.py install for mozleak 02:04:09 INFO - Running setup.py install for mozlog 02:04:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:04:10 INFO - Running setup.py install for moznetwork 02:04:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:04:10 INFO - Running setup.py install for mozprocess 02:04:10 INFO - Running setup.py install for mozprofile 02:04:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:04:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:04:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:04:10 INFO - Running setup.py install for mozrunner 02:04:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:04:10 INFO - Running setup.py install for mozscreenshot 02:04:10 INFO - Running setup.py install for moztest 02:04:10 INFO - Running setup.py install for mozversion 02:04:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:04:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:04:11 INFO - Cleaning up... 02:04:11 INFO - Return code: 0 02:04:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:11 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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:04:11 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 02:04:11 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 02:04:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:04:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:11 INFO - 'HOME': '/Users/cltbld', 02:04:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:11 INFO - 'LOGNAME': 'cltbld', 02:04:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:11 INFO - 'MOZ_NO_REMOTE': '1', 02:04:11 INFO - 'NO_EM_RESTART': '1', 02:04:11 INFO - 'PAGER': '/bin/cat', 02:04:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:11 INFO - 'PWD': '/builds/slave/test', 02:04:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:11 INFO - 'SHELL': '/bin/bash', 02:04:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:04:11 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:04:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:11 INFO - 'USER': 'cltbld', 02:04:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:11 INFO - 'XPC_FLAGS': '0x0', 02:04:11 INFO - 'XPC_SERVICE_NAME': '0', 02:04:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:04:11 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-qdLraP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:04:11 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)) 02:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:04:11 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-aWYLkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:04:11 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)) 02:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:04:11 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-6NKQCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:04:11 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)) 02:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:04:11 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-p1AGKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:04:11 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)) 02:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:04:11 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-CHBb8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:04:11 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)) 02:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:04:11 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-bmSVca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-nl0ZnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-zVjr12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-asz4zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-CbDUlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-YZSwet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-nSciPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-7WLtHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-PNMCFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-871A4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:04:12 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)) 02:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:04:12 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-r6KuAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:04:13 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)) 02:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:04:13 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Xo0RUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:04:13 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)) 02:04:13 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)) 02:04:13 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)) 02:04:13 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)) 02:04:13 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)) 02:04:13 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)) 02:04:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:15 INFO - Downloading blessings-1.6.tar.gz 02:04:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:04:15 INFO - Installing collected packages: blessings 02:04:15 INFO - Running setup.py install for blessings 02:04:15 INFO - Successfully installed blessings 02:04:15 INFO - Cleaning up... 02:04:15 INFO - Return code: 0 02:04:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:04:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:04:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:04:16 INFO - Reading from file tmpfile_stdout 02:04:16 INFO - Current package versions: 02:04:16 INFO - blessings == 1.6 02:04:16 INFO - blobuploader == 1.2.4 02:04:16 INFO - docopt == 0.6.1 02:04:16 INFO - manifestparser == 1.1 02:04:16 INFO - mozInstall == 1.12 02:04:16 INFO - mozcrash == 0.17 02:04:16 INFO - mozdebug == 0.1 02:04:16 INFO - mozdevice == 0.48 02:04:16 INFO - mozfile == 1.2 02:04:16 INFO - mozhttpd == 0.7 02:04:16 INFO - mozinfo == 0.9 02:04:16 INFO - mozleak == 0.1 02:04:16 INFO - mozlog == 3.1 02:04:16 INFO - moznetwork == 0.27 02:04:16 INFO - mozprocess == 0.22 02:04:16 INFO - mozprofile == 0.28 02:04:16 INFO - mozrunner == 6.11 02:04:16 INFO - mozscreenshot == 0.1 02:04:16 INFO - mozsystemmonitor == 0.0 02:04:16 INFO - moztest == 0.7 02:04:16 INFO - mozversion == 1.4 02:04:16 INFO - psutil == 3.1.1 02:04:16 INFO - requests == 1.2.3 02:04:16 INFO - wsgiref == 0.1.2 02:04:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:04:16 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 02:04:16 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 02:04:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:04:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:16 INFO - 'HOME': '/Users/cltbld', 02:04:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:16 INFO - 'LOGNAME': 'cltbld', 02:04:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:16 INFO - 'MOZ_NO_REMOTE': '1', 02:04:16 INFO - 'NO_EM_RESTART': '1', 02:04:16 INFO - 'PAGER': '/bin/cat', 02:04:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:16 INFO - 'PWD': '/builds/slave/test', 02:04:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:16 INFO - 'SHELL': '/bin/bash', 02:04:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:04:16 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:04:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:16 INFO - 'USER': 'cltbld', 02:04:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:16 INFO - 'XPC_FLAGS': '0x0', 02:04:16 INFO - 'XPC_SERVICE_NAME': '0', 02:04:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 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)) 02:04:16 INFO - Cleaning up... 02:04:16 INFO - Return code: 0 02:04:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:16 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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:04:16 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 02:04:16 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 02:04:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:04:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:16 INFO - 'HOME': '/Users/cltbld', 02:04:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:16 INFO - 'LOGNAME': 'cltbld', 02:04:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:16 INFO - 'MOZ_NO_REMOTE': '1', 02:04:16 INFO - 'NO_EM_RESTART': '1', 02:04:16 INFO - 'PAGER': '/bin/cat', 02:04:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:16 INFO - 'PWD': '/builds/slave/test', 02:04:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:16 INFO - 'SHELL': '/bin/bash', 02:04:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:04:16 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:04:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:16 INFO - 'USER': 'cltbld', 02:04:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:16 INFO - 'XPC_FLAGS': '0x0', 02:04:16 INFO - 'XPC_SERVICE_NAME': '0', 02:04:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:16 INFO - Downloading/unpacking jsonschema 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:04:19 INFO - Downloading/unpacking functools32 (from jsonschema) 02:04:19 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 02:04:19 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 02:04:19 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 02:04:19 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 02:04:19 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 02:04:19 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 02:04:19 INFO - Downloading functools32-3.2.3-2.tar.gz 02:04:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:04:19 INFO - warning: no files found matching '*.txt' 02:04:19 INFO - no previously-included directories found matching 'build' 02:04:19 INFO - no previously-included directories found matching 'dist' 02:04:19 INFO - no previously-included directories found matching '.git*' 02:04:19 INFO - Installing collected packages: jsonschema, functools32 02:04:19 INFO - Running setup.py install for functools32 02:04:19 INFO - warning: no files found matching '*.txt' 02:04:19 INFO - no previously-included directories found matching 'build' 02:04:19 INFO - no previously-included directories found matching 'dist' 02:04:19 INFO - no previously-included directories found matching '.git*' 02:04:19 INFO - Successfully installed jsonschema functools32 02:04:19 INFO - Cleaning up... 02:04:20 INFO - Return code: 0 02:04:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:04:20 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')]} 02:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:04:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:20 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')]} 02:04:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:04:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:04:20 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 0x109d08a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d18420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab32480bc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, '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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 02:04:20 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 02:04:20 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 02:04:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:04:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:20 INFO - 'HOME': '/Users/cltbld', 02:04:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:20 INFO - 'LOGNAME': 'cltbld', 02:04:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:20 INFO - 'MOZ_NO_REMOTE': '1', 02:04:20 INFO - 'NO_EM_RESTART': '1', 02:04:20 INFO - 'PAGER': '/bin/cat', 02:04:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:20 INFO - 'PWD': '/builds/slave/test', 02:04:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:20 INFO - 'SHELL': '/bin/bash', 02:04:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:04:20 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:04:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:20 INFO - 'USER': 'cltbld', 02:04:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:20 INFO - 'XPC_FLAGS': '0x0', 02:04:20 INFO - 'XPC_SERVICE_NAME': '0', 02:04:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:04:20 INFO - Downloading/unpacking flake8 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:04:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:04:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:04:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:04:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:04:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04: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 02:04:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:04:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:04:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:04:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:04:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:04:24 INFO - no previously-included directories found matching 'docs/_build' 02:04:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:04:24 INFO - Running setup.py install for pep8 02:04:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:04:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:04:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:04:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:04:24 INFO - no previously-included directories found matching 'docs/_build' 02:04:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:04:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:04:24 INFO - Cleaning up... 02:04:24 INFO - Return code: 0 02:04:24 INFO - Running post-action listener: _resource_record_post_action 02:04:24 INFO - Running post-action listener: _start_resource_monitoring 02:04:24 INFO - Starting resource monitoring. 02:04:24 INFO - ##### 02:04:24 INFO - ##### Running install step. 02:04:24 INFO - ##### 02:04:24 INFO - Running pre-action listener: _resource_record_pre_action 02:04:24 INFO - Running main action method: install 02:04:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:04:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:04:24 INFO - Reading from file tmpfile_stdout 02:04:24 INFO - Detecting whether we're running mozinstall >=1.0... 02:04:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:04:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:04:24 INFO - Reading from file tmpfile_stdout 02:04:24 INFO - Output received: 02:04:24 INFO - Usage: mozinstall [options] installer 02:04:24 INFO - Options: 02:04:24 INFO - -h, --help show this help message and exit 02:04:24 INFO - -d DEST, --destination=DEST 02:04:24 INFO - Directory to install application into. [default: 02:04:24 INFO - "/builds/slave/test"] 02:04:24 INFO - --app=APP Application being installed. [default: firefox] 02:04:24 INFO - mkdir: /builds/slave/test/build/application 02:04:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:04:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:04:56 INFO - Reading from file tmpfile_stdout 02:04:56 INFO - Output received: 02:04:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:04:56 INFO - Running post-action listener: _resource_record_post_action 02:04:56 INFO - ##### 02:04:56 INFO - ##### Running run-tests step. 02:04:56 INFO - ##### 02:04:56 INFO - Running pre-action listener: _resource_record_pre_action 02:04:56 INFO - Running main action method: run_tests 02:04:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:04:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:04:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:04:56 INFO - 2016-05-28 02:04:56.825 screenresolution[2285:25591] starting screenresolution argv=screenresolution get 02:04:56 INFO - 2016-05-28 02:04:56.827 screenresolution[2285:25591] Display 0: 1600x1200x32@60 02:04:56 INFO - 2016-05-28 02:04:56.833 screenresolution[2286:25594] starting screenresolution argv=screenresolution list 02:04:56 INFO - Available Modes on Display 0 02:04:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:04:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:04:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:04:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:04:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:04:57 INFO - Intel Iris: 02:04:57 INFO - Chipset Model: Intel Iris 02:04:57 INFO - Type: GPU 02:04:57 INFO - Bus: Built-In 02:04:57 INFO - VRAM (Dynamic, Max): 1536 MB 02:04:57 INFO - Vendor: Intel (0x8086) 02:04:57 INFO - Device ID: 0x0a2e 02:04:57 INFO - Revision ID: 0x0009 02:04:57 INFO - Displays: 02:04:57 INFO - Display: 02:04:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:04:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:04:57 INFO - Display Serial Number: 1600x1200 60 02:04:57 INFO - Main Display: Yes 02:04:57 INFO - Mirror: Off 02:04:57 INFO - Online: Yes 02:04:57 INFO - Rotation: Supported 02:04:57 INFO - Return code: 0 02:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:04:57 INFO - Python 2.7.3 02:04:57 INFO - Return code: 0 02:04:57 INFO - grabbing minidump binary from tooltool 02:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:04:57 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 0x109ba7e00>, '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 0x109a10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109ba2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:04:57 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 02:04:57 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 02:04:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:04:57 INFO - Return code: 0 02:04:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:04:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:04:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:04:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:04:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:04:58 INFO - Return code: 0 02:04:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--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 02:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0136 --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 02:04:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mwkkbBpP7U/Render', 02:04:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:58 INFO - 'HOME': '/Users/cltbld', 02:04:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:58 INFO - 'LOGNAME': 'cltbld', 02:04:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:04:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:58 INFO - 'MOZ_NO_REMOTE': '1', 02:04:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:04:58 INFO - 'NO_EM_RESTART': '1', 02:04:58 INFO - 'PAGER': '/bin/cat', 02:04:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:58 INFO - 'PWD': '/builds/slave/test', 02:04:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:04:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:58 INFO - 'SHELL': '/bin/bash', 02:04:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYvq8FhKCI/Listeners', 02:04:58 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 02:04:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:58 INFO - 'USER': 'cltbld', 02:04:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:58 INFO - 'XPC_FLAGS': '0x0', 02:04:58 INFO - 'XPC_SERVICE_NAME': '0', 02:04:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--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 02:04:58 INFO - mozversion application_buildid: 20160528004028 02:04:58 INFO - mozversion application_changeset: 138121c338c75865135a001fc51f7465e36f4740 02:04:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:04:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:04:58 INFO - mozversion application_name: Firefox 02:04:58 INFO - mozversion application_remotingname: firefox-dev 02:04:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:04:58 INFO - mozversion application_vendor: Mozilla 02:04:58 INFO - mozversion application_version: 48.0a2 02:04:58 INFO - mozversion platform_buildid: 20160528004028 02:04:58 INFO - mozversion platform_changeset: 138121c338c75865135a001fc51f7465e36f4740 02:04:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:04:58 INFO - mozversion platform_version: 48.0a2 02:04:58 INFO - using testdate: 1464426298 02:04:58 INFO - actual date: 1464426298 02:04:58 INFO - starting webserver on 'localhost:53166' 02:04:58 INFO - SUITE-START | Running 2 tests 02:04:58 INFO - TEST-START | dromaeo_css 02:04:58 INFO - operating with platform_type : mac_ 02:04:58 INFO - Initialising browser for dromaeo_css test... 02:04:58 INFO - TEST-INFO | started process 2304 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpUxk8gh/profile http://localhost:53166/getInfo.html) 02:05:01 INFO - PROCESS | 2304 | __metrics Screen width/height:1600/1200 02:05:01 INFO - PROCESS | 2304 | colorDepth:24 02:05:01 INFO - PROCESS | 2304 | Browser inner width/height: 1024/689 02:05:01 INFO - PROCESS | 2304 | __metrics 02:05:01 ERROR - PROCESS | 2304 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 02:05:02 INFO - TEST-INFO | 2304: exit 0 02:05:02 INFO - Browser initialized. 02:05:02 INFO - Running cycle 1/1 for dromaeo_css test... 02:05:02 INFO - TEST-INFO | started process 2308 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpUxk8gh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:05:14 INFO - PROCESS | 2308 | RSS: Main: 221241344 02:05:14 INFO - PROCESS | 2308 | 02:06:03 INFO - PROCESS | 2308 | 1464426363788 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] 02:08:15 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:53166/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53166/tests/dromaeo/cssquery-ext.html) 02:08:16 INFO - PROCESS | 2308 | RSS: Main: 231182336 02:08:16 INFO - PROCESS | 2308 | 02:11:18 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:53166/tests/dromaeo/cssquery-ext.html (next: http://localhost:53166/tests/dromaeo/cssquery-jquery.html) 02:11:19 INFO - PROCESS | 2308 | RSS: Main: 243040256 02:11:19 INFO - PROCESS | 2308 | 02:14:20 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:53166/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53166/tests/dromaeo/cssquery-mootools.html) 02:14:21 INFO - PROCESS | 2308 | RSS: Main: 245035008 02:14:21 INFO - PROCESS | 2308 | 02:17:23 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:53166/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53166/tests/dromaeo/cssquery-prototype.html) 02:17:23 INFO - PROCESS | 2308 | RSS: Main: 247521280 02:17:23 INFO - PROCESS | 2308 | 02:20:26 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:53166/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53166/tests/dromaeo/cssquery-yui.html) 02:20:27 INFO - PROCESS | 2308 | RSS: Main: 249540608 02:20:27 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:53166/tests/dromaeo/cssquery-yui.html (next: http://localhost:53166/tests/dromaeo/cssquery-dojo.html) 02:23:29 INFO - PROCESS | 2308 | RSS: Main: 250810368 02:23:29 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | __start_tp_report 02:23:29 INFO - PROCESS | 2308 | _x_x_mozilla_page_load 02:23:29 INFO - PROCESS | 2308 | _x_x_mozilla_page_load_details 02:23:29 INFO - PROCESS | 2308 | |i|pagename|runs| 02:23:29 INFO - PROCESS | 2308 | |0;dojo.html;3431.2279737059157;3446.276281980784;3464.2725027744173;3481.3907566175913;3522.894313170604;5009.499050094989;5590.909772594346;5771.394003629616;5789.102689083194;5793.957087724213;5683.829485115442;5753.942460575393;5782.045962416196;5824;5829.358770535248;4370.4099946507295;5211.3746350438005;5241.5806735461265;5299.788008479656;5334.653247538917;4141.7514949103;5187.0922588546855;5190.636655434121;5313.574914006889;5375.301210842585;5234.057869583474;5252.632315737881;5253.8949221015555;5256.237845512417;5278.3665960084845;5209.374875015003;5240.161574148137;5255.658382205144;5263.105272103751;5264.473552644705;5107;5210.687358758449;5222.399424066243;5229.215617657382;5238.764255608507;5783.103618939072;5804.912926306109;5825.708714564297;5874.559408044371;5909.379515150933;6052.697365131726;6177.042558403455;6210.627362358273;6224.470919971808;6241.438270555626;6142.560166306883;6183.567150299481;6230.844228894269;6232.439080482778;6247;5909.497692696126;5959.523238140939;5961.254843144607;5979.1031345298115;6042.063480160582;6323.430891219812;6329.082283068929;6348.746050157942;6351.777687780906;6379.64911929839;5460.153676180198;6125.082842094287;6152.415520525543;6214.25428948529;6228.688565571703;6144.463884028993;6187.969060154671;6190;6191.938080619136;6219.595726277777;6189.597676151036;6217.875642487125;6221.129041934042;6223;6233.3143354231;5390.595705322116;6124.960759790135;6208.658523781235;6221.782237621662;6247.219097612799;3835.9424608630893;4944.456109827916;4978.153713868651;4979.701217926936;5001.8499445016705;4327.6105150536605;4453.35426363182;4479.955200448019;4517.232070548015;4543.954560454419;5733.799317023884;5790.594658373873;5795.623284486495;5819.970900145557;5835.5915085944425;6242.375762423812;6253.1245625612455;6264.906026409608;6289.0880822280515;6304.3695630437505;4916.434610019822;5669.943300567024;5837.416258374214;5862;5887.175795388649;5292.629515933924;5352.143657014859;5399.406065332811;5409.918851217235;5473.343198816088;5447.700376479332;6419.679016049179;6446.097546343516;6491.1236983008575;6506.707198176188;6124.106319906264;6194.690265486798;6267.905981410191;6296.874062518816;6308.589941653778;6394.872102558016;6439.195100612424;6456.967715161394;6460.160179176677;6483.351664833479;6224.097505861534;6230.003199488241;6256.499480041494;6258.217722784652;6279.372062793684;6128.754849805958;6147.754089836357;6208.348123446973;6266.060090986206;6278.46633036197;5266.394364648115;6162.26052873658;6174.567780255339;6182.381761823782;6205.193324867919;6093.390660933871;6161.661108638888;6178.814635560941;6179.907301390393;6180.6291622502795;5141.460146684544;5778.913316300343;5915.467607915309;5938.881222375442;5975.581709280483;6109.236345456818;6134.478569321657;6158.107074474266;6161.168242287413;6175.475084617858;6056.636601803906;6062.42406971337;6093.146959425595;6093.36019717941;6116.235470566179 02:23:29 INFO - PROCESS | 2308 | |1;ext.html;498.2227724749391;512.671889990406;523.4085483403754;533.7064614462047;534.9010433069885;37450.62549374506;38532.422013669784;38626.61373386265;38635.61364386355;38750.41874371883;40011.19977600446;40320;40560;40643.59356406435;40793;9369.437833729964;9589.568469418859;9599.32804703671;9609;9617.75955601112;9437.009114042996;9630.614775408976;9663;9713.417194968324;9720.61117555297;9614.951925240364;9682.370645907993;9713.708588742315;9723.513824308791;9723.611055557769;9615.615375384976;9662.855057174149;9663.661771838022;9684.70945871625;9717.979612140694;9599.232061435068;9702.175315098155;9733.756656083655;9747.025297470267;9748.26887983397;39052.60947390547;39474;39502.802485987384;39514.60485395138;39716.80141599273;2753.9449211015667;3021.4410334088266;3144.795588286754;3248.4640034394297;3275.525048867925;25663.27215923506;26069.60895586568;26086.608700869503;26871;27685.861570692217;2616.5813469844925;2705.553583658694;2718.687350954646;2724.4823483538066;2775.5559110542417;318736;318805;319655.40172299225;321215;321545;33817.492737608954;35336.93989180329;35422.46866297007;36191.63808361936;36398;33946.83026584851;36086.458703119475;36138;36346.8182659085;36690;11802.586909458129;12827.807582886264;15267.854910881728;15336.003159794578;15392.692146157015;12332.013438924865;12393.62819115428;12460.81308780369;12464.688382790502;12485.188462749893;8156.2659360656635;8161.469504482189;8173.386995975326;8230.958845205736;8270.2556769891;2703.6890757562737;3167.889123880699;3179.5071763876635;3453.4647129694936;3469.9479507807405;900.9324300677475;1002.5688953749811;1014.7209517382779;1053.0259509953262;1120.926103194638;29961.25096872552;30161.396772064876;30263;30749.69250307513;30792.846035770126;1079.32002838213;1085.514172544879;1142.2860712054965;1151.8848115188414;1212.678640160358;14646.707065858624;15527.844721552865;15601.687966240612;15682.059076455449;15897.205139742966;595002;599206;602467;605418;607057;45097.54902450999;53494.73252633712;60750.696246518484;60791;60983;1490.6869557392852;1507.1635242440332;1508.2308022908396;1508.9773653395207;1525.6033431307717;24471.96721229636;30048.849755751082;30766.384672306875;32148.678513215506;32488.675113248566;58219.4178058214;58260.4173958255;58798.70600646969;59234.70382648231;59420.405795941486;573087;575200;579073;579297;582274;42632;43104.56895431005;43305.13389732251;43834.12331753283;43961.34057989069;46528.767356163;46622.53377466182;46758.53241467542;47042.29436558386;47532;38323;38438.23123537569;39112.80443597764;39265;39477.605223947394;30141;37809.10954452321;37928.241435171694;38456.615433845305;38979.610203897595;31127.688723113384;37525.061873453385;38193.23613527769;38601.03497412587;38682.22635547329;33323.83338083294;33608;34266.31467370688;34282.314353713286;34328.48507272343;25544.61683074718;25641.871790640926;25651.35871603225;25797.484050319264;26274.737252627994 02:23:29 INFO - PROCESS | 2308 | |2;jquery.html;352.88002079293034;393.6712844774613;396.6568917886029;399.1398536154586;411.3356928560376;20353.28763493278;20846;21249.68125478119;21442.0351084201;21594.568108637817;21022.684659730116;21104.683429748566;21721;21744;21769.129234830587;5692.516136128424;5850.0932355484965;5859.003969325225;5884.058550631899;5939.732712027948;5631.4087020862835;5830.125481177814;5854;5857.297124345085;5859.384764599719;5592.944070559303;5755.856103597402;5807.1579620954935;5823.3012038555435;5835.679037652927;5725.198472213893;5753.8561535961935;5760.078387458028;5774.711264436783;5776.768929242846;4130.884661141505;5032.396112466491;5076.492350764931;5077.923831142536;5102.566281866046;26302.342441438926;26340.86829565859;26629.06798262071;26792.330191745168;26874.462510749676;2200.2959053103014;2201.0315461197024;2209.8674079555117;2335.7547457517057;2392.9949421242945;11421.714457138554;14660.56018319452;15287.694246115127;15402.691946161014;15474;1755.4821327708357;1769.2834402067183;1815.9818401815944;1819.645169192008;1923.240320073563;96724;107402.85194296148;111463;112982.87017129805;115680;11001.22991390611;11238.943805281086;11314.094872410187;11472.770544589228;11584.884151158549;13767.655808604664;13822.861771382359;13829.723405531833;13874.791878121836;13888.375023123983;8829.558522074;8876.911230887737;8882.333824963154;8924.8661270081;8979.865302020475;9040.412373195853;9096.090390960851;9099.81800363989;9163.404378715362;9195.218406435528;6667.833304167338;6722.865542689119;6838.059369739718;6847.897281540781;6860.176778786577;1864.7202919561903;1880.0881572437356;1885.679434496139;1894.507428068712;1894.9621007579772;400.34743368309466;406.6238729175501;886.3883920095113;1004.638330201127;1020.4030642074321;11361.0343120834;11769.293842369485;11822.054235661126;11880.93071623558;12068.758624827455;449.4763600405491;451.8192722910862;453.3884823417141;459.46931294355073;463.7148153885404;5974.402559744078;6138.5396095292135;6163.075538669146;6163.414475624809;6168.290646575611;621727;641046;643531;647176;652746;103747;139165;139211.30394347964;140875.29562352123;141860.2906985479;459.6116281741923;460.5118574311241;464.20620738536803;465.54841803450597;467.15678200847117;11013.229073964745;11040.668779936615;11066.114710822954;11083.50124244427;11214.775704486028;50379.74810125926;51979.74010129925;53445.19832202442;53467.6633084176;55113.72443137759;77330.6133469329;79929;81850.34635605583;81961.59019204866;83572;7689.077310722749;8015.839683206186;8137.471064380797;8143.982002249719;8164.510129392255;8926.73219803407;11887;12708.555200567936;13017.674558136123;13021.609351719464;10937.945310273397;10986.505607247533;11006.908687349378;11026.228164028442;11209.383483908463;9019.909800901907;10909.945450272699;10932.453377330943;11033.613823516138;11207.271527350696;11106.222564420414;11253.437328133725;11394.48724807369;11434;11673.299602023906;8084.393670474856;8161.020677518736;8169.959150204211;8461.323094152576;8513.233808956977;11123.443827808738;11286.15353848481;11479.311241325548;11710.648680539598;11720.238184517979 02:23:29 INFO - PROCESS | 2308 | |3;mootools.html;1380.2753554383949;1548.380647740904;1734.9479515614537;1751.8773685841995;1903.6954087346019;7871.527708337504;8488.66045358185;8693.130686931312;8924.152205540471;8938.910610893889;8515.78710532237;8593.097724738891;8689.565521723904;8713.825723485526;8739.519326437043;2440.438699099204;2453.9631905521433;2457.631355296711;2460.59400198967;2463.2610216934936;2403.9542798882453;2470.4318006858434;2471.505698860226;2487.502499500098;2496.5256601245705;2430.8419952703016;2443.5723748344058;2479.293401380607;2479.504099180171;2501.3246423465653;2446.90212391505;2461.212412028152;2479.690038745157;2484.9751502484924;2498.7376325485834;2449.3876530867283;2482.863442510661;2484.8757562121823;2489.614109812982;2490.96624900666;8961.238294744955;8985.281177505783;8999.730008099768;9011.639534418615;9091.999880013193;5336.546393556552;5440.863978400532;5500.50495455411;5546.611737178399;5547.944520554784;3532.2229109595846;3618.8914332570066;3636.799976001319;3648.981755091234;3728.142527218755;1043.2436483549366;1055.2665897201448;1055.59887242848;1056.4084112896803;1057;11888.643340699793;12082.758344833142;12097.516099356015;12103.515859365614;12103.636890893375;4702.670813043054;4796.5203479651755;4842.661013729075;4852.223644216909;4892.412910450768;10208.081272685495;10238.129758970423;10238.53926573306;10258.743531411774;10258.846117308247;5443.455654434604;5455.754491047913;5470.56235501159;5494.2857428534835;5511.145772405283;4383.649308055348;4762.618990480753;4864.294677271776;4897.2654101885;4955.801767929314;4042.818073186655;4046.615571520701;4111.218868414991;4117.382392641068;4173.645240154621;1130.5025788652965;1139.780434934612;1308.9803652945213;1310.5151094095056;1320.9009324300716;913.9268858491305;915.9084091590788;925.796324808541;936.6721647423484;936.9765755856158;4950.975245123824;5681.57388195887;5786.479216870502;5861.589688721749;5874.676892770939;397.530913522041;451.905099929012;469.20469803683113;470.76226505614443;475.372508289062;3139.21519620095;3860.131470419133;3863.864764733221;3865.40086286626;3897.707671924617;10364.222683298784;10578.947105264579;10710;10779.191560632984;10800.1899857511;10634.840477392845;10673.306235094695;10696.78606427867;10822.296550724177;10833.512491937881;8071.515709057593;8353.579891418474;8356.582170891432;8364.247217750431;8370.995480542373;5599.356074051373;5648;5668.914966275592;5705.773258749389;5766.5675074368755;9306.534673266446;9425.434473931586;9474.24206063527;9575.99452057524;9579.760505987406;9199.632014719604;9565.713028609152;9614.03859614033;9645.324827262024;9719.125278724949;6822.624755638289;7279.781606551812;7461.776146715607;7504.699812007459;7669.233076692286;6979.267176946348;8263;8303.086660467465;8307.916920830714;8345.54099524532;7262.6731797068205;7372.152202496782;7374.041374621265;7475.7009719610605;7493.726066568587;7248.855022899407;7275.527090739085;7328.706851726084;7382.187959324363;7385.556866588022;7096.361327480551;7104.431645468453;7122.7507037253445;7128.501004929605;7137.250588688113;4833.178359678793;4847.127517046965;4859.246816743269;4936.086823937583;4949.82675606352;4638.6752927294765;4897.436794768505;4914.213725803854;4939.382577177853;5125.410577783454 02:23:29 INFO - PROCESS | 2308 | |4;prototype.html;321.350871240095;321.3653035255371;328.90966446230743;328.92763592009766;334.2729563200039;6911.2743161967965;6949.444044476442;6985.545939513928;7010.719571217158;7028.332308430704;6885;6900.480754815056;6903.930960690392;6906.171259448861;6911.995521298817;4773.284007398901;4846.9515304846855;4978.875528111808;4988.176950803131;4991.825286114987;4933.161362568354;4972.428170760355;4977.55202031817;4977.900441991176;5001.524855138774;4907.312976183337;4914.70511769295;4928.236123400879;4936.593070974773;4943.209086546153;4906.509349065101;4913.901721965576;4928.876778080541;4953.2074868020945;4979.925301120486;4253.74477531346;4284.464441944757;4290.69965102443;4293.012607100353;4311.504177019651;6364.872702545923;6678.4991125665765;6693.263740988487;6697.129373181504;6717.361850624183;5836.649801011953;5851.795187168429;5864.179014937912;5874.118882167709;5875.441833025899;5965.254343207099;6358.713857876407;7380.604921736008;7395.741149059757;7411.4441416893405;3692.46459263408;3869.690424766012;4641.7911193996015;4664.836730714443;4679.578837904571;7543.2079631638435;7763.922360776376;7816.257455541714;7835.764927052198;7842.98041254639;4680.040591678698;4707.976460117678;4718.339432479455;4723.645726570521;4764.309175169581;3958.802059897051;4086.203190377848;4775.331453596499;4786.712797232177;4812.205986012269;5795.362510123883;5795.8551036224435;5810.5061069809535;5817.447342502455;5840.766369345263;5984.970075149597;7382.630868456556;7398.815029624302;7407.555546667218;7435.962820185864;1648.282996896354;1668.457751230855;1671.097607292073;1672.4480921295942;1678.6726588315405;1047.3454091193005;1064.590132798878;1073.9409332486632;1127.6975093766548;1135.3982389333667;6756;6823.454123670094;6834;6866.450683945272;6919.308069193041;7388.113426388868;7646.3882889368715;7691.50005249657;7711.228877112356;7748.070231572247;6907.171139463297;6946.548474349152;6946.6179360135675;6951.957206418976;6987.196472405739;4059.6752259819145;4098.549159592442;4106.67146628269;4125.257453658311;4127.968007998001;7063.540869843444;8152.144024877421;8152.714654987047;8156.143604921516;8173.386995975326;6502.934970650327;6516.804495865132;7708.344790692854;8065.314448271845;8084.434089613671;8112.269895709414;8154.38842086834;8163.346932245406;8176.550289733884;8184.83630327402;7835.960820195863;7847.176046515034;7861.371090312647;7861.724839630814;7878.84242315139;6669.933300666931;7245.456590755609;7662.310392064518;7677.155512893689;7720.792168268195;6984.441244700311;7767.456278060482;7824.4131690124605;8169.346452283684;8187.71343002992;4656.254999200111;4747.406574178228;4754.928676069792;4756.6908150970075;4758.643101767312;6336.714847831767;7734.071911370672;7762.844743105219;7769.72805951789;7811.960940195263;7155.534890232118;7481.625918703934;7499.02512673349;7502.512336698097;7510.474266801271;7466.589337586268;7470.252974702486;7473.850522989401;7484.962575187089;7495.325420712162;7234.059572255573;7288.708451662086;7288.96355518219;7289.125304963438;7347.228541003117;7479.70081196746;7489.850202995801;7493.250674932463;7498.850022999619;7506.9249307506225;6298.84252893663;6314.62112273265;6999.265077167028;7323.780286591411;7336.523125996793 02:23:29 INFO - PROCESS | 2308 | |5;yui.html;606.4329851588764;673.3703986772367;726.5277569579775;745.7389913530266;761.744815486812;12296.139270251086;14705;14778;14836.258187090643;15073.924630376847;11270.718232044203;13669.863301366984;13698.383572739225;13702.862971370283;13716.245606491639;718.192033961793;867.3104881619097;892.3173772064376;900.5542256583001;935.39199520312;885.1104639836987;913.6162811619135;923.7136487688838;924.4638109896277;927.2026057590467;967.1005964453091;982.3123813330661;985.9007206964219;986.4623780039896;987.8863930647959;968.6173961285323;972.1833659725864;972.5380444288942;977.0132166511803;977.31587888478;712.5510928115306;726.288237527221;729.7591794707733;733.6515155301242;733.9522931009467;11930.343831089287;14422.350994205286;14454.132752034806;14504.129752214796;14516.854831451656;10654.200934929833;12480.126391152622;12489.562865299671;12832;12863.485460581565;9818.26363022776;10156.593736250541;10186.643467478676;10248;10345.896541034568;2895.4498645257336;3449.793012419263;3489.8102111469148;3536.1513236823234;3767.4348847672795;82182.58908705418;82934;83253.58373208156;85472;85493.99999999937;6774.72901083961;9024.819503609891;9567.282453815991;9571.952140239255;9616.663416780379;20379;20436.182552698025;20615.69076463854;20706.171753129707;20708.275210367567;7006.684699188447;7201.819954501179;7224.952381904594;7325.047743793273;7493.70025198986;4058.2086493133556;4129.132882094794;4133.524644665842;4134.338505839111;4134.4831896013;3601.7838929664304;3671.6328367163605;3692.280005398975;3694.6674799268194;3703.2963736889906;539.7274376439873;607.5534482155612;653.7613770973655;655.7901471529065;657.1686816177539;501.801788293622;534.2973989204185;571.7255717255657;592.8843875444334;597.0864577196895;8184.54984975832;8228.30059444942;8276.255137037695;8330.541820199947;8524.488530688179;454.55226601797864;566.4420545762421;601.8435206846164;615.4061330815784;615.9938400616026;3611.2304970564232;3849.4225866119746;3893.805309734502;3912.902177445587;4107.507099148122;65852.670736646;66829.66340673256;88015;88997.55501222581;89068;59837;69078.61842763117;72851.54296914033;73816;74363.5127297451;65251;65752.67123664351;66200.33799662038;66273;67361.64234251875;8195.385346098947;8377.45546539473;8402.07577166499;8426.915730842613;8568.785780355542;37054;47392.763036184595;47686;47695.523044770496;47988;50748.731281718254;51012.70442830156;60103.69948150231;63319;65401.67299163474;10627.415492148006;10806.297590656897;10884.61903833362;11116.666500005012;11158.44207789591;11798.941005294919;11984.64046078619;12049.33728644898;12057.276563406223;12406.503739850305;11181.256036722985;11203.327800332007;11259.380734059378;11363.715907102389;11508.194426390073;10770.39711911577;11164.330140191614;11426.542938282708;11632.592859250224;12129.393530323272;8973.327000475121;10854.945725271324;11108.166887483309;11219.439028048402;11499.8275025873;9301.906980930375;9387.249020078514;9391.107844754737;9484.905150948402;9771.609135634495;7783.143854175923;8058.31702932732;9196.954015229881;9488.573014214238;9806.068423499755 02:23:29 INFO - PROCESS | 2308 | __end_tp_report 02:23:29 INFO - PROCESS | 2308 | __start_cc_report 02:23:29 INFO - PROCESS | 2308 | _x_x_mozilla_cycle_collect,417 02:23:29 INFO - PROCESS | 2308 | __end_cc_report 02:23:29 INFO - PROCESS | 2308 | __startTimestamp1464427409830__endTimestamp 02:23:29 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | ------- Summary: start ------- 02:23:29 INFO - PROCESS | 2308 | Number of tests: 6 02:23:29 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | [#0] dojo.html Cycles:165 Average:5743.50 Median:5979.10 stddev:670.92 (11.2%) stddev-sans-first:647.99 02:23:29 INFO - PROCESS | 2308 | Values: 3431.2 3446.3 3464.3 3481.4 3522.9 5009.5 5590.9 5771.4 5789.1 5794.0 5683.8 5753.9 5782.0 5824.0 5829.4 4370.4 5211.4 5241.6 5299.8 5334.7 4141.8 5187.1 5190.6 5313.6 5375.3 5234.1 5252.6 5253.9 5256.2 5278.4 5209.4 5240.2 5255.7 5263.1 5264.5 5107.0 5210.7 5222.4 5229.2 5238.8 5783.1 5804.9 5825.7 5874.6 5909.4 6052.7 6177.0 6210.6 6224.5 6241.4 6142.6 6183.6 6230.8 6232.4 6247.0 5909.5 5959.5 5961.3 5979.1 6042.1 6323.4 6329.1 6348.7 6351.8 6379.6 5460.2 6125.1 6152.4 6214.3 6228.7 6144.5 6188.0 6190.0 6191.9 6219.6 6189.6 6217.9 6221.1 6223.0 6233.3 5390.6 6125.0 6208.7 6221.8 6247.2 3835.9 4944.5 4978.2 4979.7 5001.8 4327.6 4453.4 4480.0 4517.2 4544.0 5733.8 5790.6 5795.6 5820.0 5835.6 6242.4 6253.1 6264.9 6289.1 6304.4 4916.4 5669.9 5837.4 5862.0 5887.2 5292.6 5352.1 5399.4 5409.9 5473.3 5447.7 6419.7 6446.1 6491.1 6506.7 6124.1 6194.7 6267.9 6296.9 6308.6 6394.9 6439.2 6457.0 6460.2 6483.4 6224.1 6230.0 6256.5 6258.2 6279.4 6128.8 6147.8 6208.3 6266.1 6278.5 5266.4 6162.3 6174.6 6182.4 6205.2 6093.4 6161.7 6178.8 6179.9 6180.6 5141.5 5778.9 5915.5 5938.9 5975.6 6109.2 6134.5 6158.1 6161.2 6175.5 6056.6 6062.4 6093.1 6093.4 6116.2 02:23:29 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | [#1] ext.html Cycles:180 Average:62920.21 Median:38258.12 stddev:138187.86 (361.2%) stddev-sans-first:138496.04 02:23:29 INFO - PROCESS | 2308 | Values: 498.2 512.7 523.4 533.7 534.9 37450.6 38532.4 38626.6 38635.6 38750.4 40011.2 40320.0 40560.0 40643.6 40793.0 9369.4 9589.6 9599.3 9609.0 9617.8 9437.0 9630.6 9663.0 9713.4 9720.6 9615.0 9682.4 9713.7 9723.5 9723.6 9615.6 9662.9 9663.7 9684.7 9718.0 9599.2 9702.2 9733.8 9747.0 9748.3 39052.6 39474.0 39502.8 39514.6 39716.8 2753.9 3021.4 3144.8 3248.5 3275.5 25663.3 26069.6 26086.6 26871.0 27685.9 2616.6 2705.6 2718.7 2724.5 2775.6 318736.0 318805.0 319655.4 321215.0 321545.0 33817.5 35336.9 35422.5 36191.6 36398.0 33946.8 36086.5 36138.0 36346.8 36690.0 11802.6 12827.8 15267.9 15336.0 15392.7 12332.0 12393.6 12460.8 12464.7 12485.2 8156.3 8161.5 8173.4 8231.0 8270.3 2703.7 3167.9 3179.5 3453.5 3469.9 900.9 1002.6 1014.7 1053.0 1120.9 29961.3 30161.4 30263.0 30749.7 30792.8 1079.3 1085.5 1142.3 1151.9 1212.7 14646.7 15527.8 15601.7 15682.1 15897.2 595002.0 599206.0 602467.0 605418.0 607057.0 45097.5 53494.7 60750.7 60791.0 60983.0 1490.7 1507.2 1508.2 1509.0 1525.6 24472.0 30048.8 30766.4 32148.7 32488.7 58219.4 58260.4 58798.7 59234.7 59420.4 573087.0 575200.0 579073.0 579297.0 582274.0 42632.0 43104.6 43305.1 43834.1 43961.3 46528.8 46622.5 46758.5 47042.3 47532.0 38323.0 38438.2 39112.8 39265.0 39477.6 30141.0 37809.1 37928.2 38456.6 38979.6 31127.7 37525.1 38193.2 38601.0 38682.2 33323.8 33608.0 34266.3 34282.3 34328.5 25544.6 25641.9 25651.4 25797.5 26274.7 02:23:29 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | [#2] jquery.html Cycles:180 Average:35783.87 Median:395.16 stddev:106748.08 (27013.6%) stddev-sans-first:107014.38 02:23:29 INFO - PROCESS | 2308 | Values: 352.9 393.7 396.7 399.1 411.3 20353.3 20846.0 21249.7 21442.0 21594.6 21022.7 21104.7 21721.0 21744.0 21769.1 5692.5 5850.1 5859.0 5884.1 5939.7 5631.4 5830.1 5854.0 5857.3 5859.4 5592.9 5755.9 5807.2 5823.3 5835.7 5725.2 5753.9 5760.1 5774.7 5776.8 4130.9 5032.4 5076.5 5077.9 5102.6 26302.3 26340.9 26629.1 26792.3 26874.5 2200.3 2201.0 2209.9 2335.8 2393.0 11421.7 14660.6 15287.7 15402.7 15474.0 1755.5 1769.3 1816.0 1819.6 1923.2 96724.0 107402.9 111463.0 112982.9 115680.0 11001.2 11238.9 11314.1 11472.8 11584.9 13767.7 13822.9 13829.7 13874.8 13888.4 8829.6 8876.9 8882.3 8924.9 8979.9 9040.4 9096.1 9099.8 9163.4 9195.2 6667.8 6722.9 6838.1 6847.9 6860.2 1864.7 1880.1 1885.7 1894.5 1895.0 400.3 406.6 886.4 1004.6 1020.4 11361.0 11769.3 11822.1 11880.9 12068.8 449.5 451.8 453.4 459.5 463.7 5974.4 6138.5 6163.1 6163.4 6168.3 621727.0 641046.0 643531.0 647176.0 652746.0 103747.0 139165.0 139211.3 140875.3 141860.3 459.6 460.5 464.2 465.5 467.2 11013.2 11040.7 11066.1 11083.5 11214.8 50379.7 51979.7 53445.2 53467.7 55113.7 77330.6 79929.0 81850.3 81961.6 83572.0 7689.1 8015.8 8137.5 8144.0 8164.5 8926.7 11887.0 12708.6 13017.7 13021.6 10937.9 10986.5 11006.9 11026.2 11209.4 9019.9 10909.9 10932.5 11033.6 11207.3 11106.2 11253.4 11394.5 11434.0 11673.3 8084.4 8161.0 8170.0 8461.3 8513.2 11123.4 11286.2 11479.3 11710.6 11720.2 02:23:29 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | [#3] mootools.html Cycles:180 Average:5701.13 Median:4878.35 stddev:3194.59 (65.5%) stddev-sans-first:3187.05 02:23:29 INFO - PROCESS | 2308 | Values: 1380.3 1548.4 1734.9 1751.9 1903.7 7871.5 8488.7 8693.1 8924.2 8938.9 8515.8 8593.1 8689.6 8713.8 8739.5 2440.4 2454.0 2457.6 2460.6 2463.3 2404.0 2470.4 2471.5 2487.5 2496.5 2430.8 2443.6 2479.3 2479.5 2501.3 2446.9 2461.2 2479.7 2485.0 2498.7 2449.4 2482.9 2484.9 2489.6 2491.0 8961.2 8985.3 8999.7 9011.6 9092.0 5336.5 5440.9 5500.5 5546.6 5547.9 3532.2 3618.9 3636.8 3649.0 3728.1 1043.2 1055.3 1055.6 1056.4 1057.0 11888.6 12082.8 12097.5 12103.5 12103.6 4702.7 4796.5 4842.7 4852.2 4892.4 10208.1 10238.1 10238.5 10258.7 10258.8 5443.5 5455.8 5470.6 5494.3 5511.1 4383.6 4762.6 4864.3 4897.3 4955.8 4042.8 4046.6 4111.2 4117.4 4173.6 1130.5 1139.8 1309.0 1310.5 1320.9 913.9 915.9 925.8 936.7 937.0 4951.0 5681.6 5786.5 5861.6 5874.7 397.5 451.9 469.2 470.8 475.4 3139.2 3860.1 3863.9 3865.4 3897.7 10364.2 10578.9 10710.0 10779.2 10800.2 10634.8 10673.3 10696.8 10822.3 10833.5 8071.5 8353.6 8356.6 8364.2 8371.0 5599.4 5648.0 5668.9 5705.8 5766.6 9306.5 9425.4 9474.2 9576.0 9579.8 9199.6 9565.7 9614.0 9645.3 9719.1 6822.6 7279.8 7461.8 7504.7 7669.2 6979.3 8263.0 8303.1 8307.9 8345.5 7262.7 7372.2 7374.0 7475.7 7493.7 7248.9 7275.5 7328.7 7382.2 7385.6 7096.4 7104.4 7122.8 7128.5 7137.3 4833.2 4847.1 4859.2 4936.1 4949.8 4638.7 4897.4 4914.2 4939.4 5125.4 02:23:29 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | [#4] prototype.html Cycles:180 Average:5983.47 Median:6828.73 stddev:1969.43 (28.8%) stddev-sans-first:1928.56 02:23:29 INFO - PROCESS | 2308 | Values: 321.4 321.4 328.9 328.9 334.3 6911.3 6949.4 6985.5 7010.7 7028.3 6885.0 6900.5 6903.9 6906.2 6912.0 4773.3 4847.0 4978.9 4988.2 4991.8 4933.2 4972.4 4977.6 4977.9 5001.5 4907.3 4914.7 4928.2 4936.6 4943.2 4906.5 4913.9 4928.9 4953.2 4979.9 4253.7 4284.5 4290.7 4293.0 4311.5 6364.9 6678.5 6693.3 6697.1 6717.4 5836.6 5851.8 5864.2 5874.1 5875.4 5965.3 6358.7 7380.6 7395.7 7411.4 3692.5 3869.7 4641.8 4664.8 4679.6 7543.2 7763.9 7816.3 7835.8 7843.0 4680.0 4708.0 4718.3 4723.6 4764.3 3958.8 4086.2 4775.3 4786.7 4812.2 5795.4 5795.9 5810.5 5817.4 5840.8 5985.0 7382.6 7398.8 7407.6 7436.0 1648.3 1668.5 1671.1 1672.4 1678.7 1047.3 1064.6 1073.9 1127.7 1135.4 6756.0 6823.5 6834.0 6866.5 6919.3 7388.1 7646.4 7691.5 7711.2 7748.1 6907.2 6946.5 6946.6 6952.0 6987.2 4059.7 4098.5 4106.7 4125.3 4128.0 7063.5 8152.1 8152.7 8156.1 8173.4 6502.9 6516.8 7708.3 8065.3 8084.4 8112.3 8154.4 8163.3 8176.6 8184.8 7836.0 7847.2 7861.4 7861.7 7878.8 6669.9 7245.5 7662.3 7677.2 7720.8 6984.4 7767.5 7824.4 8169.3 8187.7 4656.3 4747.4 4754.9 4756.7 4758.6 6336.7 7734.1 7762.8 7769.7 7812.0 7155.5 7481.6 7499.0 7502.5 7510.5 7466.6 7470.3 7473.9 7485.0 7495.3 7234.1 7288.7 7289.0 7289.1 7347.2 7479.7 7489.9 7493.3 7498.9 7506.9 6298.8 6314.6 6999.3 7323.8 7336.5 02:23:29 INFO - PROCESS | 2308 | 02:23:29 INFO - PROCESS | 2308 | [#5] yui.html Cycles:180 Average:17155.60 Median:30355.07 stddev:23716.14 (78.1%) stddev-sans-first:23750.11 02:23:29 INFO - PROCESS | 2308 | Values: 606.4 673.4 726.5 745.7 761.7 12296.1 14705.0 14778.0 14836.3 15073.9 11270.7 13669.9 13698.4 13702.9 13716.2 718.2 867.3 892.3 900.6 935.4 885.1 913.6 923.7 924.5 927.2 967.1 982.3 985.9 986.5 987.9 968.6 972.2 972.5 977.0 977.3 712.6 726.3 729.8 733.7 734.0 11930.3 14422.4 14454.1 14504.1 14516.9 10654.2 12480.1 12489.6 12832.0 12863.5 9818.3 10156.6 10186.6 10248.0 10345.9 2895.4 3449.8 3489.8 3536.2 3767.4 82182.6 82934.0 83253.6 85472.0 85494.0 6774.7 9024.8 9567.3 9572.0 9616.7 20379.0 20436.2 20615.7 20706.2 20708.3 7006.7 7201.8 7225.0 7325.0 7493.7 4058.2 4129.1 4133.5 4134.3 4134.5 3601.8 3671.6 3692.3 3694.7 3703.3 539.7 607.6 653.8 655.8 657.2 501.8 534.3 571.7 592.9 597.1 8184.5 8228.3 8276.3 8330.5 8524.5 454.6 566.4 601.8 615.4 616.0 3611.2 3849.4 3893.8 3912.9 4107.5 65852.7 66829.7 88015.0 88997.6 89068.0 59837.0 69078.6 72851.5 73816.0 74363.5 65251.0 65752.7 66200.3 66273.0 67361.6 8195.4 8377.5 8402.1 8426.9 8568.8 37054.0 47392.8 47686.0 47695.5 47988.0 50748.7 51012.7 60103.7 63319.0 65401.7 10627.4 10806.3 10884.6 11116.7 11158.4 11798.9 11984.6 12049.3 12057.3 12406.5 11181.3 11203.3 11259.4 11363.7 11508.2 10770.4 11164.3 11426.5 11632.6 12129.4 8973.3 10854.9 11108.2 11219.4 11499.8 9301.9 9387.2 9391.1 9484.9 9771.6 7783.1 8058.3 9197.0 9488.6 9806.1 02:23:29 INFO - PROCESS | 2308 | -------- Summary: end -------- 02:23:29 INFO - PROCESS | 2308 | 02:23:30 INFO - TEST-INFO | 2308: exit 0 02:23:30 INFO - TEST-OK | dromaeo_css | took 1111900ms 02:23:30 INFO - TEST-START | kraken 02:23:30 INFO - operating with platform_type : mac_ 02:23:30 INFO - Initialising browser for kraken test... 02:23:30 INFO - TEST-INFO | started process 2315 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpyfVZIK/profile http://localhost:53166/getInfo.html) 02:23:33 INFO - PROCESS | 2315 | __metrics Screen width/height:1600/1200 02:23:33 INFO - PROCESS | 2315 | colorDepth:24 02:23:33 INFO - PROCESS | 2315 | Browser inner width/height: 1024/689 02:23:33 INFO - PROCESS | 2315 | __metrics 02:23:33 ERROR - PROCESS | 2315 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 02:23:34 INFO - TEST-INFO | 2315: exit 0 02:23:34 INFO - Browser initialized. 02:23:34 INFO - Running cycle 1/1 for kraken test... 02:23:34 INFO - TEST-INFO | started process 2319 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpyfVZIK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:23:46 INFO - PROCESS | 2319 | RSS: Main: 221143040 02:23:46 INFO - PROCESS | 2319 | 02:23:48 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53166/tests/kraken/driver.html?testName=audio-beat-detection) 02:23:49 INFO - PROCESS | 2319 | RSS: Main: 233009152 02:23:49 INFO - PROCESS | 2319 | 02:23:52 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53166/tests/kraken/driver.html?testName=audio-dft) 02:23:53 INFO - PROCESS | 2319 | RSS: Main: 242003968 02:23:53 INFO - PROCESS | 2319 | 02:23:56 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53166/tests/kraken/driver.html?testName=audio-fft) 02:23:57 INFO - PROCESS | 2319 | RSS: Main: 246013952 02:23:57 INFO - PROCESS | 2319 | 02:23:59 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53166/tests/kraken/driver.html?testName=audio-oscillator) 02:23:59 INFO - PROCESS | 2319 | RSS: Main: 246345728 02:23:59 INFO - PROCESS | 2319 | 02:24:01 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53166/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:24:02 INFO - PROCESS | 2319 | RSS: Main: 246116352 02:24:02 INFO - PROCESS | 2319 | 02:24:05 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53166/tests/kraken/driver.html?testName=imaging-darkroom) 02:24:06 INFO - PROCESS | 2319 | RSS: Main: 247578624 02:24:06 INFO - PROCESS | 2319 | 02:24:09 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53166/tests/kraken/driver.html?testName=imaging-desaturate) 02:24:09 INFO - PROCESS | 2319 | RSS: Main: 247685120 02:24:09 INFO - PROCESS | 2319 | 02:24:12 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53166/tests/kraken/driver.html?testName=json-parse-financial) 02:24:13 INFO - PROCESS | 2319 | RSS: Main: 247894016 02:24:13 INFO - PROCESS | 2319 | 02:24:14 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53166/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:24:15 INFO - PROCESS | 2319 | RSS: Main: 247709696 02:24:15 INFO - PROCESS | 2319 | 02:24:17 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53166/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:24:18 INFO - PROCESS | 2319 | RSS: Main: 247574528 02:24:18 INFO - PROCESS | 2319 | 02:24:20 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53166/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:24:20 INFO - PROCESS | 2319 | RSS: Main: 247463936 02:24:20 INFO - PROCESS | 2319 | 02:24:23 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:24:24 INFO - PROCESS | 2319 | RSS: Main: 247435264 02:24:24 INFO - PROCESS | 2319 | 02:24:27 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:24:27 INFO - PROCESS | 2319 | RSS: Main: 247455744 02:24:27 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | Cycle 1(1): loaded http://localhost:53166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53166/tests/kraken/driver.html?testName=ai-astar) 02:24:29 INFO - PROCESS | 2319 | RSS: Main: 249319424 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | __start_tp_report 02:24:29 INFO - PROCESS | 2319 | _x_x_mozilla_page_load 02:24:29 INFO - PROCESS | 2319 | _x_x_mozilla_page_load_details 02:24:29 INFO - PROCESS | 2319 | |i|pagename|runs| 02:24:29 INFO - PROCESS | 2319 | |0;ai-astar;114;118;110;118;118;118;107;117;133;154 02:24:29 INFO - PROCESS | 2319 | |1;audio-beat-detection;153;140;160;175;160;139;142;130;207;149 02:24:29 INFO - PROCESS | 2319 | |2;audio-dft;199;201;198;201;204;195;200;201;199;198 02:24:29 INFO - PROCESS | 2319 | |3;audio-fft;67;81;74;143;67;86;82;96;128;58 02:24:29 INFO - PROCESS | 2319 | |4;audio-oscillator;77;84;124;75;78;82;115;74;73;116 02:24:29 INFO - PROCESS | 2319 | |5;imaging-gaussian-blur;88;86;101;86;82;130;130;86;92;86 02:24:29 INFO - PROCESS | 2319 | |6;imaging-darkroom;103;95;96;95;120;116;151;92;153;90 02:24:29 INFO - PROCESS | 2319 | |7;imaging-desaturate;79;132;96;142;145;89;91;92;90;95 02:24:29 INFO - PROCESS | 2319 | |8;json-parse-financial;67;64;66;65;65;67;66;82;71;94 02:24:29 INFO - PROCESS | 2319 | |9;json-stringify-tinderbox;63;71;63;72;74;80;71;71;69;68 02:24:29 INFO - PROCESS | 2319 | |10;stanford-crypto-aes;83;82;83;81;81;80;83;83;87;81 02:24:29 INFO - PROCESS | 2319 | |11;stanford-crypto-ccm;128;129;131;137;154;141;120;156;182;150 02:24:29 INFO - PROCESS | 2319 | |12;stanford-crypto-pbkdf2;220;159;219;223;176;167;168;167;168;215 02:24:29 INFO - PROCESS | 2319 | |13;stanford-crypto-sha256-iterative;70;66;64;65;71;67;70;67;68;68 02:24:29 INFO - PROCESS | 2319 | __end_tp_report 02:24:29 INFO - PROCESS | 2319 | __start_cc_report 02:24:29 INFO - PROCESS | 2319 | _x_x_mozilla_cycle_collect,1095 02:24:29 INFO - PROCESS | 2319 | __end_cc_report 02:24:29 INFO - PROCESS | 2319 | __startTimestamp1464427469909__endTimestamp 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | ------- Summary: start ------- 02:24:29 INFO - PROCESS | 2319 | Number of tests: 14 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#0] ai-astar Cycles:10 Average:120.70 Median:118.00 stddev:13.54 (11.5%) stddev-sans-first:14.14 02:24:29 INFO - PROCESS | 2319 | Values: 114.0 118.0 110.0 118.0 118.0 118.0 107.0 117.0 133.0 154.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#1] audio-beat-detection Cycles:10 Average:155.50 Median:156.50 stddev:22.28 (14.2%) stddev-sans-first:23.61 02:24:29 INFO - PROCESS | 2319 | Values: 153.0 140.0 160.0 175.0 160.0 139.0 142.0 130.0 207.0 149.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#2] audio-dft Cycles:10 Average:199.60 Median:200.50 stddev:2.41 (1.2%) stddev-sans-first:2.55 02:24:29 INFO - PROCESS | 2319 | Values: 199.0 201.0 198.0 201.0 204.0 195.0 200.0 201.0 199.0 198.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#3] audio-fft Cycles:10 Average:88.20 Median:77.50 stddev:27.40 (35.4%) stddev-sans-first:27.96 02:24:29 INFO - PROCESS | 2319 | Values: 67.0 81.0 74.0 143.0 67.0 86.0 82.0 96.0 128.0 58.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:76.00 stddev:20.11 (26.5%) stddev-sans-first:20.79 02:24:29 INFO - PROCESS | 2319 | Values: 77.0 84.0 124.0 75.0 78.0 82.0 115.0 74.0 73.0 116.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:86.00 stddev:18.28 (21.3%) stddev-sans-first:19.12 02:24:29 INFO - PROCESS | 2319 | Values: 88.0 86.0 101.0 86.0 82.0 130.0 130.0 86.0 92.0 86.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:91.00 stddev:23.74 (26.1%) stddev-sans-first:25.00 02:24:29 INFO - PROCESS | 2319 | Values: 103.0 95.0 96.0 95.0 120.0 116.0 151.0 92.0 153.0 90.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#7] imaging-desaturate Cycles:10 Average:105.10 Median:90.50 stddev:24.50 (27.1%) stddev-sans-first:24.09 02:24:29 INFO - PROCESS | 2319 | Values: 79.0 132.0 96.0 142.0 145.0 89.0 91.0 92.0 90.0 95.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#8] json-parse-financial Cycles:10 Average:70.70 Median:67.00 stddev:9.73 (14.5%) stddev-sans-first:10.23 02:24:29 INFO - PROCESS | 2319 | Values: 67.0 64.0 66.0 65.0 65.0 67.0 66.0 82.0 71.0 94.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.00 stddev:5.01 (7.1%) stddev-sans-first:4.58 02:24:29 INFO - PROCESS | 2319 | Values: 63.0 71.0 63.0 72.0 74.0 80.0 71.0 71.0 69.0 68.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:83.00 stddev:1.96 (2.4%) stddev-sans-first:2.06 02:24:29 INFO - PROCESS | 2319 | Values: 83.0 82.0 83.0 81.0 81.0 80.0 83.0 83.0 87.0 81.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:145.50 stddev:18.24 (12.5%) stddev-sans-first:18.54 02:24:29 INFO - PROCESS | 2319 | Values: 128.0 129.0 131.0 137.0 154.0 141.0 120.0 156.0 182.0 150.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:195.50 stddev:27.09 (13.9%) stddev-sans-first:26.18 02:24:29 INFO - PROCESS | 2319 | Values: 220.0 159.0 219.0 223.0 176.0 167.0 168.0 167.0 168.0 215.0 02:24:29 INFO - PROCESS | 2319 | 02:24:29 INFO - PROCESS | 2319 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:2.27 (3.3%) stddev-sans-first:2.24 02:24:29 INFO - PROCESS | 2319 | Values: 70.0 66.0 64.0 65.0 71.0 67.0 70.0 67.0 68.0 68.0 02:24:29 INFO - PROCESS | 2319 | -------- Summary: end -------- 02:24:29 INFO - PROCESS | 2319 | 02:24:30 INFO - TEST-INFO | 2319: exit 0 02:24:30 INFO - TEST-OK | kraken | took 60471ms 02:24:30 INFO - SUITE-END | took 1172s 02:24:31 INFO - Completed test suite (00:19:32) 02:24:31 INFO - javascript benchmark 02:24:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3431.2279737059157, 3446.276281980784, 3464.2725027744173, 3481.3907566175913, 3522.894313170604, 5009.499050094989, 5590.909772594346, 5771.394003629616, 5789.102689083194, 5793.957087724213, 5683.829485115442, 5753.942460575393, 5782.045962416196, 5824.0, 5829.358770535248, 4370.4099946507295, 5211.3746350438005, 5241.5806735461265, 5299.788008479656, 5334.653247538917, 4141.7514949103, 5187.0922588546855, 5190.636655434121, 5313.574914006889, 5375.301210842585, 5234.057869583474, 5252.632315737881, 5253.8949221015555, 5256.237845512417, 5278.3665960084845, 5209.374875015003, 5240.161574148137, 5255.658382205144, 5263.105272103751, 5264.473552644705, 5107.0, 5210.687358758449, 5222.399424066243, 5229.215617657382, 5238.764255608507, 5783.103618939072, 5804.912926306109, 5825.708714564297, 5874.559408044371, 5909.379515150933, 6052.697365131726, 6177.042558403455, 6210.627362358273, 6224.470919971808, 6241.438270555626, 6142.560166306883, 6183.567150299481, 6230.844228894269, 6232.439080482778, 6247.0, 5909.497692696126, 5959.523238140939, 5961.254843144607, 5979.1031345298115, 6042.063480160582, 6323.430891219812, 6329.082283068929, 6348.746050157942, 6351.777687780906, 6379.64911929839, 5460.153676180198, 6125.082842094287, 6152.415520525543, 6214.25428948529, 6228.688565571703, 6144.463884028993, 6187.969060154671, 6190.0, 6191.938080619136, 6219.595726277777, 6189.597676151036, 6217.875642487125, 6221.129041934042, 6223.0, 6233.3143354231, 5390.595705322116, 6124.960759790135, 6208.658523781235, 6221.782237621662, 6247.219097612799, 3835.9424608630893, 4944.456109827916, 4978.153713868651, 4979.701217926936, 5001.8499445016705, 4327.6105150536605, 4453.35426363182, 4479.955200448019, 4517.232070548015, 4543.954560454419, 5733.799317023884, 5790.594658373873, 5795.623284486495, 5819.970900145557, 5835.5915085944425, 6242.375762423812, 6253.1245625612455, 6264.906026409608, 6289.0880822280515, 6304.3695630437505, 4916.434610019822, 5669.943300567024, 5837.416258374214, 5862.0, 5887.175795388649, 5292.629515933924, 5352.143657014859, 5399.406065332811, 5409.918851217235, 5473.343198816088, 5447.700376479332, 6419.679016049179, 6446.097546343516, 6491.1236983008575, 6506.707198176188, 6124.106319906264, 6194.690265486798, 6267.905981410191, 6296.874062518816, 6308.589941653778, 6394.872102558016, 6439.195100612424, 6456.967715161394, 6460.160179176677, 6483.351664833479, 6224.097505861534, 6230.003199488241, 6256.499480041494, 6258.217722784652, 6279.372062793684, 6128.754849805958, 6147.754089836357, 6208.348123446973, 6266.060090986206, 6278.46633036197, 5266.394364648115, 6162.26052873658, 6174.567780255339, 6182.381761823782, 6205.193324867919, 6093.390660933871, 6161.661108638888, 6178.814635560941, 6179.907301390393, 6180.6291622502795, 5141.460146684544, 5778.913316300343, 5915.467607915309, 5938.881222375442, 5975.581709280483, 6109.236345456818, 6134.478569321657, 6158.107074474266, 6161.168242287413, 6175.475084617858, 6056.636601803906, 6062.42406971337, 6093.146959425595, 6093.36019717941, 6116.235470566179], "value": 5702.276665347726, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.2227724749391, 512.671889990406, 523.4085483403754, 533.7064614462047, 534.9010433069885, 37450.62549374506, 38532.422013669784, 38626.61373386265, 38635.61364386355, 38750.41874371883, 40011.19977600446, 40320.0, 40560.0, 40643.59356406435, 40793.0, 9369.437833729964, 9589.568469418859, 9599.32804703671, 9609.0, 9617.75955601112, 9437.009114042996, 9630.614775408976, 9663.0, 9713.417194968324, 9720.61117555297, 9614.951925240364, 9682.370645907993, 9713.708588742315, 9723.513824308791, 9723.611055557769, 9615.615375384976, 9662.855057174149, 9663.661771838022, 9684.70945871625, 9717.979612140694, 9599.232061435068, 9702.175315098155, 9733.756656083655, 9747.025297470267, 9748.26887983397, 39052.60947390547, 39474.0, 39502.802485987384, 39514.60485395138, 39716.80141599273, 2753.9449211015667, 3021.4410334088266, 3144.795588286754, 3248.4640034394297, 3275.525048867925, 25663.27215923506, 26069.60895586568, 26086.608700869503, 26871.0, 27685.861570692217, 2616.5813469844925, 2705.553583658694, 2718.687350954646, 2724.4823483538066, 2775.5559110542417, 318736.0, 318805.0, 319655.40172299225, 321215.0, 321545.0, 33817.492737608954, 35336.93989180329, 35422.46866297007, 36191.63808361936, 36398.0, 33946.83026584851, 36086.458703119475, 36138.0, 36346.8182659085, 36690.0, 11802.586909458129, 12827.807582886264, 15267.854910881728, 15336.003159794578, 15392.692146157015, 12332.013438924865, 12393.62819115428, 12460.81308780369, 12464.688382790502, 12485.188462749893, 8156.2659360656635, 8161.469504482189, 8173.386995975326, 8230.958845205736, 8270.2556769891, 2703.6890757562737, 3167.889123880699, 3179.5071763876635, 3453.4647129694936, 3469.9479507807405, 900.9324300677475, 1002.5688953749811, 1014.7209517382779, 1053.0259509953262, 1120.926103194638, 29961.25096872552, 30161.396772064876, 30263.0, 30749.69250307513, 30792.846035770126, 1079.32002838213, 1085.514172544879, 1142.2860712054965, 1151.8848115188414, 1212.678640160358, 14646.707065858624, 15527.844721552865, 15601.687966240612, 15682.059076455449, 15897.205139742966, 595002.0, 599206.0, 602467.0, 605418.0, 607057.0, 45097.54902450999, 53494.73252633712, 60750.696246518484, 60791.0, 60983.0, 1490.6869557392852, 1507.1635242440332, 1508.2308022908396, 1508.9773653395207, 1525.6033431307717, 24471.96721229636, 30048.849755751082, 30766.384672306875, 32148.678513215506, 32488.675113248566, 58219.4178058214, 58260.4173958255, 58798.70600646969, 59234.70382648231, 59420.405795941486, 573087.0, 575200.0, 579073.0, 579297.0, 582274.0, 42632.0, 43104.56895431005, 43305.13389732251, 43834.12331753283, 43961.34057989069, 46528.767356163, 46622.53377466182, 46758.53241467542, 47042.29436558386, 47532.0, 38323.0, 38438.23123537569, 39112.80443597764, 39265.0, 39477.605223947394, 30141.0, 37809.10954452321, 37928.241435171694, 38456.615433845305, 38979.610203897595, 31127.688723113384, 37525.061873453385, 38193.23613527769, 38601.03497412587, 38682.22635547329, 33323.83338083294, 33608.0, 34266.31467370688, 34282.314353713286, 34328.48507272343, 25544.61683074718, 25641.871790640926, 25651.35871603225, 25797.484050319264, 26274.737252627994], "value": 18322.70164009359, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.88002079293034, 393.6712844774613, 396.6568917886029, 399.1398536154586, 411.3356928560376, 20353.28763493278, 20846.0, 21249.68125478119, 21442.0351084201, 21594.568108637817, 21022.684659730116, 21104.683429748566, 21721.0, 21744.0, 21769.129234830587, 5692.516136128424, 5850.0932355484965, 5859.003969325225, 5884.058550631899, 5939.732712027948, 5631.4087020862835, 5830.125481177814, 5854.0, 5857.297124345085, 5859.384764599719, 5592.944070559303, 5755.856103597402, 5807.1579620954935, 5823.3012038555435, 5835.679037652927, 5725.198472213893, 5753.8561535961935, 5760.078387458028, 5774.711264436783, 5776.768929242846, 4130.884661141505, 5032.396112466491, 5076.492350764931, 5077.923831142536, 5102.566281866046, 26302.342441438926, 26340.86829565859, 26629.06798262071, 26792.330191745168, 26874.462510749676, 2200.2959053103014, 2201.0315461197024, 2209.8674079555117, 2335.7547457517057, 2392.9949421242945, 11421.714457138554, 14660.56018319452, 15287.694246115127, 15402.691946161014, 15474.0, 1755.4821327708357, 1769.2834402067183, 1815.9818401815944, 1819.645169192008, 1923.240320073563, 96724.0, 107402.85194296148, 111463.0, 112982.87017129805, 115680.0, 11001.22991390611, 11238.943805281086, 11314.094872410187, 11472.770544589228, 11584.884151158549, 13767.655808604664, 13822.861771382359, 13829.723405531833, 13874.791878121836, 13888.375023123983, 8829.558522074, 8876.911230887737, 8882.333824963154, 8924.8661270081, 8979.865302020475, 9040.412373195853, 9096.090390960851, 9099.81800363989, 9163.404378715362, 9195.218406435528, 6667.833304167338, 6722.865542689119, 6838.059369739718, 6847.897281540781, 6860.176778786577, 1864.7202919561903, 1880.0881572437356, 1885.679434496139, 1894.507428068712, 1894.9621007579772, 400.34743368309466, 406.6238729175501, 886.3883920095113, 1004.638330201127, 1020.4030642074321, 11361.0343120834, 11769.293842369485, 11822.054235661126, 11880.93071623558, 12068.758624827455, 449.4763600405491, 451.8192722910862, 453.3884823417141, 459.46931294355073, 463.7148153885404, 5974.402559744078, 6138.5396095292135, 6163.075538669146, 6163.414475624809, 6168.290646575611, 621727.0, 641046.0, 643531.0, 647176.0, 652746.0, 103747.0, 139165.0, 139211.30394347964, 140875.29562352123, 141860.2906985479, 459.6116281741923, 460.5118574311241, 464.20620738536803, 465.54841803450597, 467.15678200847117, 11013.229073964745, 11040.668779936615, 11066.114710822954, 11083.50124244427, 11214.775704486028, 50379.74810125926, 51979.74010129925, 53445.19832202442, 53467.6633084176, 55113.72443137759, 77330.6133469329, 79929.0, 81850.34635605583, 81961.59019204866, 83572.0, 7689.077310722749, 8015.839683206186, 8137.471064380797, 8143.982002249719, 8164.510129392255, 8926.73219803407, 11887.0, 12708.555200567936, 13017.674558136123, 13021.609351719464, 10937.945310273397, 10986.505607247533, 11006.908687349378, 11026.228164028442, 11209.383483908463, 9019.909800901907, 10909.945450272699, 10932.453377330943, 11033.613823516138, 11207.271527350696, 11106.222564420414, 11253.437328133725, 11394.48724807369, 11434.0, 11673.299602023906, 8084.393670474856, 8161.020677518736, 8169.959150204211, 8461.323094152576, 8513.233808956977, 11123.443827808738, 11286.15353848481, 11479.311241325548, 11710.648680539598, 11720.238184517979], "value": 8902.72505650788, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.2753554383949, 1548.380647740904, 1734.9479515614537, 1751.8773685841995, 1903.6954087346019, 7871.527708337504, 8488.66045358185, 8693.130686931312, 8924.152205540471, 8938.910610893889, 8515.78710532237, 8593.097724738891, 8689.565521723904, 8713.825723485526, 8739.519326437043, 2440.438699099204, 2453.9631905521433, 2457.631355296711, 2460.59400198967, 2463.2610216934936, 2403.9542798882453, 2470.4318006858434, 2471.505698860226, 2487.502499500098, 2496.5256601245705, 2430.8419952703016, 2443.5723748344058, 2479.293401380607, 2479.504099180171, 2501.3246423465653, 2446.90212391505, 2461.212412028152, 2479.690038745157, 2484.9751502484924, 2498.7376325485834, 2449.3876530867283, 2482.863442510661, 2484.8757562121823, 2489.614109812982, 2490.96624900666, 8961.238294744955, 8985.281177505783, 8999.730008099768, 9011.639534418615, 9091.999880013193, 5336.546393556552, 5440.863978400532, 5500.50495455411, 5546.611737178399, 5547.944520554784, 3532.2229109595846, 3618.8914332570066, 3636.799976001319, 3648.981755091234, 3728.142527218755, 1043.2436483549366, 1055.2665897201448, 1055.59887242848, 1056.4084112896803, 1057.0, 11888.643340699793, 12082.758344833142, 12097.516099356015, 12103.515859365614, 12103.636890893375, 4702.670813043054, 4796.5203479651755, 4842.661013729075, 4852.223644216909, 4892.412910450768, 10208.081272685495, 10238.129758970423, 10238.53926573306, 10258.743531411774, 10258.846117308247, 5443.455654434604, 5455.754491047913, 5470.56235501159, 5494.2857428534835, 5511.145772405283, 4383.649308055348, 4762.618990480753, 4864.294677271776, 4897.2654101885, 4955.801767929314, 4042.818073186655, 4046.615571520701, 4111.218868414991, 4117.382392641068, 4173.645240154621, 1130.5025788652965, 1139.780434934612, 1308.9803652945213, 1310.5151094095056, 1320.9009324300716, 913.9268858491305, 915.9084091590788, 925.796324808541, 936.6721647423484, 936.9765755856158, 4950.975245123824, 5681.57388195887, 5786.479216870502, 5861.589688721749, 5874.676892770939, 397.530913522041, 451.905099929012, 469.20469803683113, 470.76226505614443, 475.372508289062, 3139.21519620095, 3860.131470419133, 3863.864764733221, 3865.40086286626, 3897.707671924617, 10364.222683298784, 10578.947105264579, 10710.0, 10779.191560632984, 10800.1899857511, 10634.840477392845, 10673.306235094695, 10696.78606427867, 10822.296550724177, 10833.512491937881, 8071.515709057593, 8353.579891418474, 8356.582170891432, 8364.247217750431, 8370.995480542373, 5599.356074051373, 5648.0, 5668.914966275592, 5705.773258749389, 5766.5675074368755, 9306.534673266446, 9425.434473931586, 9474.24206063527, 9575.99452057524, 9579.760505987406, 9199.632014719604, 9565.713028609152, 9614.03859614033, 9645.324827262024, 9719.125278724949, 6822.624755638289, 7279.781606551812, 7461.776146715607, 7504.699812007459, 7669.233076692286, 6979.267176946348, 8263.0, 8303.086660467465, 8307.916920830714, 8345.54099524532, 7262.6731797068205, 7372.152202496782, 7374.041374621265, 7475.7009719610605, 7493.726066568587, 7248.855022899407, 7275.527090739085, 7328.706851726084, 7382.187959324363, 7385.556866588022, 7096.361327480551, 7104.431645468453, 7122.7507037253445, 7128.501004929605, 7137.250588688113, 4833.178359678793, 4847.127517046965, 4859.246816743269, 4936.086823937583, 4949.82675606352, 4638.6752927294765, 4897.436794768505, 4914.213725803854, 4939.382577177853, 5125.410577783454], "value": 4523.927941192338, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.350871240095, 321.3653035255371, 328.90966446230743, 328.92763592009766, 334.2729563200039, 6911.2743161967965, 6949.444044476442, 6985.545939513928, 7010.719571217158, 7028.332308430704, 6885.0, 6900.480754815056, 6903.930960690392, 6906.171259448861, 6911.995521298817, 4773.284007398901, 4846.9515304846855, 4978.875528111808, 4988.176950803131, 4991.825286114987, 4933.161362568354, 4972.428170760355, 4977.55202031817, 4977.900441991176, 5001.524855138774, 4907.312976183337, 4914.70511769295, 4928.236123400879, 4936.593070974773, 4943.209086546153, 4906.509349065101, 4913.901721965576, 4928.876778080541, 4953.2074868020945, 4979.925301120486, 4253.74477531346, 4284.464441944757, 4290.69965102443, 4293.012607100353, 4311.504177019651, 6364.872702545923, 6678.4991125665765, 6693.263740988487, 6697.129373181504, 6717.361850624183, 5836.649801011953, 5851.795187168429, 5864.179014937912, 5874.118882167709, 5875.441833025899, 5965.254343207099, 6358.713857876407, 7380.604921736008, 7395.741149059757, 7411.4441416893405, 3692.46459263408, 3869.690424766012, 4641.7911193996015, 4664.836730714443, 4679.578837904571, 7543.2079631638435, 7763.922360776376, 7816.257455541714, 7835.764927052198, 7842.98041254639, 4680.040591678698, 4707.976460117678, 4718.339432479455, 4723.645726570521, 4764.309175169581, 3958.802059897051, 4086.203190377848, 4775.331453596499, 4786.712797232177, 4812.205986012269, 5795.362510123883, 5795.8551036224435, 5810.5061069809535, 5817.447342502455, 5840.766369345263, 5984.970075149597, 7382.630868456556, 7398.815029624302, 7407.555546667218, 7435.962820185864, 1648.282996896354, 1668.457751230855, 1671.097607292073, 1672.4480921295942, 1678.6726588315405, 1047.3454091193005, 1064.590132798878, 1073.9409332486632, 1127.6975093766548, 1135.3982389333667, 6756.0, 6823.454123670094, 6834.0, 6866.450683945272, 6919.308069193041, 7388.113426388868, 7646.3882889368715, 7691.50005249657, 7711.228877112356, 7748.070231572247, 6907.171139463297, 6946.548474349152, 6946.6179360135675, 6951.957206418976, 6987.196472405739, 4059.6752259819145, 4098.549159592442, 4106.67146628269, 4125.257453658311, 4127.968007998001, 7063.540869843444, 8152.144024877421, 8152.714654987047, 8156.143604921516, 8173.386995975326, 6502.934970650327, 6516.804495865132, 7708.344790692854, 8065.314448271845, 8084.434089613671, 8112.269895709414, 8154.38842086834, 8163.346932245406, 8176.550289733884, 8184.83630327402, 7835.960820195863, 7847.176046515034, 7861.371090312647, 7861.724839630814, 7878.84242315139, 6669.933300666931, 7245.456590755609, 7662.310392064518, 7677.155512893689, 7720.792168268195, 6984.441244700311, 7767.456278060482, 7824.4131690124605, 8169.346452283684, 8187.71343002992, 4656.254999200111, 4747.406574178228, 4754.928676069792, 4756.6908150970075, 4758.643101767312, 6336.714847831767, 7734.071911370672, 7762.844743105219, 7769.72805951789, 7811.960940195263, 7155.534890232118, 7481.625918703934, 7499.02512673349, 7502.512336698097, 7510.474266801271, 7466.589337586268, 7470.252974702486, 7473.850522989401, 7484.962575187089, 7495.325420712162, 7234.059572255573, 7288.708451662086, 7288.96355518219, 7289.125304963438, 7347.228541003117, 7479.70081196746, 7489.850202995801, 7493.250674932463, 7498.850022999619, 7506.9249307506225, 6298.84252893663, 6314.62112273265, 6999.265077167028, 7323.780286591411, 7336.523125996793], "value": 5320.6311170446215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.4329851588764, 673.3703986772367, 726.5277569579775, 745.7389913530266, 761.744815486812, 12296.139270251086, 14705.0, 14778.0, 14836.258187090643, 15073.924630376847, 11270.718232044203, 13669.863301366984, 13698.383572739225, 13702.862971370283, 13716.245606491639, 718.192033961793, 867.3104881619097, 892.3173772064376, 900.5542256583001, 935.39199520312, 885.1104639836987, 913.6162811619135, 923.7136487688838, 924.4638109896277, 927.2026057590467, 967.1005964453091, 982.3123813330661, 985.9007206964219, 986.4623780039896, 987.8863930647959, 968.6173961285323, 972.1833659725864, 972.5380444288942, 977.0132166511803, 977.31587888478, 712.5510928115306, 726.288237527221, 729.7591794707733, 733.6515155301242, 733.9522931009467, 11930.343831089287, 14422.350994205286, 14454.132752034806, 14504.129752214796, 14516.854831451656, 10654.200934929833, 12480.126391152622, 12489.562865299671, 12832.0, 12863.485460581565, 9818.26363022776, 10156.593736250541, 10186.643467478676, 10248.0, 10345.896541034568, 2895.4498645257336, 3449.793012419263, 3489.8102111469148, 3536.1513236823234, 3767.4348847672795, 82182.58908705418, 82934.0, 83253.58373208156, 85472.0, 85493.99999999937, 6774.72901083961, 9024.819503609891, 9567.282453815991, 9571.952140239255, 9616.663416780379, 20379.0, 20436.182552698025, 20615.69076463854, 20706.171753129707, 20708.275210367567, 7006.684699188447, 7201.819954501179, 7224.952381904594, 7325.047743793273, 7493.70025198986, 4058.2086493133556, 4129.132882094794, 4133.524644665842, 4134.338505839111, 4134.4831896013, 3601.7838929664304, 3671.6328367163605, 3692.280005398975, 3694.6674799268194, 3703.2963736889906, 539.7274376439873, 607.5534482155612, 653.7613770973655, 655.7901471529065, 657.1686816177539, 501.801788293622, 534.2973989204185, 571.7255717255657, 592.8843875444334, 597.0864577196895, 8184.54984975832, 8228.30059444942, 8276.255137037695, 8330.541820199947, 8524.488530688179, 454.55226601797864, 566.4420545762421, 601.8435206846164, 615.4061330815784, 615.9938400616026, 3611.2304970564232, 3849.4225866119746, 3893.805309734502, 3912.902177445587, 4107.507099148122, 65852.670736646, 66829.66340673256, 88015.0, 88997.55501222581, 89068.0, 59837.0, 69078.61842763117, 72851.54296914033, 73816.0, 74363.5127297451, 65251.0, 65752.67123664351, 66200.33799662038, 66273.0, 67361.64234251875, 8195.385346098947, 8377.45546539473, 8402.07577166499, 8426.915730842613, 8568.785780355542, 37054.0, 47392.763036184595, 47686.0, 47695.523044770496, 47988.0, 50748.731281718254, 51012.70442830156, 60103.69948150231, 63319.0, 65401.67299163474, 10627.415492148006, 10806.297590656897, 10884.61903833362, 11116.666500005012, 11158.44207789591, 11798.941005294919, 11984.64046078619, 12049.33728644898, 12057.276563406223, 12406.503739850305, 11181.256036722985, 11203.327800332007, 11259.380734059378, 11363.715907102389, 11508.194426390073, 10770.39711911577, 11164.330140191614, 11426.542938282708, 11632.592859250224, 12129.393530323272, 8973.327000475121, 10854.945725271324, 11108.166887483309, 11219.439028048402, 11499.8275025873, 9301.906980930375, 9387.249020078514, 9391.107844754737, 9484.905150948402, 9771.609135634495, 7783.143854175923, 8058.31702932732, 9196.954015229881, 9488.573014214238, 9806.068423499755], "value": 6715.899069654942, "unit": "score"}], "name": "dromaeo_css", "value": 7292.272516920749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 110.0, 118.0, 118.0, 118.0, 107.0, 117.0, 133.0, 154.0], "value": 120.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 140.0, 160.0, 175.0, 160.0, 139.0, 142.0, 130.0, 207.0, 149.0], "value": 155.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 198.0, 201.0, 204.0, 195.0, 200.0, 201.0, 199.0, 198.0], "value": 199.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 81.0, 74.0, 143.0, 67.0, 86.0, 82.0, 96.0, 128.0, 58.0], "value": 88.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 84.0, 124.0, 75.0, 78.0, 82.0, 115.0, 74.0, 73.0, 116.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 101.0, 86.0, 82.0, 130.0, 130.0, 86.0, 92.0, 86.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 95.0, 96.0, 95.0, 120.0, 116.0, 151.0, 92.0, 153.0, 90.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 132.0, 96.0, 142.0, 145.0, 89.0, 91.0, 92.0, 90.0, 95.0], "value": 105.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 66.0, 65.0, 65.0, 67.0, 66.0, 82.0, 71.0, 94.0], "value": 70.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 63.0, 72.0, 74.0, 80.0, 71.0, 71.0, 69.0, 68.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 81.0, 81.0, 80.0, 83.0, 83.0, 87.0, 81.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 129.0, 131.0, 137.0, 154.0, 141.0, 120.0, 156.0, 182.0, 150.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 159.0, 219.0, 223.0, 176.0, 167.0, 168.0, 167.0, 168.0, 215.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 64.0, 65.0, 71.0, 67.0, 70.0, 67.0, 68.0, 68.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1588.6000000000001, "alertThreshold": 2.0}]} 02:24:31 INFO - Return code: 0 02:24:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:24:31 ERROR - # TBPL SUCCESS # 02:24:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:24:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:24:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:24:31 INFO - 2016-05-28 02:24:31.229 screenresolution[2324:28247] starting screenresolution argv=screenresolution get 02:24:31 INFO - 2016-05-28 02:24:31.232 screenresolution[2324:28247] Display 0: 1600x1200x32@60 02:24:31 INFO - 2016-05-28 02:24:31.238 screenresolution[2325:28250] starting screenresolution argv=screenresolution list 02:24:31 INFO - Available Modes on Display 0 02:24:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:24:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:24:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:24:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:24:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:24:31 INFO - Intel Iris: 02:24:31 INFO - Chipset Model: Intel Iris 02:24:31 INFO - Type: GPU 02:24:31 INFO - Bus: Built-In 02:24:31 INFO - VRAM (Dynamic, Max): 1536 MB 02:24:31 INFO - Vendor: Intel (0x8086) 02:24:31 INFO - Device ID: 0x0a2e 02:24:31 INFO - Revision ID: 0x0009 02:24:31 INFO - Displays: 02:24:31 INFO - Display: 02:24:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:24:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:24:31 INFO - Display Serial Number: 1600x1200 60 02:24:31 INFO - Main Display: Yes 02:24:31 INFO - Mirror: Off 02:24:31 INFO - Online: Yes 02:24:31 INFO - Rotation: Supported 02:24:31 INFO - Return code: 0 02:24:31 INFO - Running post-action listener: _resource_record_post_action 02:24:31 INFO - Running post-run listener: _resource_record_post_run 02:24:32 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 1081344; Write bytes: 658511360; Read time: 12; Write time: 1946 02:24:32 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 350757376; Write bytes: 345708544; Read time: 27790; Write time: 596 02:24:32 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 1077248; Write bytes: 300029440; Read time: 12; Write time: 1226 02:24:32 INFO - Running post-run listener: _upload_blobber_files 02:24:32 INFO - Blob upload gear active. 02:24:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:24:32 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 02:24:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:24:32 INFO - (blobuploader) - INFO - Open directory for files ... 02:24:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:24:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:24:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:34 INFO - (blobuploader) - INFO - Done attempting. 02:24:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:24:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:24:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:34 INFO - (blobuploader) - INFO - Done attempting. 02:24:34 INFO - (blobuploader) - INFO - Iteration through files over. 02:24:35 INFO - Return code: 0 02:24:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:24:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:24:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/436157dfa3b352fb335d6746dc7d48260bb22f90a62abe58641bdf5a9c3a07924be337fe150d763f171c572a31c45b53fab0eecb41d3ac56e366b7faf3ea783e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d622a86e610d37e000e94e5b2085076f154003381f7cddfeccc5592b912dac19f87cb92dcf96aad4f74602b0df93c622086e66daa20bc58daf32f540e24e549"} 02:24:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:24:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:24:35 INFO - Contents: 02:24:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/436157dfa3b352fb335d6746dc7d48260bb22f90a62abe58641bdf5a9c3a07924be337fe150d763f171c572a31c45b53fab0eecb41d3ac56e366b7faf3ea783e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d622a86e610d37e000e94e5b2085076f154003381f7cddfeccc5592b912dac19f87cb92dcf96aad4f74602b0df93c622086e66daa20bc58daf32f540e24e549"} 02:24:35 INFO - Running post-run listener: copy_logs_to_upload_dir 02:24:35 INFO - Copying logs to upload dir... 02:24:35 INFO - mkdir: /builds/slave/test/build/upload/logs 02:24:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1335.412672 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2016-05-28 02:24:35.066745) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 02:24:35.069878) ========= 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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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/Mozilla-Aurora/sha512/436157dfa3b352fb335d6746dc7d48260bb22f90a62abe58641bdf5a9c3a07924be337fe150d763f171c572a31c45b53fab0eecb41d3ac56e366b7faf3ea783e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d622a86e610d37e000e94e5b2085076f154003381f7cddfeccc5592b912dac19f87cb92dcf96aad4f74602b0df93c622086e66daa20bc58daf32f540e24e549"} build_url:https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011488 build_url: 'https://queue.taskcluster.net/v1/task/WTWfWJPCQbyuK_akXjqKuw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/436157dfa3b352fb335d6746dc7d48260bb22f90a62abe58641bdf5a9c3a07924be337fe150d763f171c572a31c45b53fab0eecb41d3ac56e366b7faf3ea783e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4d622a86e610d37e000e94e5b2085076f154003381f7cddfeccc5592b912dac19f87cb92dcf96aad4f74602b0df93c622086e66daa20bc58daf32f540e24e549"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-28 02:24:35.100353) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 02:24:35.100698) ========= 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.mwkkbBpP7U/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.bYvq8FhKCI/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.005385 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-28 02:24:35.149513) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 02:24:35.149860) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-28 02:24:35.150270) ========= ========= Total master_lag: 0.17 =========