builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0119 starttime: 1467234987.59 results: success (0) buildid: 20160629120525 builduid: d4850ce80015440da859f8dc788e998f revision: 38d73d3e10e42d0a5b42f9fa6a42499b5e2746c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:27.589624) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:27.590140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:27.590428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:27.615493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:27.615869) ========= 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.YJRUt6xY0c/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.tnMeke8q3B/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-06-29 14:16:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.7M=0.001s 2016-06-29 14:16:27 (14.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140603 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:27.779133) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:27.779449) ========= 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.YJRUt6xY0c/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.tnMeke8q3B/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.030542 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:27.831237) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 14:16:27.831776) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 38d73d3e10e42d0a5b42f9fa6a42499b5e2746c9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 38d73d3e10e42d0a5b42f9fa6a42499b5e2746c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YJRUt6xY0c/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.tnMeke8q3B/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-06-29 14:16:27,904 truncating revision to first 12 chars 2016-06-29 14:16:27,904 Setting DEBUG logging. 2016-06-29 14:16:27,904 attempt 1/10 2016-06-29 14:16:27,904 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/38d73d3e10e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 14:16:29,059 unpacking tar archive at: larch-38d73d3e10e4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.419697 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 14:16:29.273356) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:29.273700) ========= script_repo_revision: 38d73d3e10e42d0a5b42f9fa6a42499b5e2746c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:29.274103) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:29.274413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 14:16:29.292182) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-06-29 14:16:29.292551) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YJRUt6xY0c/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.tnMeke8q3B/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:16:29 INFO - MultiFileLogger online at 20160629 14:16:29 in /builds/slave/test 14:16:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 14:16:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:16:29 INFO - {'append_to_log': False, 14:16:29 INFO - 'base_work_dir': '/builds/slave/test', 14:16:29 INFO - 'blob_upload_branch': 'Larch', 14:16:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:16:29 INFO - 'branch': 'Larch', 14:16:29 INFO - 'buildbot_json_path': 'buildprops.json', 14:16:29 INFO - 'config_files': ('talos/mac_config.py',), 14:16:29 INFO - 'default_actions': ('clobber', 14:16:29 INFO - 'read-buildbot-config', 14:16:29 INFO - 'download-and-extract', 14:16:29 INFO - 'populate-webroot', 14:16:29 INFO - 'create-virtualenv', 14:16:29 INFO - 'install', 14:16:29 INFO - 'run-tests'), 14:16:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:16:29 INFO - 'download_minidump_stackwalk': True, 14:16:29 INFO - 'download_symbols': 'ondemand', 14:16:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:16:29 INFO - 'tooltool.py': '/tools/tooltool.py', 14:16:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:16:29 INFO - '/tools/misc-python/virtualenv.py')}, 14:16:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:16:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:16:29 INFO - 'installer_path': 'installer.exe', 14:16:29 INFO - 'log_level': 'info', 14:16:29 INFO - 'log_name': 'talos', 14:16:29 INFO - 'log_to_console': True, 14:16:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:16:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:16:29 INFO - 'opt_config_files': (), 14:16:29 INFO - 'pip_index': False, 14:16:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:16:29 INFO - 'cmd': ('bash', 14:16:29 INFO - '-c', 14:16:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:16:29 INFO - 'enabled': True, 14:16:29 INFO - 'halt_on_failure': False, 14:16:29 INFO - 'name': 'check_screen_resolution'},), 14:16:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:16:29 INFO - 'cmd': ('bash', 14:16:29 INFO - '-c', 14:16:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:16:29 INFO - 'enabled': True, 14:16:29 INFO - 'halt_on_failure': False, 14:16:29 INFO - 'name': 'check_screen_resolution'},), 14:16:29 INFO - 'run_cmd_checks_enabled': True, 14:16:29 INFO - 'sps_profile': False, 14:16:29 INFO - 'sps_profile_interval': 0, 14:16:29 INFO - 'suite': 'dromaeojs', 14:16:29 INFO - 'system_bits': '32', 14:16:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:16:29 INFO - 'title': 't-yosemite-r7-0119', 14:16:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:16:29 INFO - 'use_talos_json': True, 14:16:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:16:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:16:29 INFO - 'work_dir': 'build'} 14:16:29 INFO - ##### 14:16:29 INFO - ##### Running clobber step. 14:16:29 INFO - ##### 14:16:29 INFO - Running pre-action listener: _resource_record_pre_action 14:16:29 INFO - Running main action method: clobber 14:16:29 INFO - rmtree: /builds/slave/test/build 14:16:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:16:31 INFO - Running post-action listener: _resource_record_post_action 14:16:31 INFO - ##### 14:16:31 INFO - ##### Running read-buildbot-config step. 14:16:31 INFO - ##### 14:16:31 INFO - Running pre-action listener: _resource_record_pre_action 14:16:31 INFO - Running main action method: read_buildbot_config 14:16:31 INFO - Using buildbot properties: 14:16:31 INFO - { 14:16:31 INFO - "project": "", 14:16:31 INFO - "product": "firefox", 14:16:31 INFO - "script_repo_revision": "production", 14:16:31 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 14:16:31 INFO - "repository": "", 14:16:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 14:16:31 INFO - "buildid": "20160629120525", 14:16:31 INFO - "pgo_build": "False", 14:16:31 INFO - "basedir": "/builds/slave/test", 14:16:31 INFO - "buildnumber": 0, 14:16:31 INFO - "slavename": "t-yosemite-r7-0119", 14:16:31 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:16:31 INFO - "platform": "yosemite_r7", 14:16:31 INFO - "scheduler": "tests-larch-macosx64-talos", 14:16:31 INFO - "branch": "larch", 14:16:31 INFO - "repo_path": "projects/larch", 14:16:31 INFO - "revision": "38d73d3e10e42d0a5b42f9fa6a42499b5e2746c9", 14:16:31 INFO - "stage_platform": "macosx64", 14:16:31 INFO - "builduid": "d4850ce80015440da859f8dc788e998f", 14:16:31 INFO - "slavebuilddir": "test" 14:16:31 INFO - } 14:16:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg. 14:16:31 INFO - Running post-action listener: _resource_record_post_action 14:16:31 INFO - ##### 14:16:31 INFO - ##### Running download-and-extract step. 14:16:31 INFO - ##### 14:16:31 INFO - Running pre-action listener: _resource_record_pre_action 14:16:31 INFO - Running main action method: download_and_extract 14:16:31 INFO - mkdir: /builds/slave/test/build/tests 14:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:16:31 INFO - https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:16:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.test_packages.json 14:16:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.test_packages.json 14:16:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.2.en-US.mac.test_packages.json 14:16:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.2.en-US.mac.test_packages.json'}, attempt #1 14:16:32 INFO - Downloaded 1277 bytes. 14:16:32 INFO - Reading from file /builds/slave/test/build/firefox-47.0.2.en-US.mac.test_packages.json 14:16:32 INFO - Using the following test package requirements: 14:16:32 INFO - {u'common': [u'firefox-47.0.2.en-US.mac.common.tests.zip'], 14:16:32 INFO - u'cppunittest': [u'firefox-47.0.2.en-US.mac.common.tests.zip', 14:16:32 INFO - u'firefox-47.0.2.en-US.mac.cppunittest.tests.zip'], 14:16:32 INFO - u'gtest': [u'firefox-47.0.2.en-US.mac.common.tests.zip', 14:16:32 INFO - u'firefox-47.0.2.en-US.mac.gtest.tests.zip'], 14:16:32 INFO - u'jittest': [u'firefox-47.0.2.en-US.mac.common.tests.zip', 14:16:32 INFO - u'jsshell-mac.zip'], 14:16:32 INFO - u'mochitest': [u'firefox-47.0.2.en-US.mac.common.tests.zip', 14:16:32 INFO - u'firefox-47.0.2.en-US.mac.mochitest.tests.zip'], 14:16:32 INFO - u'mozbase': [u'firefox-47.0.2.en-US.mac.common.tests.zip'], 14:16:32 INFO - u'reftest': [u'firefox-47.0.2.en-US.mac.common.tests.zip', 14:16:32 INFO - u'firefox-47.0.2.en-US.mac.reftest.tests.zip'], 14:16:32 INFO - u'talos': [u'firefox-47.0.2.en-US.mac.common.tests.zip', 14:16:32 INFO - u'firefox-47.0.2.en-US.mac.talos.tests.zip'], 14:16:32 INFO - u'web-platform': [u'firefox-47.0.2.en-US.mac.common.tests.zip', 14:16:32 INFO - u'firefox-47.0.2.en-US.mac.web-platform.tests.zip'], 14:16:32 INFO - u'webapprt': [u'firefox-47.0.2.en-US.mac.common.tests.zip'], 14:16:32 INFO - u'xpcshell': [u'firefox-47.0.2.en-US.mac.common.tests.zip', 14:16:32 INFO - u'firefox-47.0.2.en-US.mac.xpcshell.tests.zip']} 14:16:32 INFO - Downloading packages: [u'firefox-47.0.2.en-US.mac.common.tests.zip'] for test suite category: common 14:16:32 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')]} 14:16:32 INFO - https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:16:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip 14:16:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip 14:16:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.2.en-US.mac.common.tests.zip 14:16:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.2.en-US.mac.common.tests.zip'}, attempt #1 14:16:33 INFO - Downloaded 17990246 bytes. 14:16:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:16:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:16:37 INFO - Return code: 0 14:16:37 INFO - Downloading packages: [u'firefox-47.0.2.en-US.mac.common.tests.zip', u'firefox-47.0.2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:16:37 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')]} 14:16:37 INFO - https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:16:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip 14:16:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip 14:16:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.2.en-US.mac.common.tests.zip 14:16:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.2.en-US.mac.common.tests.zip'}, attempt #1 14:16:38 INFO - Downloaded 17990246 bytes. 14:16:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:16:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:16:41 INFO - Return code: 0 14:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:41 INFO - https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:16:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.talos.tests.zip 14:16:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.talos.tests.zip 14:16:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.2.en-US.mac.talos.tests.zip 14:16:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.2.en-US.mac.talos.tests.zip'}, attempt #1 14:16:42 INFO - Downloaded 10845194 bytes. 14:16:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:16:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:16:43 INFO - Return code: 0 14:16:43 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')]} 14:16:43 INFO - https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg matches https://queue.taskcluster.net 14:16:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg 14:16:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg 14:16:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.2.en-US.mac.dmg 14:16:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.2.en-US.mac.dmg'}, attempt #1 14:16:46 INFO - Downloaded 115402214 bytes. 14:16:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg 14:16:46 INFO - mkdir: /builds/slave/test/properties 14:16:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:16:46 INFO - Writing to file /builds/slave/test/properties/build_url 14:16:46 INFO - Contents: 14:16:46 INFO - build_url:https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg 14:16:47 INFO - Running post-action listener: _resource_record_post_action 14:16:47 INFO - Running post-action listener: set_extra_try_arguments 14:16:47 INFO - ##### 14:16:47 INFO - ##### Running populate-webroot step. 14:16:47 INFO - ##### 14:16:47 INFO - Running pre-action listener: _resource_record_pre_action 14:16:47 INFO - Running main action method: populate_webroot 14:16:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:16:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:16:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:16:47 INFO - '--tppagecycles', 14:16:47 INFO - '7'], 14:16:47 INFO - 'tests': ['tsvgm']}}, 14:16:47 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:16:47 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:16:47 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 14:16:47 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:16:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:16:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:47 INFO - 'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:16:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:47 INFO - 'tests': ['damp', 'tps']}, 14:16:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:47 INFO - 'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['damp', 'tps']}, 14:16:47 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:16:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:16:47 INFO - 'other': {'tests': ['a11yr', 14:16:47 INFO - 'ts_paint', 14:16:47 INFO - 'tpaint', 14:16:47 INFO - 'sessionrestore', 14:16:47 INFO - 'sessionrestore_no_auto_restore', 14:16:47 INFO - 'tabpaint']}, 14:16:47 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['a11yr', 14:16:47 INFO - 'ts_paint', 14:16:47 INFO - 'tpaint', 14:16:47 INFO - 'sessionrestore', 14:16:47 INFO - 'sessionrestore_no_auto_restore', 14:16:47 INFO - 'tabpaint']}, 14:16:47 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['a11yr', 14:16:47 INFO - 'ts_paint', 14:16:47 INFO - 'tpaint', 14:16:47 INFO - 'sessionrestore', 14:16:47 INFO - 'sessionrestore_no_auto_restore', 14:16:47 INFO - 'tabpaint']}, 14:16:47 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['a11yr', 14:16:47 INFO - 'ts_paint', 14:16:47 INFO - 'tpaint', 14:16:47 INFO - 'sessionrestore', 14:16:47 INFO - 'sessionrestore_no_auto_restore', 14:16:47 INFO - 'tabpaint']}, 14:16:47 INFO - 'other_l64': {'tests': ['a11yr', 14:16:47 INFO - 'ts_paint', 14:16:47 INFO - 'tpaint', 14:16:47 INFO - 'sessionrestore', 14:16:47 INFO - 'sessionrestore_no_auto_restore', 14:16:47 INFO - 'tabpaint']}, 14:16:47 INFO - 'other_nol64': {'tests': ['a11yr', 14:16:47 INFO - 'ts_paint', 14:16:47 INFO - 'tpaint', 14:16:47 INFO - 'sessionrestore', 14:16:47 INFO - 'sessionrestore_no_auto_restore', 14:16:47 INFO - 'tabpaint']}, 14:16:47 INFO - 'svgr': {'tests': ['tsvgx', 14:16:47 INFO - 'tsvgr_opacity', 14:16:47 INFO - 'tart', 14:16:47 INFO - 'tscrollx', 14:16:47 INFO - 'cart']}, 14:16:47 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['tsvgx', 14:16:47 INFO - 'tsvgr_opacity', 14:16:47 INFO - 'tart', 14:16:47 INFO - 'tscrollx', 14:16:47 INFO - 'cart']}, 14:16:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:47 INFO - 'tests': ['tp5o']}, 14:16:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:47 INFO - 'talos_options': ['--e10s'], 14:16:47 INFO - 'tests': ['tp5o']}, 14:16:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:47 INFO - 'talos_options': ['--xperf_path', 14:16:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:16:47 INFO - 'tests': ['tp5n']}, 14:16:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:16:47 INFO - 'talos_options': ['--e10s', 14:16:47 INFO - '--xperf_path', 14:16:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:16:47 INFO - 'tests': ['tp5n']}}, 14:16:47 INFO - 'talos.zip': {'path': '', 14:16:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:16:47 INFO - Running post-action listener: _resource_record_post_action 14:16:47 INFO - ##### 14:16:47 INFO - ##### Running create-virtualenv step. 14:16:47 INFO - ##### 14:16:47 INFO - Running pre-action listener: _resource_record_pre_action 14:16:47 INFO - Running main action method: create_virtualenv 14:16:47 INFO - Creating virtualenv /builds/slave/test/build/venv 14:16:47 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 14:16:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:16:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:16:47 INFO - Using real prefix '/tools/python27' 14:16:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:16:48 INFO - Installing distribute.............................................................................................................................................................................................done. 14:16:50 INFO - Installing pip.................done. 14:16:50 INFO - Return code: 0 14:16:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:50 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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:16:50 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 14:16:50 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 14:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:16:50 INFO - 'HOME': '/Users/cltbld', 14:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:16:50 INFO - 'LOGNAME': 'cltbld', 14:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:50 INFO - 'MOZ_NO_REMOTE': '1', 14:16:50 INFO - 'NO_EM_RESTART': '1', 14:16:50 INFO - 'PAGER': '/bin/cat', 14:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:16:50 INFO - 'PWD': '/builds/slave/test', 14:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:16:50 INFO - 'SHELL': '/bin/bash', 14:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:16:50 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:16:50 INFO - 'USER': 'cltbld', 14:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:50 INFO - 'XPC_FLAGS': '0x0', 14:16:50 INFO - 'XPC_SERVICE_NAME': '0', 14:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:51 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 14:16:51 INFO - Cleaning up... 14:16:51 INFO - Return code: 0 14:16:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:51 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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:16:51 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 14:16:51 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 14:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:16:51 INFO - 'HOME': '/Users/cltbld', 14:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:16:51 INFO - 'LOGNAME': 'cltbld', 14:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:51 INFO - 'MOZ_NO_REMOTE': '1', 14:16:51 INFO - 'NO_EM_RESTART': '1', 14:16:51 INFO - 'PAGER': '/bin/cat', 14:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:16:51 INFO - 'PWD': '/builds/slave/test', 14:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:16:51 INFO - 'SHELL': '/bin/bash', 14:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:16:51 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:16:51 INFO - 'USER': 'cltbld', 14:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:51 INFO - 'XPC_FLAGS': '0x0', 14:16:51 INFO - 'XPC_SERVICE_NAME': '0', 14:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:51 INFO - Downloading/unpacking psutil>=0.7.1 14:16:51 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 14:16:51 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 14:16:51 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 14:16:51 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 14:16:51 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 14:16:51 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 14:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:16:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:16:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:16:54 INFO - Installing collected packages: psutil 14:16:54 INFO - Running setup.py install for psutil 14:16:54 INFO - building 'psutil._psutil_osx' extension 14:16:54 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 14:16:55 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 14:16:55 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 14:16:55 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 14:16:55 INFO - building 'psutil._psutil_posix' extension 14:16:55 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 14:16:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:16:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:16:55 INFO - ^ 14:16:55 INFO - 1 warning generated. 14:16:55 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 14:16:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:16:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:16:55 INFO - Successfully installed psutil 14:16:55 INFO - Cleaning up... 14:16:55 INFO - Return code: 0 14:16:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16:55 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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:16:55 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 14:16:55 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 14:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:16:55 INFO - 'HOME': '/Users/cltbld', 14:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:16:55 INFO - 'LOGNAME': 'cltbld', 14:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:55 INFO - 'MOZ_NO_REMOTE': '1', 14:16:55 INFO - 'NO_EM_RESTART': '1', 14:16:55 INFO - 'PAGER': '/bin/cat', 14:16:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:16:55 INFO - 'PWD': '/builds/slave/test', 14:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:16:55 INFO - 'SHELL': '/bin/bash', 14:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:16:55 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:16:55 INFO - 'USER': 'cltbld', 14:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:55 INFO - 'XPC_FLAGS': '0x0', 14:16:55 INFO - 'XPC_SERVICE_NAME': '0', 14:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:16:55 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 14:16:55 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 14:16:55 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 14:16:55 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 14:16:55 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 14:16:55 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 14:16:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:16:58 INFO - Installing collected packages: mozsystemmonitor 14:16:58 INFO - Running setup.py install for mozsystemmonitor 14:16:58 INFO - Successfully installed mozsystemmonitor 14:16:58 INFO - Cleaning up... 14:16:58 INFO - Return code: 0 14:16:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:16: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')]} 14:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16: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')]} 14:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:16: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', '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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:16: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 blobuploader==1.2.4 14:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:16:58 INFO - 'HOME': '/Users/cltbld', 14:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:16:58 INFO - 'LOGNAME': 'cltbld', 14:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:58 INFO - 'MOZ_NO_REMOTE': '1', 14:16:58 INFO - 'NO_EM_RESTART': '1', 14:16:58 INFO - 'PAGER': '/bin/cat', 14:16:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:16:58 INFO - 'PWD': '/builds/slave/test', 14:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:16:58 INFO - 'SHELL': '/bin/bash', 14:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:16:58 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:16:58 INFO - 'USER': 'cltbld', 14:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:58 INFO - 'XPC_FLAGS': '0x0', 14:16:58 INFO - 'XPC_SERVICE_NAME': '0', 14:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:16:58 INFO - Downloading/unpacking blobuploader==1.2.4 14:16: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 14:16: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 14:16: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 14:16: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 14:16: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 14:16: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 14:17:01 INFO - Downloading blobuploader-1.2.4.tar.gz 14:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:17:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:17:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:17:02 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 14:17:02 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 14:17:02 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 14:17:02 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 14:17:02 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 14:17:02 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 14:17:02 INFO - Downloading docopt-0.6.1.tar.gz 14:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:17:02 INFO - Installing collected packages: blobuploader, requests, docopt 14:17:02 INFO - Running setup.py install for blobuploader 14:17:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:17:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:17:02 INFO - Running setup.py install for requests 14:17:03 INFO - Running setup.py install for docopt 14:17:03 INFO - Successfully installed blobuploader requests docopt 14:17:03 INFO - Cleaning up... 14:17:03 INFO - Return code: 0 14:17:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:03 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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:17:03 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 14:17:03 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 14:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:03 INFO - 'HOME': '/Users/cltbld', 14:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:03 INFO - 'LOGNAME': 'cltbld', 14:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:03 INFO - 'MOZ_NO_REMOTE': '1', 14:17:03 INFO - 'NO_EM_RESTART': '1', 14:17:03 INFO - 'PAGER': '/bin/cat', 14:17:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:03 INFO - 'PWD': '/builds/slave/test', 14:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:03 INFO - 'SHELL': '/bin/bash', 14:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:17:03 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:03 INFO - 'USER': 'cltbld', 14:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:03 INFO - 'XPC_FLAGS': '0x0', 14:17:03 INFO - 'XPC_SERVICE_NAME': '0', 14:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:03 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-i13bCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-uyOrAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-VKxA90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-AiZhK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-9HLmp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-FZjsQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-UxdKAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-NBsJGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-xiNJTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-TJa_0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:04 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-9R49nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-gxjuqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-W5idXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-QmF1i3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Ju_APr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-j5tpHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:05 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-UOl85Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:17:05 INFO - Running setup.py install for manifestparser 14:17:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:17:05 INFO - Running setup.py install for mozcrash 14:17:05 INFO - Running setup.py install for mozdebug 14:17:06 INFO - Running setup.py install for mozdevice 14:17:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:17:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:17:06 INFO - Running setup.py install for mozfile 14:17:06 INFO - Running setup.py install for mozhttpd 14:17:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:17:06 INFO - Running setup.py install for mozinfo 14:17:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:17:06 INFO - Running setup.py install for mozInstall 14:17:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:17:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:17:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:17:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:17:06 INFO - Running setup.py install for mozleak 14:17:07 INFO - Running setup.py install for mozlog 14:17:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:17:07 INFO - Running setup.py install for moznetwork 14:17:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:17:07 INFO - Running setup.py install for mozprocess 14:17:07 INFO - Running setup.py install for mozprofile 14:17:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:17:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:17:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:17:07 INFO - Running setup.py install for mozrunner 14:17:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:17:07 INFO - Running setup.py install for mozscreenshot 14:17:07 INFO - Running setup.py install for moztest 14:17:08 INFO - Running setup.py install for mozversion 14:17:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:17:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:17:08 INFO - Cleaning up... 14:17:08 INFO - Return code: 0 14:17:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:08 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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:17:08 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 14:17:08 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 14:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:08 INFO - 'HOME': '/Users/cltbld', 14:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:08 INFO - 'LOGNAME': 'cltbld', 14:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:08 INFO - 'MOZ_NO_REMOTE': '1', 14:17:08 INFO - 'NO_EM_RESTART': '1', 14:17:08 INFO - 'PAGER': '/bin/cat', 14:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:08 INFO - 'PWD': '/builds/slave/test', 14:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:08 INFO - 'SHELL': '/bin/bash', 14:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:17:08 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:08 INFO - 'USER': 'cltbld', 14:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:08 INFO - 'XPC_FLAGS': '0x0', 14:17:08 INFO - 'XPC_SERVICE_NAME': '0', 14:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-NGuWOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:08 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)) 14:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-hanfmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:08 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)) 14:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-OrlCvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:08 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)) 14:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:08 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-PWDm_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-QtYtBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-KE5HIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-b24jub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-9keORC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-kGCt7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-SNXFt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-t4WNyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-amxRBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:09 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)) 14:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:09 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-N1ZUR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:10 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)) 14:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:10 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-y0ld_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:10 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)) 14:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:10 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-H5v5Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:10 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)) 14:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:10 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-sueaAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:10 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)) 14:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:10 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ULvpf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:10 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)) 14:17:10 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)) 14:17:10 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)) 14:17:10 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)) 14:17:10 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)) 14:17:10 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)) 14:17:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:12 INFO - Downloading blessings-1.6.tar.gz 14:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:17:13 INFO - Installing collected packages: blessings 14:17:13 INFO - Running setup.py install for blessings 14:17:13 INFO - Successfully installed blessings 14:17:13 INFO - Cleaning up... 14:17:13 INFO - Return code: 0 14:17:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:17:13 INFO - Reading from file tmpfile_stdout 14:17:13 INFO - Current package versions: 14:17:13 INFO - blessings == 1.6 14:17:13 INFO - blobuploader == 1.2.4 14:17:13 INFO - docopt == 0.6.1 14:17:13 INFO - manifestparser == 1.1 14:17:13 INFO - mozInstall == 1.12 14:17:13 INFO - mozcrash == 0.17 14:17:13 INFO - mozdebug == 0.1 14:17:13 INFO - mozdevice == 0.48 14:17:13 INFO - mozfile == 1.2 14:17:13 INFO - mozhttpd == 0.7 14:17:13 INFO - mozinfo == 0.9 14:17:13 INFO - mozleak == 0.1 14:17:13 INFO - mozlog == 3.1 14:17:13 INFO - moznetwork == 0.27 14:17:13 INFO - mozprocess == 0.22 14:17:13 INFO - mozprofile == 0.28 14:17:13 INFO - mozrunner == 6.11 14:17:13 INFO - mozscreenshot == 0.1 14:17:13 INFO - mozsystemmonitor == 0.0 14:17:13 INFO - moztest == 0.7 14:17:13 INFO - mozversion == 1.4 14:17:13 INFO - psutil == 3.1.1 14:17:13 INFO - requests == 1.2.3 14:17:13 INFO - wsgiref == 0.1.2 14:17:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:13 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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:17:13 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 14:17:13 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 14:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:13 INFO - 'HOME': '/Users/cltbld', 14:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:13 INFO - 'LOGNAME': 'cltbld', 14:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:13 INFO - 'MOZ_NO_REMOTE': '1', 14:17:13 INFO - 'NO_EM_RESTART': '1', 14:17:13 INFO - 'PAGER': '/bin/cat', 14:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:13 INFO - 'PWD': '/builds/slave/test', 14:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:13 INFO - 'SHELL': '/bin/bash', 14:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:17:13 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:13 INFO - 'USER': 'cltbld', 14:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:13 INFO - 'XPC_FLAGS': '0x0', 14:17:13 INFO - 'XPC_SERVICE_NAME': '0', 14:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 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)) 14:17:13 INFO - Cleaning up... 14:17:13 INFO - Return code: 0 14:17:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:13 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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:17:13 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 14:17:13 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 14:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:13 INFO - 'HOME': '/Users/cltbld', 14:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:13 INFO - 'LOGNAME': 'cltbld', 14:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:13 INFO - 'MOZ_NO_REMOTE': '1', 14:17:13 INFO - 'NO_EM_RESTART': '1', 14:17:13 INFO - 'PAGER': '/bin/cat', 14:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:13 INFO - 'PWD': '/builds/slave/test', 14:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:13 INFO - 'SHELL': '/bin/bash', 14:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:17:13 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:13 INFO - 'USER': 'cltbld', 14:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:13 INFO - 'XPC_FLAGS': '0x0', 14:17:13 INFO - 'XPC_SERVICE_NAME': '0', 14:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:14 INFO - Downloading/unpacking jsonschema 14:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:17:16 INFO - Downloading/unpacking functools32 (from jsonschema) 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17:16 INFO - Downloading functools32-3.2.3-2.tar.gz 14:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:17:17 INFO - warning: no files found matching '*.txt' 14:17:17 INFO - no previously-included directories found matching 'build' 14:17:17 INFO - no previously-included directories found matching 'dist' 14:17:17 INFO - no previously-included directories found matching '.git*' 14:17:17 INFO - Installing collected packages: jsonschema, functools32 14:17:17 INFO - Running setup.py install for functools32 14:17:17 INFO - warning: no files found matching '*.txt' 14:17:17 INFO - no previously-included directories found matching 'build' 14:17:17 INFO - no previously-included directories found matching 'dist' 14:17:17 INFO - no previously-included directories found matching '.git*' 14:17:17 INFO - Successfully installed jsonschema functools32 14:17:17 INFO - Cleaning up... 14:17:17 INFO - Return code: 0 14:17:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:17 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 0x104a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a7d4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3cacb1f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, '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.YJRUt6xY0c/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.tnMeke8q3B/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 14:17:17 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 14:17:17 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 14:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:17 INFO - 'HOME': '/Users/cltbld', 14:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:17 INFO - 'LOGNAME': 'cltbld', 14:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:17 INFO - 'MOZ_NO_REMOTE': '1', 14:17:17 INFO - 'NO_EM_RESTART': '1', 14:17:17 INFO - 'PAGER': '/bin/cat', 14:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:17 INFO - 'PWD': '/builds/slave/test', 14:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:17 INFO - 'SHELL': '/bin/bash', 14:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:17:17 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:17 INFO - 'USER': 'cltbld', 14:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:17 INFO - 'XPC_FLAGS': '0x0', 14:17:17 INFO - 'XPC_SERVICE_NAME': '0', 14:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:17 INFO - Downloading/unpacking flake8 14:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:17:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:17:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17: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 14:17:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:17:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:17:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:17:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:17:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:17:21 INFO - no previously-included directories found matching 'docs/_build' 14:17:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:17:21 INFO - Running setup.py install for pep8 14:17:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:17:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:17:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:17:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:17:21 INFO - no previously-included directories found matching 'docs/_build' 14:17:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:17:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:17:21 INFO - Cleaning up... 14:17:21 INFO - Return code: 0 14:17:21 INFO - Running post-action listener: _resource_record_post_action 14:17:21 INFO - Running post-action listener: _start_resource_monitoring 14:17:21 INFO - Starting resource monitoring. 14:17:21 INFO - ##### 14:17:21 INFO - ##### Running install step. 14:17:21 INFO - ##### 14:17:21 INFO - Running pre-action listener: _resource_record_pre_action 14:17:21 INFO - Running main action method: install 14:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:17:21 INFO - Reading from file tmpfile_stdout 14:17:21 INFO - Detecting whether we're running mozinstall >=1.0... 14:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:17:22 INFO - Reading from file tmpfile_stdout 14:17:22 INFO - Output received: 14:17:22 INFO - Usage: mozinstall [options] installer 14:17:22 INFO - Options: 14:17:22 INFO - -h, --help show this help message and exit 14:17:22 INFO - -d DEST, --destination=DEST 14:17:22 INFO - Directory to install application into. [default: 14:17:22 INFO - "/builds/slave/test"] 14:17:22 INFO - --app=APP Application being installed. [default: firefox] 14:17:22 INFO - mkdir: /builds/slave/test/build/application 14:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:17:54 INFO - Reading from file tmpfile_stdout 14:17:54 INFO - Output received: 14:17:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:17:54 INFO - Running post-action listener: _resource_record_post_action 14:17:54 INFO - ##### 14:17:54 INFO - ##### Running run-tests step. 14:17:54 INFO - ##### 14:17:54 INFO - Running pre-action listener: _resource_record_pre_action 14:17:54 INFO - Running main action method: run_tests 14:17:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:17:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:17:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:17:54 INFO - 2016-06-29 14:17:54.174 screenresolution[1641:7473] starting screenresolution argv=screenresolution get 14:17:54 INFO - 2016-06-29 14:17:54.177 screenresolution[1641:7473] Display 0: 1600x1200x32@60 14:17:54 INFO - 2016-06-29 14:17:54.183 screenresolution[1642:7475] starting screenresolution argv=screenresolution list 14:17:54 INFO - Available Modes on Display 0 14:17:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:17:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:17:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:17:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:17:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:17:54 INFO - Intel Iris: 14:17:54 INFO - Chipset Model: Intel Iris 14:17:54 INFO - Type: GPU 14:17:54 INFO - Bus: Built-In 14:17:54 INFO - VRAM (Dynamic, Max): 1536 MB 14:17:54 INFO - Vendor: Intel (0x8086) 14:17:54 INFO - Device ID: 0x0a2e 14:17:54 INFO - Revision ID: 0x0009 14:17:54 INFO - Displays: 14:17:54 INFO - Display: 14:17:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:17:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:17:54 INFO - Display Serial Number: 1600x1200 60 14:17:54 INFO - Main Display: Yes 14:17:54 INFO - Mirror: Off 14:17:54 INFO - Online: Yes 14:17:54 INFO - Rotation: Supported 14:17:54 INFO - Return code: 0 14:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:17:54 INFO - Python 2.7.3 14:17:54 INFO - Return code: 0 14:17:54 INFO - grabbing minidump binary from tooltool 14:17: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')]} 14:17:54 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 0x104919d30>, '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 0x10473ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104910b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:17:54 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 14:17:54 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 14:17:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:17:54 INFO - Return code: 0 14:17:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:17:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:17:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:17:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:17:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:17:55 INFO - Return code: 0 14:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YJRUt6xY0c/Render', 14:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:17:55 INFO - 'HOME': '/Users/cltbld', 14:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:17:55 INFO - 'LOGNAME': 'cltbld', 14:17:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:17:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:55 INFO - 'MOZ_NO_REMOTE': '1', 14:17:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:17:55 INFO - 'NO_EM_RESTART': '1', 14:17:55 INFO - 'PAGER': '/bin/cat', 14:17:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:55 INFO - 'PWD': '/builds/slave/test', 14:17:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:17:55 INFO - 'SHELL': '/bin/bash', 14:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnMeke8q3B/Listeners', 14:17:55 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 14:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:17:55 INFO - 'USER': 'cltbld', 14:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:55 INFO - 'XPC_FLAGS': '0x0', 14:17:55 INFO - 'XPC_SERVICE_NAME': '0', 14:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:17:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:17:55 INFO - mozversion application_buildid: 20160629120525 14:17:55 INFO - mozversion application_changeset: 38d73d3e10e42d0a5b42f9fa6a42499b5e2746c9 14:17:55 INFO - mozversion application_display_name: Nightly 14:17:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:17:55 INFO - mozversion application_name: Firefox 14:17:55 INFO - mozversion application_remotingname: firefox 14:17:55 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:17:55 INFO - mozversion application_vendor: Mozilla 14:17:55 INFO - mozversion application_version: 47.0.2 14:17:55 INFO - mozversion platform_buildid: 20160629120525 14:17:55 INFO - mozversion platform_changeset: 38d73d3e10e42d0a5b42f9fa6a42499b5e2746c9 14:17:55 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:17:55 INFO - mozversion platform_version: 47.0.2 14:17:55 INFO - using testdate: 1467235075 14:17:55 INFO - actual date: 1467235075 14:17:55 INFO - starting webserver on 'localhost:49275' 14:17:55 INFO - SUITE-START | Running 2 tests 14:17:55 INFO - TEST-START | dromaeo_css 14:17:55 INFO - operating with platform_type : mac_ 14:17:55 INFO - Initialising browser for dromaeo_css test... 14:17:55 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpsB1sHy/profile http://localhost:49275/getInfo.html) 14:17:58 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 14:17:58 INFO - PROCESS | 1660 | colorDepth:24 14:17:58 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 14:17:58 INFO - PROCESS | 1660 | __metrics 14:17:58 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:17:58 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:17:58 INFO - TEST-INFO | 1660: exit 0 14:17:58 INFO - Browser initialized. 14:17:58 INFO - Running cycle 1/1 for dromaeo_css test... 14:17:58 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpsB1sHy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:18:10 INFO - PROCESS | 1662 | RSS: Main: 184619008 14:18:10 INFO - PROCESS | 1662 | 14:19:01 INFO - PROCESS | 1662 | 1467235141715 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] 14:21:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 14:21:13 INFO - PROCESS | 1662 | RSS: Main: 324182016 14:21:13 INFO - PROCESS | 1662 | 14:24:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 14:24:16 INFO - PROCESS | 1662 | RSS: Main: 313348096 14:24:16 INFO - PROCESS | 1662 | 14:27:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 14:27:18 INFO - PROCESS | 1662 | RSS: Main: 323895296 14:27:18 INFO - PROCESS | 1662 | 14:30:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 14:30:21 INFO - PROCESS | 1662 | RSS: Main: 282333184 14:30:21 INFO - PROCESS | 1662 | 14:33:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 14:33:24 INFO - PROCESS | 1662 | RSS: Main: 285491200 14:33:24 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 14:36:27 INFO - PROCESS | 1662 | RSS: Main: 354177024 14:36:27 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | __start_tp_report 14:36:27 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 14:36:27 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 14:36:27 INFO - PROCESS | 1662 | |i|pagename|runs| 14:36:27 INFO - PROCESS | 1662 | |0;dojo.html;3132.6867313268667;3443.9483407748903;3476.6001909780384;3479.808610526421;3516.6834984851375;5544.362398324194;5546.86132846679;5551.77792888284;5565.554755619551;5565.972170139149;5537.141743029837;5571.164325351199;5586.469285417889;5593.916091258634;5646.971765141178;5020.673656212352;5067.493250674931;5082.339295891529;5129;5141.640085194038;5072.239164125393;5148;5152.304438900756;5155.252488389181;5162.096633089194;5071.518205770464;5124.4106927703415;5144;5144.511271429208;5152.845414637548;5126.666766660155;5165.044466773659;5167.534921857051;5168.32811734474;5212.530872221519;4901.607871370282;5070.72111033893;5079.6952182869145;5081.034603425374;5088.2876397304035;5456.727163641842;5459.344878614592;5489.039418101816;5500.4499550045075;5504.972475137639;5864.824055278348;6116.663583502906;6125.969370153165;6126.417990290915;6128.479079278267;6031.065184896348;6082.024823170938;6085.2393450818645;6104.786332478387;6109.908351374733;5584.553235741131;5607.523360514361;5655.632383895116;5669.518090962314;5683.687397193153;6269.404406581368;6298.401651843067;6312.621242725451;6313.652749098844;6323.494120470444;5925.73334199962;5957.076653118686;5974.4324289192455;5976.163337132805;5985.790497332572;5929.0513517836935;6107.389261073857;6150.876982460326;6189.597676151036;6195.473384762346;6011.729472173763;6073.605215660968;6075.726592303357;6080.361562036011;6082.513398928075;6122.081687746784;6125.877482450415;6131.724072416663;6131.908021379683;6139.907901381483;4061.573534778806;4694.295855621615;4804.06320767447;4809.927851082237;4848.466668666444;3741.420079887567;4308.246056940023;4327.913441731148;4335.024619460596;4368.847090351823;5557.694326811983;5562.499375056264;5564;5577.804776832872;5618.6628802272;5956.404359564009;6057.818265451955;6142.98993493194;6147;6154.784582539679;5565.9443405566235;5569.944300557024;5571.470710282517;5574.275344205227;5576.079946808731;5182.870428239325;5280.339957505312;5295.814646487354;5295.894082118336;5320.76056577455;6205.844853878689;6293.992961126198;6295.961166407492;6311.936880631135;6340.651264180515;6141.293751218667;6151.630902145886;6168.506519478521;6178.351273116438;6184.443400094013;6130.520435899211;6227.595206311757;6332.240131184288;6353.491720662429;6354.491640668828;5942.405759424023;5956.404359564009;5957.225560677258;5958.731857066529;5991.131285963598;6043.969780151071;6097.4817140543555;6099.878002440015;6112.327643959072;6119.44924956756;5794.768209271582;5875.383084776037;5882.088276317178;5883.411658834082;5917.911231331447;5965.194698715792;5973.970130149321;5976.850578735566;6005.39946005396;6014.278286605635;5640.5769567281795;5777.537796976148;5820.068788993741;5825.825225243249;5835.387284335084;5766.509846663081;5769.653820770767;5839.0949402842625;5903.085021821625;5927.140564618193;5699.601027928005;5700.743466543932;5736.0248757712125;5736.19693242938;5741.0527263002805 14:36:27 INFO - PROCESS | 1662 | |1;ext.html;490.4947903659232;596.2517041113402;608.1637748096367;664.5813137723235;667.5927684112692;29865.1040468786;31501.212469688267;31528.369432611333;38927.61072389275;39315.41026884599;37293.88118356454;38943.610563894355;39922;41703.582964170346;42430;9572.521373931311;9777.706668799947;9791.902080979205;9801.460919649417;9801.460919649417;9609;9737.707868763948;9743.756406089833;9746.707598772047;9761.902380976171;9704.41773493589;9771.169450596708;9811.95094024529;9873.60505579776;9895.50522473877;8422.28410585098;9840.163586095156;9866.50667466631;9869.161121304698;9874.55564499592;9695.612175512972;9822.557984890696;9833.213342932548;9833.31166818323;9835.114839664393;41261.587384126076;41653.791731041456;41663.166736665095;41745;41851.1629767406;3172.4924012157944;3212.4860022396306;3215.26049008727;3434.278801451698;3599.0822340303316;23079.653805192935;28746.712532874823;29233.41533169346;29286.267843303875;29552.113436596723;2756.324700448383;2766.3084228942766;2783.2902609834564;2799.678037025748;2803.887844486218;465993;466455.6677216626;467395;467442;467626;66334.66832665805;66900;67025;67028;67034;69822;75642.10894727566;75674;75716.24283757202;75805;17959.191836367394;18082.186301616457;18164.91010539372;18232.544186395447;18274.903505789694;13679.658008549866;13739.587812365646;13752.93123534396;13755.899528037733;13762.311884405739;21657.458563536038;21965.780342196693;21967.890160549094;21983;21983.34049978503;8844.292456603456;9050.27597792175;9056.230220431336;9111.772205694779;9112.772180695403;951.7763325618474;1005.3060592900839;1034.4569101221919;1102.536934487517;1143.0512674480162;29647.851760741058;29749.85125074404;30060.24849378783;30269.243268918013;30578.84710576433;1060.750723579958;1098.9780204395868;1127.1490025031048;1168.649405178443;1172.536847945057;14931.402743890123;15218.847811521742;15260.23698815077;15488;15504.914655973973;514988;522490;522509;522652;522778;58668.11997820036;60276;60348.69825650844;60419;60493.395066048775;1387.5629176809273;1390.4924702483722;1451.3396404635864;1491.5227127319154;1533.018867924529;24103;29455.864088227547;29716.373119397766;30120;30883.382332353678;55659;55679.721601391735;56062;56078.43921560895;56122.71938640281;422053;423402;423410;424481;424498;34597.65402345944;34989;47766.80582985314;49265.76835579139;49963.50036499734;45030.324545131196;46253.76873115613;46458.53541464542;47320.290195646405;48285.03429931452;36146;37497.437538437494;37547.81226093852;37564.06089847776;38380.80809595934;30679;36231.818840905624;37594.624053760206;38294;38316.2336753269;33549.49675754817;40211.79894100511;40867.59132408638;41783.7910810444;41786.37320440257;32161.195970100936;33407;34070.48894266538;34314.82842585771;34435.65564344324;21745.565088697822;25754.484910302064;26235.081772137884;26513;26554.468910622065 14:36:27 INFO - PROCESS | 1662 | |2;jquery.html;376.5519032351502;378.0812625320471;380.20347372255134;385.371843894452;387.53882879373526;16463.67072658546;16862.325506979705;20572.177112915484;21780.237691680795;22306.442338941535;17679.5580110497;17937.730934035968;19696.803031969677;20872.686909696367;21167.153313867464;5718.771249150029;5727.398623144572;6044.576879618428;6044.909326360108;6046.486048685867;5745.253117094772;5832.912506312408;5868.3838196989345;5938.7624495020145;5956.4043595640305;5894.204282421882;5947.5539334550085;5954.583179177459;5973.611715238518;5979.7907073252445;5644.4637759413;5901.4688678018965;5909.586328956974;5917.822465326004;5920.230370051866;5151.04705629456;5158.7420628968775;5160.845174644766;5161.638685292031;5174.120399532088;25691.871540642176;26333.210003699918;26777.19668409951;26831;26906.730932690814;2030;2163.0915015693445;2256.4388027952127;2319.2346525646494;2861.1273561563717;10188.796224075551;10192.490375481271;10247.84628230577;13871.722565548633;14586.34361453737;1739.7042502774432;1770.3361239535175;1797.1912639312231;1801.5496125968507;1810.3663717698832;107387.46306268497;110138.25345134184;111286;113044;114266.85733142728;11485.483153257956;11692.94153529227;11732;11744.706382340339;11908.345041022654;14408.567742967727;14430.494932677306;14452.988290819752;14459.204743739196;14483.49307774244;8908.465492070496;9073.364864459556;9079.773005674779;9086.227670647937;9173.312001599774;9287.907120928705;9297.907020929839;9351.579178936829;9376.48429336393;9391.483468409166;4568.314752787109;4599.1721490132095;4600.6549508786975;4604.401427814362;4638.698484598491;1683.947532792005;1753.6054387762651;1868.3274021352302;1928.2768961639385;1948.6687263165013;882.9867551986725;940.3558562384789;945.7541039329824;983.4050399508337;1001.0640051551823;19228.423147305602;20339.08474118668;20975.790242097384;20991.265305714227;21206.25778097759;970.5632465390603;975.7219192530237;987.5259875259771;1016.8474728790741;1019.8164330420448;10688.679339619825;10816.729581760519;10921.017108460117;10933.617323393642;10970.945145274223;584920;606791.966040167;615499;622914;623209;111977.44011279891;114636;122058;122672;123462.76537234512;935.373299889076;943.9386439881386;1049.994750026245;1103.0679076180616;1106.551846502168;18151;18342.266309347477;18360.632787344446;18465.16906739173;19208.519787005436;52239.73880130727;52491.737541312046;53556.46443535515;54059.729701352815;55970.44029559652;80403;80704;81477.59261203656;81749.59125204574;82006;8269.710560130367;8294.58527073656;8383.706570270011;8523.48859068458;8775.868361974448;12528.874711253135;13564.660883477993;13642.840358569632;13993.720125597634;14124.717505650035;11341.829872551752;11563.88436115628;11753.706157346134;12070.3361315125;12099.81850272229;10627.256092073481;11245.437728113726;11542.076633869103;11621.47703353368;11939.223950443193;10223.284370094021;11577.305361678325;11961.521539138685;11980.161388703056;12214.145009849226;8322.20939010793;8389.244967952913;8449.28181104613;8501.617427215913;8523.786905327417;8938.553072346227;11843.170978031456;12001.39993000364;12023.158378913393;12118.091143164122 14:36:27 INFO - PROCESS | 1662 | |3;mootools.html;1297.3902265935008;1617.846304601063;1643.424801319538;1955.2765476773595;2046.5395286060639;8184.427090103695;8859.911400885989;9006.369554131214;9023.819523609523;9027.638894444215;8753.91246087539;8969.282457403408;8976.910230897689;8983.146601072904;8984.640614375417;2551.234629611114;2579.9871000645066;2583.186296316654;2589.2232330300835;2592.753688399599;2411.590029694947;2412.6863507744056;2418.9274321770285;2423.53952748978;2447.5472037673;2553.131935142051;2578.5358635445614;2587.107447930461;2591.935201619956;2595.7274486178962;2553.7446255374407;2571.7428257174224;2579.1875559198793;2584.638150658909;2591.520568694788;2531.860747658878;2558.5266725655815;2564.0513010186164;2564.8974041038337;2582.341502916763;9058.275337972946;9101.544922753836;9103.135202155785;9118.58966346516;9123.817523649555;5488.9725551372385;5656.236408084897;5672.78035222425;5709.0580054290995;5711.942880571224;3613.4218525035867;3714.5542534895985;3772.9056773580605;3781.1870447853844;3793.6775374093236;1048.8688913885765;1078.827387617985;1081.4808891732014;1094.19029917861;1115.59838458155;12221.69445763863;12247.632571022794;12252;12255.325957072357;12264.632061038183;4115.8971025724595;4392.956070439319;4882.292067650242;5019.79524914018;5089.7709603067215;10371.896281037243;10467.790644187073;10507.94746026265;10524.210684198715;10528.420936848546;5764.308283006066;5768.711564421762;5813.215215945793;5878.617889837233;5898.380670029671;4601.976990115028;4769.8569042928075;4973.925391119136;5177.741112944338;5250.3962044364625;3983.1635356575443;4053.918921621551;4063.187362527506;4075.1645912587846;4119.9176016479505;1283.018490854497;1319.8680131986725;1332.5602551158095;1334.4728832111455;1345.0584590786489;1211.19455562052;1218.670958841108;1240.2186622427812;1260.4013093780381;1420.1975883625926;8297.502149871027;8338.332933365316;8366.121557236524;8380.078191398941;8388.748337549883;518.0519649042244;527.8522013836132;529.5525281137433;532.1432493685164;532.3983898195015;6208.50331973441;6291.748330066746;6310.116583678288;6404.103425520431;6422.389872962061;10629.255952083435;10841.566337346418;10934.726631834268;11011.339319640847;11246.212765106364;10749.140068794477;10804.243702940876;10982.890171098186;10995.450227488594;11009.889901101047;8102.39232057598;8520.70177543783;8530.74407767768;8565;8575.699850505202;8168.305694016075;8275.172482751677;8309.916900830915;8315.251627353568;8334.958325208336;9498.85751713711;9613.807723845624;9667.323287370098;9743.123118919331;9920.553575088994;9634.71095867097;10060.39637621745;10108.797824043331;10228.846567301645;10239.232057595858;7015.088038554955;7224.530405523624;7450.292222239095;7571.091469023752;7728.7681369559;7107.786766397016;8346.081930987466;8367.414281000514;8417;8425.62084706202;7345.853082938418;7423.443241756781;7490.4007679386605;7585.051868516435;7605.46761726696;7271.272872712898;7321.194668586558;7330.780076597711;7380.741674041383;7401.666924988496;7282.907563865462;7507.399408047452;7800.063992320957;7931.325837303893;7932.6430310635005;7110.715571377087;7125.35871771543;7188.748393806401;7197.460190485839;7201.279872012967;7262.273772622907;7278.708851645875;7333.853322933618;7337.523061001018;7338.229485903903 14:36:27 INFO - PROCESS | 1662 | |4;prototype.html;333.32668010618545;337.6926996433246;342.6881537800599;342.7609192768143;348.34163431115195;5319.335083114611;6595.472362211023;6639.236487803904;6680.331966803317;6712.630805305718;6577.177852768404;6594.769183078593;6597.934020659792;6609.272979972199;6617.735290588371;4740.881477963044;4860.3195552622665;4925.064237794825;4999.925001124977;5036.068327359432;4990.351254336932;5012.172991456406;5024.899502009957;5025.3969523657215;5031.195008798593;4975.4527002029745;4979.228219625964;4995.900081998358;5001.44984051754;5011.774470148852;4774.9761251193695;4827.517248275162;4935.90128197434;4971.875703107415;4997.975010124963;4251.787410629456;4275.764832934187;4280.657547396232;4292.635126014261;4296.033392486696;6385.297617262097;6385.648789316586;6408.743650253984;6413.679316034226;6413.871722565569;5851.356350801409;5872.941270587325;5874.353821079678;5880.8235752927485;5880.970595146997;5663.943360566383;6809.421199198074;7029.2619274975905;7038.2961703829205;7045.823854403629;4787.712737235777;4789.042191561667;4805.255185446226;4811.061842940628;4823.372961515016;6213.68931553425;7396.112466504054;7400.555966642019;7407.185209626937;7419;4885.780139893714;4888.902221955541;4890.510948905081;4891.926621100686;4893.192623217201;4943.802247910044;4952.826651067196;4957.429895561984;4963.90072198554;4968.354113965162;5660.405657405913;5857.238558987389;5870.295564532284;5885.382034886361;5886.852828679317;6654.23476300222;7057.7176912922905;7058.788236352918;7066.787996360117;7101.431885449152;1682.4700219431086;1730.6192637619956;1732.9429048280538;1733.0381638190925;1736.8957862528289;1033.813913495578;1073.63496411219;1084.4902895639036;1104.6465131157952;1121.433675993618;6260.843478913063;6260.968695156495;6363.649999250086;6531;6579.1447111876105;5623.240862483594;6103;7016.228214896358;7354.742584009534;7358.668859901317;6525.902111468332;6525.902111468332;6537.836554086186;6541.836454088686;6542.509311801538;3363.4450315698127;3827.406751953396;3864.439656249828;3884.7086468514976;3886.630770076838;7270;7618.6190690465255;7655.846883062308;7669.424793140537;7678.692852285846;6260.373962603703;7538.283863033003;7558.773236802905;7563.546187228784;7608.657610407545;7574.659140338698;7600.8479830403085;7608.8478230435085;7624.466287359832;7629.389648828192;6386.552941294065;6945.645599108328;7071.893921591078;7306.0502134724275;7315.48791584584;7213.242609525924;7225.891611625935;7227.674754636159;7229.09636295463;7249.601271930094;5995.940040599538;6306.905396419149;7194.748183813751;7560.432967527568;7602.543847369176;4832.299316599003;4859.684120532154;4874.2444921037295;4887.120318342732;4894.559489645949;7284.708611655475;7285.307895749896;7303.269673032867;7306.123265208209;7310.086239220098;5733.226014487991;6761.661916904233;7070.929290707027;7080.787576372717;7087.149542054986;5603.775848966159;7015.228324884157;7029.718811247493;7031.1211098612675;7078.1506219254015;5906.143609176731;6678.465722742266;6921.134858142732;6923.169219693669;6929.514933954575;6841.863162736817;7044.908039253924;7049.718011279697;7065;7077.5399599025895;6680.198376194888;6803.4557235422035;6902.378785909092;6904.792856214322;6925.688344024632 14:36:27 INFO - PROCESS | 1662 | |5;yui.html;751.9285667861553;763.5037225803229;770.6378002338898;781.2929298984418;788.4874831359615;15245.847541524581;15474;15479.922600386997;15496.302666380012;15506.612334691637;14325.426982920697;14399;14465.493707720232;14479.710405791879;14492.782608260884;780.4458834227705;802.3220378779922;975.9609615615367;996.7458298133965;999.7800483893568;971.3783178765595;981.5533932060931;983.0120728667679;984.8818141822991;986.856905748666;774.1213722425032;1014.8579198912158;1045.4563626914;1047.240750455921;1052.63157894737;1008.5814387029374;1034.131329683066;1034.6740776655329;1035.0167341026056;1040.9583616655325;764.4534158077001;767.0795045944889;768.7463137164722;771.155584634824;773.7446642607925;15177.468788592456;15179.620509487351;15184.01303915242;15206.16366099864;15235.162066086361;12682.302473363961;12794.42425090873;13113.409896554678;13167.770143660213;13410.46358145673;10206.489675516195;10208.846867296996;10277.691669249934;10464.843027354596;10889.509972051277;3509.1928856362924;3523.295340931824;3570.928581428383;3610.6750392464805;3766.133789228493;85512;85968.57015714882;86253;88584;88934;9679.70960871175;9808.29113483293;9887.110160085627;9969.40183588987;9993.700188994342;23254.883725581265;23746.7625323748;23753.40616484567;23770.88114559416;23797.405064873517;7637.618119094134;7750.14748377678;7874.6850125994315;7908.446408751447;7986.800329991681;4122.711410201317;4188.4136220929095;4209.221294060609;4213.53651098385;4254.87235382939;3759;3807.2195199983935;3817.484639573622;3822.636849499293;3826.253880493277;615.092738211135;624.1916717850395;653.4674240493982;660.5409240577752;701.4073108223542;537.4598528478854;553.6705660132214;615.1818081950994;618.4959258204549;620.7827260458894;7685.884711729329;8053.163422768703;8165.918340816635;8327.541985190752;8466.195711407407;587.685588210314;593.8188852399973;597.5637784417313;608.9299730530958;618.8514756458418;3746.2320224353944;3908.9609103909165;3912.784796836201;3937.7046721496004;3943.4479172915817;99060;100398;100736;100859;101128.49435752774;83550.16449835546;85354.29291414136;85409;85708;85812;74670.6266468675;75073;75290.62354688192;76494;76841.23158768452;7818.804529886798;7886.250806173404;7925.603719813871;7962.7213047543055;8002.799930001796;48383.758081209366;49251.75374123106;51239.743801282246;53032;53496.73251633717;68970;69427;70583;70894;71893.640531797;10177.745556361151;10317.535710892878;10512.947435262775;10891.509882055168;10990.120790336914;10867.619633312795;11041.944790275997;11053.944730276296;11062.17033722458;11107.333559986426;8488.845935417157;10477;11355.48900299472;11407.543698251977;11629.88370116288;11268.492917818554;11465.369404682822;11482.253653512495;11694.532218711156;11746.647600571996;9082.954585227031;9146;10558.31370960885;11144.387058711849;11152.665420037412;9378.531073446436;9529.047095290416;9590.616375344907;9973.052560006789;9995.000499949947;9563;9633.710988670351;9637.132658060807;9734.318597698377;9812.852807207755 14:36:27 INFO - PROCESS | 1662 | __end_tp_report 14:36:27 INFO - PROCESS | 1662 | __start_cc_report 14:36:27 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1301 14:36:27 INFO - PROCESS | 1662 | __end_cc_report 14:36:27 INFO - PROCESS | 1662 | __startTimestamp1467236187626__endTimestamp 14:36:27 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | ------- Summary: start ------- 14:36:27 INFO - PROCESS | 1662 | Number of tests: 6 14:36:27 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5616.31 Median:5769.65 stddev:634.73 (11.0%) stddev-sans-first:606.03 14:36:27 INFO - PROCESS | 1662 | Values: 3132.7 3443.9 3476.6 3479.8 3516.7 5544.4 5546.9 5551.8 5565.6 5566.0 5537.1 5571.2 5586.5 5593.9 5647.0 5020.7 5067.5 5082.3 5129.0 5141.6 5072.2 5148.0 5152.3 5155.3 5162.1 5071.5 5124.4 5144.0 5144.5 5152.8 5126.7 5165.0 5167.5 5168.3 5212.5 4901.6 5070.7 5079.7 5081.0 5088.3 5456.7 5459.3 5489.0 5500.4 5505.0 5864.8 6116.7 6126.0 6126.4 6128.5 6031.1 6082.0 6085.2 6104.8 6109.9 5584.6 5607.5 5655.6 5669.5 5683.7 6269.4 6298.4 6312.6 6313.7 6323.5 5925.7 5957.1 5974.4 5976.2 5985.8 5929.1 6107.4 6150.9 6189.6 6195.5 6011.7 6073.6 6075.7 6080.4 6082.5 6122.1 6125.9 6131.7 6131.9 6139.9 4061.6 4694.3 4804.1 4809.9 4848.5 3741.4 4308.2 4327.9 4335.0 4368.8 5557.7 5562.5 5564.0 5577.8 5618.7 5956.4 6057.8 6143.0 6147.0 6154.8 5565.9 5569.9 5571.5 5574.3 5576.1 5182.9 5280.3 5295.8 5295.9 5320.8 6205.8 6294.0 6296.0 6311.9 6340.7 6141.3 6151.6 6168.5 6178.4 6184.4 6130.5 6227.6 6332.2 6353.5 6354.5 5942.4 5956.4 5957.2 5958.7 5991.1 6044.0 6097.5 6099.9 6112.3 6119.4 5794.8 5875.4 5882.1 5883.4 5917.9 5965.2 5974.0 5976.9 6005.4 6014.3 5640.6 5777.5 5820.1 5825.8 5835.4 5766.5 5769.7 5839.1 5903.1 5927.1 5699.6 5700.7 5736.0 5736.2 5741.1 14:36:27 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:63089.70 Median:40539.70 stddev:125243.28 (308.9%) stddev-sans-first:125506.43 14:36:27 INFO - PROCESS | 1662 | Values: 490.5 596.3 608.2 664.6 667.6 29865.1 31501.2 31528.4 38927.6 39315.4 37293.9 38943.6 39922.0 41703.6 42430.0 9572.5 9777.7 9791.9 9801.5 9801.5 9609.0 9737.7 9743.8 9746.7 9761.9 9704.4 9771.2 9812.0 9873.6 9895.5 8422.3 9840.2 9866.5 9869.2 9874.6 9695.6 9822.6 9833.2 9833.3 9835.1 41261.6 41653.8 41663.2 41745.0 41851.2 3172.5 3212.5 3215.3 3434.3 3599.1 23079.7 28746.7 29233.4 29286.3 29552.1 2756.3 2766.3 2783.3 2799.7 2803.9 465993.0 466455.7 467395.0 467442.0 467626.0 66334.7 66900.0 67025.0 67028.0 67034.0 69822.0 75642.1 75674.0 75716.2 75805.0 17959.2 18082.2 18164.9 18232.5 18274.9 13679.7 13739.6 13752.9 13755.9 13762.3 21657.5 21965.8 21967.9 21983.0 21983.3 8844.3 9050.3 9056.2 9111.8 9112.8 951.8 1005.3 1034.5 1102.5 1143.1 29647.9 29749.9 30060.2 30269.2 30578.8 1060.8 1099.0 1127.1 1168.6 1172.5 14931.4 15218.8 15260.2 15488.0 15504.9 514988.0 522490.0 522509.0 522652.0 522778.0 58668.1 60276.0 60348.7 60419.0 60493.4 1387.6 1390.5 1451.3 1491.5 1533.0 24103.0 29455.9 29716.4 30120.0 30883.4 55659.0 55679.7 56062.0 56078.4 56122.7 422053.0 423402.0 423410.0 424481.0 424498.0 34597.7 34989.0 47766.8 49265.8 49963.5 45030.3 46253.8 46458.5 47320.3 48285.0 36146.0 37497.4 37547.8 37564.1 38380.8 30679.0 36231.8 37594.6 38294.0 38316.2 33549.5 40211.8 40867.6 41783.8 41786.4 32161.2 33407.0 34070.5 34314.8 34435.7 21745.6 25754.5 26235.1 26513.0 26554.5 14:36:27 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:35192.07 Median:22043.34 stddev:101476.21 (460.3%) stddev-sans-first:101727.21 14:36:27 INFO - PROCESS | 1662 | Values: 376.6 378.1 380.2 385.4 387.5 16463.7 16862.3 20572.2 21780.2 22306.4 17679.6 17937.7 19696.8 20872.7 21167.2 5718.8 5727.4 6044.6 6044.9 6046.5 5745.3 5832.9 5868.4 5938.8 5956.4 5894.2 5947.6 5954.6 5973.6 5979.8 5644.5 5901.5 5909.6 5917.8 5920.2 5151.0 5158.7 5160.8 5161.6 5174.1 25691.9 26333.2 26777.2 26831.0 26906.7 2030.0 2163.1 2256.4 2319.2 2861.1 10188.8 10192.5 10247.8 13871.7 14586.3 1739.7 1770.3 1797.2 1801.5 1810.4 107387.5 110138.3 111286.0 113044.0 114266.9 11485.5 11692.9 11732.0 11744.7 11908.3 14408.6 14430.5 14453.0 14459.2 14483.5 8908.5 9073.4 9079.8 9086.2 9173.3 9287.9 9297.9 9351.6 9376.5 9391.5 4568.3 4599.2 4600.7 4604.4 4638.7 1683.9 1753.6 1868.3 1928.3 1948.7 883.0 940.4 945.8 983.4 1001.1 19228.4 20339.1 20975.8 20991.3 21206.3 970.6 975.7 987.5 1016.8 1019.8 10688.7 10816.7 10921.0 10933.6 10970.9 584920.0 606792.0 615499.0 622914.0 623209.0 111977.4 114636.0 122058.0 122672.0 123462.8 935.4 943.9 1050.0 1103.1 1106.6 18151.0 18342.3 18360.6 18465.2 19208.5 52239.7 52491.7 53556.5 54059.7 55970.4 80403.0 80704.0 81477.6 81749.6 82006.0 8269.7 8294.6 8383.7 8523.5 8775.9 12528.9 13564.7 13642.8 13993.7 14124.7 11341.8 11563.9 11753.7 12070.3 12099.8 10627.3 11245.4 11542.1 11621.5 11939.2 10223.3 11577.3 11961.5 11980.2 12214.1 8322.2 8389.2 8449.3 8501.6 8523.8 8938.6 11843.2 12001.4 12023.2 12118.1 14:36:27 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:6185.41 Median:528.70 stddev:3280.10 (620.4%) stddev-sans-first:3268.72 14:36:27 INFO - PROCESS | 1662 | Values: 1297.4 1617.8 1643.4 1955.3 2046.5 8184.4 8859.9 9006.4 9023.8 9027.6 8753.9 8969.3 8976.9 8983.1 8984.6 2551.2 2580.0 2583.2 2589.2 2592.8 2411.6 2412.7 2418.9 2423.5 2447.5 2553.1 2578.5 2587.1 2591.9 2595.7 2553.7 2571.7 2579.2 2584.6 2591.5 2531.9 2558.5 2564.1 2564.9 2582.3 9058.3 9101.5 9103.1 9118.6 9123.8 5489.0 5656.2 5672.8 5709.1 5711.9 3613.4 3714.6 3772.9 3781.2 3793.7 1048.9 1078.8 1081.5 1094.2 1115.6 12221.7 12247.6 12252.0 12255.3 12264.6 4115.9 4393.0 4882.3 5019.8 5089.8 10371.9 10467.8 10507.9 10524.2 10528.4 5764.3 5768.7 5813.2 5878.6 5898.4 4602.0 4769.9 4973.9 5177.7 5250.4 3983.2 4053.9 4063.2 4075.2 4119.9 1283.0 1319.9 1332.6 1334.5 1345.1 1211.2 1218.7 1240.2 1260.4 1420.2 8297.5 8338.3 8366.1 8380.1 8388.7 518.1 527.9 529.6 532.1 532.4 6208.5 6291.7 6310.1 6404.1 6422.4 10629.3 10841.6 10934.7 11011.3 11246.2 10749.1 10804.2 10982.9 10995.5 11009.9 8102.4 8520.7 8530.7 8565.0 8575.7 8168.3 8275.2 8309.9 8315.3 8335.0 9498.9 9613.8 9667.3 9743.1 9920.6 9634.7 10060.4 10108.8 10228.8 10239.2 7015.1 7224.5 7450.3 7571.1 7728.8 7107.8 8346.1 8367.4 8417.0 8425.6 7345.9 7423.4 7490.4 7585.1 7605.5 7271.3 7321.2 7330.8 7380.7 7401.7 7282.9 7507.4 7800.1 7931.3 7932.6 7110.7 7125.4 7188.7 7197.5 7201.3 7262.3 7278.7 7333.9 7337.5 7338.2 14:36:27 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5753.16 Median:6386.10 stddev:1771.50 (27.7%) stddev-sans-first:1729.13 14:36:27 INFO - PROCESS | 1662 | Values: 333.3 337.7 342.7 342.8 348.3 5319.3 6595.5 6639.2 6680.3 6712.6 6577.2 6594.8 6597.9 6609.3 6617.7 4740.9 4860.3 4925.1 4999.9 5036.1 4990.4 5012.2 5024.9 5025.4 5031.2 4975.5 4979.2 4995.9 5001.4 5011.8 4775.0 4827.5 4935.9 4971.9 4998.0 4251.8 4275.8 4280.7 4292.6 4296.0 6385.3 6385.6 6408.7 6413.7 6413.9 5851.4 5872.9 5874.4 5880.8 5881.0 5663.9 6809.4 7029.3 7038.3 7045.8 4787.7 4789.0 4805.3 4811.1 4823.4 6213.7 7396.1 7400.6 7407.2 7419.0 4885.8 4888.9 4890.5 4891.9 4893.2 4943.8 4952.8 4957.4 4963.9 4968.4 5660.4 5857.2 5870.3 5885.4 5886.9 6654.2 7057.7 7058.8 7066.8 7101.4 1682.5 1730.6 1732.9 1733.0 1736.9 1033.8 1073.6 1084.5 1104.6 1121.4 6260.8 6261.0 6363.6 6531.0 6579.1 5623.2 6103.0 7016.2 7354.7 7358.7 6525.9 6525.9 6537.8 6541.8 6542.5 3363.4 3827.4 3864.4 3884.7 3886.6 7270.0 7618.6 7655.8 7669.4 7678.7 6260.4 7538.3 7558.8 7563.5 7608.7 7574.7 7600.8 7608.8 7624.5 7629.4 6386.6 6945.6 7071.9 7306.1 7315.5 7213.2 7225.9 7227.7 7229.1 7249.6 5995.9 6306.9 7194.7 7560.4 7602.5 4832.3 4859.7 4874.2 4887.1 4894.6 7284.7 7285.3 7303.3 7306.1 7310.1 5733.2 6761.7 7070.9 7080.8 7087.1 5603.8 7015.2 7029.7 7031.1 7078.2 5906.1 6678.5 6921.1 6923.2 6929.5 6841.9 7044.9 7049.7 7065.0 7077.5 6680.2 6803.5 6902.4 6904.8 6925.7 14:36:27 INFO - PROCESS | 1662 | 14:36:27 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:19218.69 Median:545.57 stddev:27746.42 (5085.8%) stddev-sans-first:27789.60 14:36:27 INFO - PROCESS | 1662 | Values: 751.9 763.5 770.6 781.3 788.5 15245.8 15474.0 15479.9 15496.3 15506.6 14325.4 14399.0 14465.5 14479.7 14492.8 780.4 802.3 976.0 996.7 999.8 971.4 981.6 983.0 984.9 986.9 774.1 1014.9 1045.5 1047.2 1052.6 1008.6 1034.1 1034.7 1035.0 1041.0 764.5 767.1 768.7 771.2 773.7 15177.5 15179.6 15184.0 15206.2 15235.2 12682.3 12794.4 13113.4 13167.8 13410.5 10206.5 10208.8 10277.7 10464.8 10889.5 3509.2 3523.3 3570.9 3610.7 3766.1 85512.0 85968.6 86253.0 88584.0 88934.0 9679.7 9808.3 9887.1 9969.4 9993.7 23254.9 23746.8 23753.4 23770.9 23797.4 7637.6 7750.1 7874.7 7908.4 7986.8 4122.7 4188.4 4209.2 4213.5 4254.9 3759.0 3807.2 3817.5 3822.6 3826.3 615.1 624.2 653.5 660.5 701.4 537.5 553.7 615.2 618.5 620.8 7685.9 8053.2 8165.9 8327.5 8466.2 587.7 593.8 597.6 608.9 618.9 3746.2 3909.0 3912.8 3937.7 3943.4 99060.0 100398.0 100736.0 100859.0 101128.5 83550.2 85354.3 85409.0 85708.0 85812.0 74670.6 75073.0 75290.6 76494.0 76841.2 7818.8 7886.3 7925.6 7962.7 8002.8 48383.8 49251.8 51239.7 53032.0 53496.7 68970.0 69427.0 70583.0 70894.0 71893.6 10177.7 10317.5 10512.9 10891.5 10990.1 10867.6 11041.9 11053.9 11062.2 11107.3 8488.8 10477.0 11355.5 11407.5 11629.9 11268.5 11465.4 11482.3 11694.5 11746.6 9083.0 9146.0 10558.3 11144.4 11152.7 9378.5 9529.0 9590.6 9973.1 9995.0 9563.0 9633.7 9637.1 9734.3 9812.9 14:36:27 INFO - PROCESS | 1662 | -------- Summary: end -------- 14:36:27 INFO - PROCESS | 1662 | 14:36:28 INFO - TEST-INFO | 1662: exit 0 14:36:28 INFO - TEST-OK | dromaeo_css | took 1112154ms 14:36:28 INFO - TEST-START | kraken 14:36:28 INFO - operating with platform_type : mac_ 14:36:28 INFO - Initialising browser for kraken test... 14:36:28 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpXSAzkh/profile http://localhost:49275/getInfo.html) 14:36:30 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 14:36:30 INFO - PROCESS | 1669 | colorDepth:24 14:36:30 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 14:36:30 INFO - PROCESS | 1669 | __metrics 14:36:30 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:36:30 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:36:30 INFO - TEST-INFO | 1669: exit 0 14:36:30 INFO - Browser initialized. 14:36:30 INFO - Running cycle 1/1 for kraken test... 14:36:30 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpXSAzkh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:36:42 INFO - PROCESS | 1671 | RSS: Main: 184672256 14:36:42 INFO - PROCESS | 1671 | 14:36:45 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 14:36:45 INFO - PROCESS | 1671 | RSS: Main: 245735424 14:36:45 INFO - PROCESS | 1671 | 14:36:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 14:36:49 INFO - PROCESS | 1671 | RSS: Main: 256229376 14:36:49 INFO - PROCESS | 1671 | 14:36:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 14:36:52 INFO - PROCESS | 1671 | RSS: Main: 246423552 14:36:52 INFO - PROCESS | 1671 | 14:36:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 14:36:55 INFO - PROCESS | 1671 | RSS: Main: 248557568 14:36:55 INFO - PROCESS | 1671 | 14:36:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:36:58 INFO - PROCESS | 1671 | RSS: Main: 248164352 14:36:58 INFO - PROCESS | 1671 | 14:37:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 14:37:01 INFO - PROCESS | 1671 | RSS: Main: 248311808 14:37:01 INFO - PROCESS | 1671 | 14:37:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 14:37:05 INFO - PROCESS | 1671 | RSS: Main: 248385536 14:37:05 INFO - PROCESS | 1671 | 14:37:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 14:37:08 INFO - PROCESS | 1671 | RSS: Main: 248602624 14:37:08 INFO - PROCESS | 1671 | 14:37:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:37:10 INFO - PROCESS | 1671 | RSS: Main: 282918912 14:37:10 INFO - PROCESS | 1671 | 14:37:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:37:12 INFO - PROCESS | 1671 | RSS: Main: 283975680 14:37:12 INFO - PROCESS | 1671 | 14:37:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:37:15 INFO - PROCESS | 1671 | RSS: Main: 288980992 14:37:15 INFO - PROCESS | 1671 | 14:37:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:37:18 INFO - PROCESS | 1671 | RSS: Main: 291098624 14:37:18 INFO - PROCESS | 1671 | 14:37:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:37:21 INFO - PROCESS | 1671 | RSS: Main: 291225600 14:37:21 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 14:37:23 INFO - PROCESS | 1671 | RSS: Main: 353959936 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | __start_tp_report 14:37:23 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 14:37:23 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 14:37:23 INFO - PROCESS | 1671 | |i|pagename|runs| 14:37:23 INFO - PROCESS | 1671 | |0;ai-astar;113;111;112;112;114;112;131;112;109;112 14:37:23 INFO - PROCESS | 1671 | |1;audio-beat-detection;154;120;144;155;114;135;117;156;173;125 14:37:23 INFO - PROCESS | 1671 | |2;audio-dft;186;183;183;188;185;197;259;203;184;184 14:37:23 INFO - PROCESS | 1671 | |3;audio-fft;71;81;72;118;74;82;84;56;118;54 14:37:23 INFO - PROCESS | 1671 | |4;audio-oscillator;89;89;135;78;81;81;123;76;80;123 14:37:23 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;86;85;87;128;98;84;87;85;87;84 14:37:23 INFO - PROCESS | 1671 | |6;imaging-darkroom;91;91;94;92;93;91;102;149;134;93 14:37:23 INFO - PROCESS | 1671 | |7;imaging-desaturate;86;86;84;83;85;86;83;86;85;85 14:37:23 INFO - PROCESS | 1671 | |8;json-parse-financial;56;57;58;57;60;57;58;57;67;52 14:37:23 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;60;76;69;68;66;65;67;66;65 14:37:23 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;80;80;81;80;80;80;78;91;101;110 14:37:23 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;118;118;129;120;128;139;117;118;120;116 14:37:23 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;134;165;171;132;125;123;134;135;137;133 14:37:23 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;67;66;64;66;66;66;66;67;67;64 14:37:23 INFO - PROCESS | 1671 | __end_tp_report 14:37:23 INFO - PROCESS | 1671 | __start_cc_report 14:37:23 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1167 14:37:23 INFO - PROCESS | 1671 | __end_cc_report 14:37:23 INFO - PROCESS | 1671 | __startTimestamp1467236243794__endTimestamp 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | ------- Summary: start ------- 14:37:23 INFO - PROCESS | 1671 | Number of tests: 14 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:113.80 Median:112.00 stddev:6.18 (5.5%) stddev-sans-first:6.55 14:37:23 INFO - PROCESS | 1671 | Values: 113.0 111.0 112.0 112.0 114.0 112.0 131.0 112.0 109.0 112.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:149.00 stddev:20.09 (13.5%) stddev-sans-first:20.59 14:37:23 INFO - PROCESS | 1671 | Values: 154.0 120.0 144.0 155.0 114.0 135.0 117.0 156.0 173.0 125.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:195.20 Median:187.00 stddev:23.39 (12.5%) stddev-sans-first:24.57 14:37:23 INFO - PROCESS | 1671 | Values: 186.0 183.0 183.0 188.0 185.0 197.0 259.0 203.0 184.0 184.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:81.00 Median:73.00 stddev:21.94 (30.1%) stddev-sans-first:22.97 14:37:23 INFO - PROCESS | 1671 | Values: 71.0 81.0 72.0 118.0 74.0 82.0 84.0 56.0 118.0 54.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:80.50 stddev:22.37 (27.8%) stddev-sans-first:23.60 14:37:23 INFO - PROCESS | 1671 | Values: 89.0 89.0 135.0 78.0 81.0 81.0 123.0 76.0 80.0 123.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:86.50 stddev:13.58 (15.7%) stddev-sans-first:14.28 14:37:23 INFO - PROCESS | 1671 | Values: 86.0 85.0 87.0 128.0 98.0 84.0 87.0 85.0 87.0 84.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:91.50 stddev:20.85 (22.8%) stddev-sans-first:21.66 14:37:23 INFO - PROCESS | 1671 | Values: 91.0 91.0 94.0 92.0 93.0 91.0 102.0 149.0 134.0 93.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:1.20 (1.4%) stddev-sans-first:1.20 14:37:23 INFO - PROCESS | 1671 | Values: 86.0 86.0 84.0 83.0 85.0 86.0 83.0 86.0 85.0 85.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:57.50 stddev:3.78 (6.6%) stddev-sans-first:3.95 14:37:23 INFO - PROCESS | 1671 | Values: 56.0 57.0 58.0 57.0 60.0 57.0 58.0 57.0 67.0 52.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:66.50 stddev:4.06 (6.1%) stddev-sans-first:4.26 14:37:23 INFO - PROCESS | 1671 | Values: 65.0 60.0 76.0 69.0 68.0 66.0 65.0 67.0 66.0 65.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:80.00 stddev:11.03 (13.8%) stddev-sans-first:11.48 14:37:23 INFO - PROCESS | 1671 | Values: 80.0 80.0 81.0 80.0 80.0 80.0 78.0 91.0 101.0 110.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:122.30 Median:120.00 stddev:7.38 (6.1%) stddev-sans-first:7.66 14:37:23 INFO - PROCESS | 1671 | Values: 118.0 118.0 129.0 120.0 128.0 139.0 117.0 118.0 120.0 116.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.90 Median:134.50 stddev:16.01 (11.9%) stddev-sans-first:16.88 14:37:23 INFO - PROCESS | 1671 | Values: 134.0 165.0 171.0 132.0 125.0 123.0 134.0 135.0 137.0 133.0 14:37:23 INFO - PROCESS | 1671 | 14:37:23 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.10 (1.7%) stddev-sans-first:1.09 14:37:23 INFO - PROCESS | 1671 | Values: 67.0 66.0 64.0 66.0 66.0 66.0 66.0 67.0 67.0 64.0 14:37:23 INFO - PROCESS | 1671 | -------- Summary: end -------- 14:37:23 INFO - PROCESS | 1671 | 14:37:24 INFO - TEST-INFO | 1671: exit 0 14:37:24 INFO - TEST-OK | kraken | took 56337ms 14:37:24 INFO - SUITE-END | took 1168s 14:37:24 INFO - Completed test suite (00:19:28) 14:37:24 INFO - javascript benchmark 14:37:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3132.6867313268667, 3443.9483407748903, 3476.6001909780384, 3479.808610526421, 3516.6834984851375, 5544.362398324194, 5546.86132846679, 5551.77792888284, 5565.554755619551, 5565.972170139149, 5537.141743029837, 5571.164325351199, 5586.469285417889, 5593.916091258634, 5646.971765141178, 5020.673656212352, 5067.493250674931, 5082.339295891529, 5129.0, 5141.640085194038, 5072.239164125393, 5148.0, 5152.304438900756, 5155.252488389181, 5162.096633089194, 5071.518205770464, 5124.4106927703415, 5144.0, 5144.511271429208, 5152.845414637548, 5126.666766660155, 5165.044466773659, 5167.534921857051, 5168.32811734474, 5212.530872221519, 4901.607871370282, 5070.72111033893, 5079.6952182869145, 5081.034603425374, 5088.2876397304035, 5456.727163641842, 5459.344878614592, 5489.039418101816, 5500.4499550045075, 5504.972475137639, 5864.824055278348, 6116.663583502906, 6125.969370153165, 6126.417990290915, 6128.479079278267, 6031.065184896348, 6082.024823170938, 6085.2393450818645, 6104.786332478387, 6109.908351374733, 5584.553235741131, 5607.523360514361, 5655.632383895116, 5669.518090962314, 5683.687397193153, 6269.404406581368, 6298.401651843067, 6312.621242725451, 6313.652749098844, 6323.494120470444, 5925.73334199962, 5957.076653118686, 5974.4324289192455, 5976.163337132805, 5985.790497332572, 5929.0513517836935, 6107.389261073857, 6150.876982460326, 6189.597676151036, 6195.473384762346, 6011.729472173763, 6073.605215660968, 6075.726592303357, 6080.361562036011, 6082.513398928075, 6122.081687746784, 6125.877482450415, 6131.724072416663, 6131.908021379683, 6139.907901381483, 4061.573534778806, 4694.295855621615, 4804.06320767447, 4809.927851082237, 4848.466668666444, 3741.420079887567, 4308.246056940023, 4327.913441731148, 4335.024619460596, 4368.847090351823, 5557.694326811983, 5562.499375056264, 5564.0, 5577.804776832872, 5618.6628802272, 5956.404359564009, 6057.818265451955, 6142.98993493194, 6147.0, 6154.784582539679, 5565.9443405566235, 5569.944300557024, 5571.470710282517, 5574.275344205227, 5576.079946808731, 5182.870428239325, 5280.339957505312, 5295.814646487354, 5295.894082118336, 5320.76056577455, 6205.844853878689, 6293.992961126198, 6295.961166407492, 6311.936880631135, 6340.651264180515, 6141.293751218667, 6151.630902145886, 6168.506519478521, 6178.351273116438, 6184.443400094013, 6130.520435899211, 6227.595206311757, 6332.240131184288, 6353.491720662429, 6354.491640668828, 5942.405759424023, 5956.404359564009, 5957.225560677258, 5958.731857066529, 5991.131285963598, 6043.969780151071, 6097.4817140543555, 6099.878002440015, 6112.327643959072, 6119.44924956756, 5794.768209271582, 5875.383084776037, 5882.088276317178, 5883.411658834082, 5917.911231331447, 5965.194698715792, 5973.970130149321, 5976.850578735566, 6005.39946005396, 6014.278286605635, 5640.5769567281795, 5777.537796976148, 5820.068788993741, 5825.825225243249, 5835.387284335084, 5766.509846663081, 5769.653820770767, 5839.0949402842625, 5903.085021821625, 5927.140564618193, 5699.601027928005, 5700.743466543932, 5736.0248757712125, 5736.19693242938, 5741.0527263002805], "unit": "score", "name": "dojo.html", "value": 5575.455482453513}, {"lowerIsBetter": false, "replicates": [490.4947903659232, 596.2517041113402, 608.1637748096367, 664.5813137723235, 667.5927684112692, 29865.1040468786, 31501.212469688267, 31528.369432611333, 38927.61072389275, 39315.41026884599, 37293.88118356454, 38943.610563894355, 39922.0, 41703.582964170346, 42430.0, 9572.521373931311, 9777.706668799947, 9791.902080979205, 9801.460919649417, 9801.460919649417, 9609.0, 9737.707868763948, 9743.756406089833, 9746.707598772047, 9761.902380976171, 9704.41773493589, 9771.169450596708, 9811.95094024529, 9873.60505579776, 9895.50522473877, 8422.28410585098, 9840.163586095156, 9866.50667466631, 9869.161121304698, 9874.55564499592, 9695.612175512972, 9822.557984890696, 9833.213342932548, 9833.31166818323, 9835.114839664393, 41261.587384126076, 41653.791731041456, 41663.166736665095, 41745.0, 41851.1629767406, 3172.4924012157944, 3212.4860022396306, 3215.26049008727, 3434.278801451698, 3599.0822340303316, 23079.653805192935, 28746.712532874823, 29233.41533169346, 29286.267843303875, 29552.113436596723, 2756.324700448383, 2766.3084228942766, 2783.2902609834564, 2799.678037025748, 2803.887844486218, 465993.0, 466455.6677216626, 467395.0, 467442.0, 467626.0, 66334.66832665805, 66900.0, 67025.0, 67028.0, 67034.0, 69822.0, 75642.10894727566, 75674.0, 75716.24283757202, 75805.0, 17959.191836367394, 18082.186301616457, 18164.91010539372, 18232.544186395447, 18274.903505789694, 13679.658008549866, 13739.587812365646, 13752.93123534396, 13755.899528037733, 13762.311884405739, 21657.458563536038, 21965.780342196693, 21967.890160549094, 21983.0, 21983.34049978503, 8844.292456603456, 9050.27597792175, 9056.230220431336, 9111.772205694779, 9112.772180695403, 951.7763325618474, 1005.3060592900839, 1034.4569101221919, 1102.536934487517, 1143.0512674480162, 29647.851760741058, 29749.85125074404, 30060.24849378783, 30269.243268918013, 30578.84710576433, 1060.750723579958, 1098.9780204395868, 1127.1490025031048, 1168.649405178443, 1172.536847945057, 14931.402743890123, 15218.847811521742, 15260.23698815077, 15488.0, 15504.914655973973, 514988.0, 522490.0, 522509.0, 522652.0, 522778.0, 58668.11997820036, 60276.0, 60348.69825650844, 60419.0, 60493.395066048775, 1387.5629176809273, 1390.4924702483722, 1451.3396404635864, 1491.5227127319154, 1533.018867924529, 24103.0, 29455.864088227547, 29716.373119397766, 30120.0, 30883.382332353678, 55659.0, 55679.721601391735, 56062.0, 56078.43921560895, 56122.71938640281, 422053.0, 423402.0, 423410.0, 424481.0, 424498.0, 34597.65402345944, 34989.0, 47766.80582985314, 49265.76835579139, 49963.50036499734, 45030.324545131196, 46253.76873115613, 46458.53541464542, 47320.290195646405, 48285.03429931452, 36146.0, 37497.437538437494, 37547.81226093852, 37564.06089847776, 38380.80809595934, 30679.0, 36231.818840905624, 37594.624053760206, 38294.0, 38316.2336753269, 33549.49675754817, 40211.79894100511, 40867.59132408638, 41783.7910810444, 41786.37320440257, 32161.195970100936, 33407.0, 34070.48894266538, 34314.82842585771, 34435.65564344324, 21745.565088697822, 25754.484910302064, 26235.081772137884, 26513.0, 26554.468910622065], "unit": "score", "name": "ext.html", "value": 20401.016267252377}, {"lowerIsBetter": false, "replicates": [376.5519032351502, 378.0812625320471, 380.20347372255134, 385.371843894452, 387.53882879373526, 16463.67072658546, 16862.325506979705, 20572.177112915484, 21780.237691680795, 22306.442338941535, 17679.5580110497, 17937.730934035968, 19696.803031969677, 20872.686909696367, 21167.153313867464, 5718.771249150029, 5727.398623144572, 6044.576879618428, 6044.909326360108, 6046.486048685867, 5745.253117094772, 5832.912506312408, 5868.3838196989345, 5938.7624495020145, 5956.4043595640305, 5894.204282421882, 5947.5539334550085, 5954.583179177459, 5973.611715238518, 5979.7907073252445, 5644.4637759413, 5901.4688678018965, 5909.586328956974, 5917.822465326004, 5920.230370051866, 5151.04705629456, 5158.7420628968775, 5160.845174644766, 5161.638685292031, 5174.120399532088, 25691.871540642176, 26333.210003699918, 26777.19668409951, 26831.0, 26906.730932690814, 2030.0, 2163.0915015693445, 2256.4388027952127, 2319.2346525646494, 2861.1273561563717, 10188.796224075551, 10192.490375481271, 10247.84628230577, 13871.722565548633, 14586.34361453737, 1739.7042502774432, 1770.3361239535175, 1797.1912639312231, 1801.5496125968507, 1810.3663717698832, 107387.46306268497, 110138.25345134184, 111286.0, 113044.0, 114266.85733142728, 11485.483153257956, 11692.94153529227, 11732.0, 11744.706382340339, 11908.345041022654, 14408.567742967727, 14430.494932677306, 14452.988290819752, 14459.204743739196, 14483.49307774244, 8908.465492070496, 9073.364864459556, 9079.773005674779, 9086.227670647937, 9173.312001599774, 9287.907120928705, 9297.907020929839, 9351.579178936829, 9376.48429336393, 9391.483468409166, 4568.314752787109, 4599.1721490132095, 4600.6549508786975, 4604.401427814362, 4638.698484598491, 1683.947532792005, 1753.6054387762651, 1868.3274021352302, 1928.2768961639385, 1948.6687263165013, 882.9867551986725, 940.3558562384789, 945.7541039329824, 983.4050399508337, 1001.0640051551823, 19228.423147305602, 20339.08474118668, 20975.790242097384, 20991.265305714227, 21206.25778097759, 970.5632465390603, 975.7219192530237, 987.5259875259771, 1016.8474728790741, 1019.8164330420448, 10688.679339619825, 10816.729581760519, 10921.017108460117, 10933.617323393642, 10970.945145274223, 584920.0, 606791.966040167, 615499.0, 622914.0, 623209.0, 111977.44011279891, 114636.0, 122058.0, 122672.0, 123462.76537234512, 935.373299889076, 943.9386439881386, 1049.994750026245, 1103.0679076180616, 1106.551846502168, 18151.0, 18342.266309347477, 18360.632787344446, 18465.16906739173, 19208.519787005436, 52239.73880130727, 52491.737541312046, 53556.46443535515, 54059.729701352815, 55970.44029559652, 80403.0, 80704.0, 81477.59261203656, 81749.59125204574, 82006.0, 8269.710560130367, 8294.58527073656, 8383.706570270011, 8523.48859068458, 8775.868361974448, 12528.874711253135, 13564.660883477993, 13642.840358569632, 13993.720125597634, 14124.717505650035, 11341.829872551752, 11563.88436115628, 11753.706157346134, 12070.3361315125, 12099.81850272229, 10627.256092073481, 11245.437728113726, 11542.076633869103, 11621.47703353368, 11939.223950443193, 10223.284370094021, 11577.305361678325, 11961.521539138685, 11980.161388703056, 12214.145009849226, 8322.20939010793, 8389.244967952913, 8449.28181104613, 8501.617427215913, 8523.786905327417, 8938.553072346227, 11843.170978031456, 12001.39993000364, 12023.158378913393, 12118.091143164122], "unit": "score", "name": "jquery.html", "value": 9711.548694021605}, {"lowerIsBetter": false, "replicates": [1297.3902265935008, 1617.846304601063, 1643.424801319538, 1955.2765476773595, 2046.5395286060639, 8184.427090103695, 8859.911400885989, 9006.369554131214, 9023.819523609523, 9027.638894444215, 8753.91246087539, 8969.282457403408, 8976.910230897689, 8983.146601072904, 8984.640614375417, 2551.234629611114, 2579.9871000645066, 2583.186296316654, 2589.2232330300835, 2592.753688399599, 2411.590029694947, 2412.6863507744056, 2418.9274321770285, 2423.53952748978, 2447.5472037673, 2553.131935142051, 2578.5358635445614, 2587.107447930461, 2591.935201619956, 2595.7274486178962, 2553.7446255374407, 2571.7428257174224, 2579.1875559198793, 2584.638150658909, 2591.520568694788, 2531.860747658878, 2558.5266725655815, 2564.0513010186164, 2564.8974041038337, 2582.341502916763, 9058.275337972946, 9101.544922753836, 9103.135202155785, 9118.58966346516, 9123.817523649555, 5488.9725551372385, 5656.236408084897, 5672.78035222425, 5709.0580054290995, 5711.942880571224, 3613.4218525035867, 3714.5542534895985, 3772.9056773580605, 3781.1870447853844, 3793.6775374093236, 1048.8688913885765, 1078.827387617985, 1081.4808891732014, 1094.19029917861, 1115.59838458155, 12221.69445763863, 12247.632571022794, 12252.0, 12255.325957072357, 12264.632061038183, 4115.8971025724595, 4392.956070439319, 4882.292067650242, 5019.79524914018, 5089.7709603067215, 10371.896281037243, 10467.790644187073, 10507.94746026265, 10524.210684198715, 10528.420936848546, 5764.308283006066, 5768.711564421762, 5813.215215945793, 5878.617889837233, 5898.380670029671, 4601.976990115028, 4769.8569042928075, 4973.925391119136, 5177.741112944338, 5250.3962044364625, 3983.1635356575443, 4053.918921621551, 4063.187362527506, 4075.1645912587846, 4119.9176016479505, 1283.018490854497, 1319.8680131986725, 1332.5602551158095, 1334.4728832111455, 1345.0584590786489, 1211.19455562052, 1218.670958841108, 1240.2186622427812, 1260.4013093780381, 1420.1975883625926, 8297.502149871027, 8338.332933365316, 8366.121557236524, 8380.078191398941, 8388.748337549883, 518.0519649042244, 527.8522013836132, 529.5525281137433, 532.1432493685164, 532.3983898195015, 6208.50331973441, 6291.748330066746, 6310.116583678288, 6404.103425520431, 6422.389872962061, 10629.255952083435, 10841.566337346418, 10934.726631834268, 11011.339319640847, 11246.212765106364, 10749.140068794477, 10804.243702940876, 10982.890171098186, 10995.450227488594, 11009.889901101047, 8102.39232057598, 8520.70177543783, 8530.74407767768, 8565.0, 8575.699850505202, 8168.305694016075, 8275.172482751677, 8309.916900830915, 8315.251627353568, 8334.958325208336, 9498.85751713711, 9613.807723845624, 9667.323287370098, 9743.123118919331, 9920.553575088994, 9634.71095867097, 10060.39637621745, 10108.797824043331, 10228.846567301645, 10239.232057595858, 7015.088038554955, 7224.530405523624, 7450.292222239095, 7571.091469023752, 7728.7681369559, 7107.786766397016, 8346.081930987466, 8367.414281000514, 8417.0, 8425.62084706202, 7345.853082938418, 7423.443241756781, 7490.4007679386605, 7585.051868516435, 7605.46761726696, 7271.272872712898, 7321.194668586558, 7330.780076597711, 7380.741674041383, 7401.666924988496, 7282.907563865462, 7507.399408047452, 7800.063992320957, 7931.325837303893, 7932.6430310635005, 7110.715571377087, 7125.35871771543, 7188.748393806401, 7197.460190485839, 7201.279872012967, 7262.273772622907, 7278.708851645875, 7333.853322933618, 7337.523061001018, 7338.229485903903], "unit": "score", "name": "mootools.html", "value": 4958.384211581414}, {"lowerIsBetter": false, "replicates": [333.32668010618545, 337.6926996433246, 342.6881537800599, 342.7609192768143, 348.34163431115195, 5319.335083114611, 6595.472362211023, 6639.236487803904, 6680.331966803317, 6712.630805305718, 6577.177852768404, 6594.769183078593, 6597.934020659792, 6609.272979972199, 6617.735290588371, 4740.881477963044, 4860.3195552622665, 4925.064237794825, 4999.925001124977, 5036.068327359432, 4990.351254336932, 5012.172991456406, 5024.899502009957, 5025.3969523657215, 5031.195008798593, 4975.4527002029745, 4979.228219625964, 4995.900081998358, 5001.44984051754, 5011.774470148852, 4774.9761251193695, 4827.517248275162, 4935.90128197434, 4971.875703107415, 4997.975010124963, 4251.787410629456, 4275.764832934187, 4280.657547396232, 4292.635126014261, 4296.033392486696, 6385.297617262097, 6385.648789316586, 6408.743650253984, 6413.679316034226, 6413.871722565569, 5851.356350801409, 5872.941270587325, 5874.353821079678, 5880.8235752927485, 5880.970595146997, 5663.943360566383, 6809.421199198074, 7029.2619274975905, 7038.2961703829205, 7045.823854403629, 4787.712737235777, 4789.042191561667, 4805.255185446226, 4811.061842940628, 4823.372961515016, 6213.68931553425, 7396.112466504054, 7400.555966642019, 7407.185209626937, 7419.0, 4885.780139893714, 4888.902221955541, 4890.510948905081, 4891.926621100686, 4893.192623217201, 4943.802247910044, 4952.826651067196, 4957.429895561984, 4963.90072198554, 4968.354113965162, 5660.405657405913, 5857.238558987389, 5870.295564532284, 5885.382034886361, 5886.852828679317, 6654.23476300222, 7057.7176912922905, 7058.788236352918, 7066.787996360117, 7101.431885449152, 1682.4700219431086, 1730.6192637619956, 1732.9429048280538, 1733.0381638190925, 1736.8957862528289, 1033.813913495578, 1073.63496411219, 1084.4902895639036, 1104.6465131157952, 1121.433675993618, 6260.843478913063, 6260.968695156495, 6363.649999250086, 6531.0, 6579.1447111876105, 5623.240862483594, 6103.0, 7016.228214896358, 7354.742584009534, 7358.668859901317, 6525.902111468332, 6525.902111468332, 6537.836554086186, 6541.836454088686, 6542.509311801538, 3363.4450315698127, 3827.406751953396, 3864.439656249828, 3884.7086468514976, 3886.630770076838, 7270.0, 7618.6190690465255, 7655.846883062308, 7669.424793140537, 7678.692852285846, 6260.373962603703, 7538.283863033003, 7558.773236802905, 7563.546187228784, 7608.657610407545, 7574.659140338698, 7600.8479830403085, 7608.8478230435085, 7624.466287359832, 7629.389648828192, 6386.552941294065, 6945.645599108328, 7071.893921591078, 7306.0502134724275, 7315.48791584584, 7213.242609525924, 7225.891611625935, 7227.674754636159, 7229.09636295463, 7249.601271930094, 5995.940040599538, 6306.905396419149, 7194.748183813751, 7560.432967527568, 7602.543847369176, 4832.299316599003, 4859.684120532154, 4874.2444921037295, 4887.120318342732, 4894.559489645949, 7284.708611655475, 7285.307895749896, 7303.269673032867, 7306.123265208209, 7310.086239220098, 5733.226014487991, 6761.661916904233, 7070.929290707027, 7080.787576372717, 7087.149542054986, 5603.775848966159, 7015.228324884157, 7029.718811247493, 7031.1211098612675, 7078.1506219254015, 5906.143609176731, 6678.465722742266, 6921.134858142732, 6923.169219693669, 6929.514933954575, 6841.863162736817, 7044.908039253924, 7049.718011279697, 7065.0, 7077.5399599025895, 6680.198376194888, 6803.4557235422035, 6902.378785909092, 6904.792856214322, 6925.688344024632], "unit": "score", "name": "prototype.html", "value": 5169.167765195377}, {"lowerIsBetter": false, "replicates": [751.9285667861553, 763.5037225803229, 770.6378002338898, 781.2929298984418, 788.4874831359615, 15245.847541524581, 15474.0, 15479.922600386997, 15496.302666380012, 15506.612334691637, 14325.426982920697, 14399.0, 14465.493707720232, 14479.710405791879, 14492.782608260884, 780.4458834227705, 802.3220378779922, 975.9609615615367, 996.7458298133965, 999.7800483893568, 971.3783178765595, 981.5533932060931, 983.0120728667679, 984.8818141822991, 986.856905748666, 774.1213722425032, 1014.8579198912158, 1045.4563626914, 1047.240750455921, 1052.63157894737, 1008.5814387029374, 1034.131329683066, 1034.6740776655329, 1035.0167341026056, 1040.9583616655325, 764.4534158077001, 767.0795045944889, 768.7463137164722, 771.155584634824, 773.7446642607925, 15177.468788592456, 15179.620509487351, 15184.01303915242, 15206.16366099864, 15235.162066086361, 12682.302473363961, 12794.42425090873, 13113.409896554678, 13167.770143660213, 13410.46358145673, 10206.489675516195, 10208.846867296996, 10277.691669249934, 10464.843027354596, 10889.509972051277, 3509.1928856362924, 3523.295340931824, 3570.928581428383, 3610.6750392464805, 3766.133789228493, 85512.0, 85968.57015714882, 86253.0, 88584.0, 88934.0, 9679.70960871175, 9808.29113483293, 9887.110160085627, 9969.40183588987, 9993.700188994342, 23254.883725581265, 23746.7625323748, 23753.40616484567, 23770.88114559416, 23797.405064873517, 7637.618119094134, 7750.14748377678, 7874.6850125994315, 7908.446408751447, 7986.800329991681, 4122.711410201317, 4188.4136220929095, 4209.221294060609, 4213.53651098385, 4254.87235382939, 3759.0, 3807.2195199983935, 3817.484639573622, 3822.636849499293, 3826.253880493277, 615.092738211135, 624.1916717850395, 653.4674240493982, 660.5409240577752, 701.4073108223542, 537.4598528478854, 553.6705660132214, 615.1818081950994, 618.4959258204549, 620.7827260458894, 7685.884711729329, 8053.163422768703, 8165.918340816635, 8327.541985190752, 8466.195711407407, 587.685588210314, 593.8188852399973, 597.5637784417313, 608.9299730530958, 618.8514756458418, 3746.2320224353944, 3908.9609103909165, 3912.784796836201, 3937.7046721496004, 3943.4479172915817, 99060.0, 100398.0, 100736.0, 100859.0, 101128.49435752774, 83550.16449835546, 85354.29291414136, 85409.0, 85708.0, 85812.0, 74670.6266468675, 75073.0, 75290.62354688192, 76494.0, 76841.23158768452, 7818.804529886798, 7886.250806173404, 7925.603719813871, 7962.7213047543055, 8002.799930001796, 48383.758081209366, 49251.75374123106, 51239.743801282246, 53032.0, 53496.73251633717, 68970.0, 69427.0, 70583.0, 70894.0, 71893.640531797, 10177.745556361151, 10317.535710892878, 10512.947435262775, 10891.509882055168, 10990.120790336914, 10867.619633312795, 11041.944790275997, 11053.944730276296, 11062.17033722458, 11107.333559986426, 8488.845935417157, 10477.0, 11355.48900299472, 11407.543698251977, 11629.88370116288, 11268.492917818554, 11465.369404682822, 11482.253653512495, 11694.532218711156, 11746.647600571996, 9082.954585227031, 9146.0, 10558.31370960885, 11144.387058711849, 11152.665420037412, 9378.531073446436, 9529.047095290416, 9590.616375344907, 9973.052560006789, 9995.000499949947, 9563.0, 9633.710988670351, 9637.132658060807, 9734.318597698377, 9812.852807207755], "unit": "score", "name": "yui.html", "value": 7091.545583232609}], "name": "dromaeo_css", "value": 7652.319501222267}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 111.0, 112.0, 112.0, 114.0, 112.0, 131.0, 112.0, 109.0, 112.0], "unit": "score", "name": "ai-astar", "value": 113.8}, {"lowerIsBetter": true, "replicates": [154.0, 120.0, 144.0, 155.0, 114.0, 135.0, 117.0, 156.0, 173.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 139.3}, {"lowerIsBetter": true, "replicates": [186.0, 183.0, 183.0, 188.0, 185.0, 197.0, 259.0, 203.0, 184.0, 184.0], "unit": "score", "name": "audio-dft", "value": 195.2}, {"lowerIsBetter": true, "replicates": [71.0, 81.0, 72.0, 118.0, 74.0, 82.0, 84.0, 56.0, 118.0, 54.0], "unit": "score", "name": "audio-fft", "value": 81.0}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 135.0, 78.0, 81.0, 81.0, 123.0, 76.0, 80.0, 123.0], "unit": "score", "name": "audio-oscillator", "value": 95.5}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 87.0, 128.0, 98.0, 84.0, 87.0, 85.0, 87.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 91.1}, {"lowerIsBetter": true, "replicates": [91.0, 91.0, 94.0, 92.0, 93.0, 91.0, 102.0, 149.0, 134.0, 93.0], "unit": "score", "name": "imaging-darkroom", "value": 103.0}, {"lowerIsBetter": true, "replicates": [86.0, 86.0, 84.0, 83.0, 85.0, 86.0, 83.0, 86.0, 85.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 84.9}, {"lowerIsBetter": true, "replicates": [56.0, 57.0, 58.0, 57.0, 60.0, 57.0, 58.0, 57.0, 67.0, 52.0], "unit": "score", "name": "json-parse-financial", "value": 57.9}, {"lowerIsBetter": true, "replicates": [65.0, 60.0, 76.0, 69.0, 68.0, 66.0, 65.0, 67.0, 66.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.7}, {"lowerIsBetter": true, "replicates": [80.0, 80.0, 81.0, 80.0, 80.0, 80.0, 78.0, 91.0, 101.0, 110.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.1}, {"lowerIsBetter": true, "replicates": [118.0, 118.0, 129.0, 120.0, 128.0, 139.0, 117.0, 118.0, 120.0, 116.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.3}, {"lowerIsBetter": true, "replicates": [134.0, 165.0, 171.0, 132.0, 125.0, 123.0, 134.0, 135.0, 137.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 138.9}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 64.0, 66.0, 66.0, 66.0, 66.0, 67.0, 67.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.9}], "name": "kraken", "value": 1441.6}]} 14:37:24 INFO - Return code: 0 14:37:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:37:24 INFO - # TBPL SUCCESS # 14:37:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:37:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:37:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:37:24 INFO - 2016-06-29 14:37:24.600 screenresolution[1675:9611] starting screenresolution argv=screenresolution get 14:37:24 INFO - 2016-06-29 14:37:24.603 screenresolution[1675:9611] Display 0: 1600x1200x32@60 14:37:24 INFO - 2016-06-29 14:37:24.610 screenresolution[1676:9614] starting screenresolution argv=screenresolution list 14:37:24 INFO - Available Modes on Display 0 14:37:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:37:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:37:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:37:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:37:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:37:24 INFO - Intel Iris: 14:37:24 INFO - Chipset Model: Intel Iris 14:37:24 INFO - Type: GPU 14:37:24 INFO - Bus: Built-In 14:37:24 INFO - VRAM (Dynamic, Max): 1536 MB 14:37:24 INFO - Vendor: Intel (0x8086) 14:37:24 INFO - Device ID: 0x0a2e 14:37:24 INFO - Revision ID: 0x0009 14:37:24 INFO - Displays: 14:37:24 INFO - Display: 14:37:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:37:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:37:24 INFO - Display Serial Number: 1600x1200 60 14:37:24 INFO - Main Display: Yes 14:37:24 INFO - Mirror: Off 14:37:24 INFO - Online: Yes 14:37:24 INFO - Rotation: Supported 14:37:24 INFO - Return code: 0 14:37:24 INFO - Running post-action listener: _resource_record_post_action 14:37:24 INFO - Running post-run listener: _resource_record_post_run 14:37:25 INFO - Total resource usage - Wall time: 1202s; CPU: 52.0%; Read bytes: 37991936; Write bytes: 662146560; Read time: 390; Write time: 1923 14:37:25 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 361398784; Write bytes: 355886592; Read time: 28275; Write time: 841 14:37:25 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 30957056; Write bytes: 293445632; Read time: 305; Write time: 1038 14:37:25 INFO - Running post-run listener: _upload_blobber_files 14:37:25 INFO - Blob upload gear active. 14:37:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:37:25 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 14:37:25 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:37:25 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:37:26 INFO - (blobuploader) - INFO - Open directory for files ... 14:37:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:37:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:37:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:27 INFO - (blobuploader) - INFO - Done attempting. 14:37:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:37:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:37:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:28 INFO - (blobuploader) - INFO - Done attempting. 14:37:28 INFO - (blobuploader) - INFO - Iteration through files over. 14:37:28 INFO - Return code: 0 14:37:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:37:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:37:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ed9fffaac1abec2d9436a5a37cc635aa8d3a236a905d6e8008647050aafdce04228870fa59d8938ef41182bf2e74bfa5b60f78f378ae5bb74fe40ed97ae943f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3d2295a6916c9a19c81c80f1630db91637302133dbbc11256924dd9ffa24f4bf0599dc0f91e659b114bfca28d9f2cb8e87974f23b44b720b0d9e75ba7161013b"} 14:37:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:37:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:37:28 INFO - Contents: 14:37:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ed9fffaac1abec2d9436a5a37cc635aa8d3a236a905d6e8008647050aafdce04228870fa59d8938ef41182bf2e74bfa5b60f78f378ae5bb74fe40ed97ae943f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3d2295a6916c9a19c81c80f1630db91637302133dbbc11256924dd9ffa24f4bf0599dc0f91e659b114bfca28d9f2cb8e87974f23b44b720b0d9e75ba7161013b"} 14:37:28 INFO - Running post-run listener: copy_logs_to_upload_dir 14:37:28 INFO - Copying logs to upload dir... 14:37:28 INFO - mkdir: /builds/slave/test/build/upload/logs 14:37:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.890501 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-06-29 14:37:28.220396) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 14:37:28.224145) ========= 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.YJRUt6xY0c/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.tnMeke8q3B/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ed9fffaac1abec2d9436a5a37cc635aa8d3a236a905d6e8008647050aafdce04228870fa59d8938ef41182bf2e74bfa5b60f78f378ae5bb74fe40ed97ae943f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3d2295a6916c9a19c81c80f1630db91637302133dbbc11256924dd9ffa24f4bf0599dc0f91e659b114bfca28d9f2cb8e87974f23b44b720b0d9e75ba7161013b"} build_url:https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011180 build_url: 'https://queue.taskcluster.net/v1/task/EsDgV7hETJyZOPvWWLIQ-Q/artifacts/public/build/firefox-47.0.2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ed9fffaac1abec2d9436a5a37cc635aa8d3a236a905d6e8008647050aafdce04228870fa59d8938ef41182bf2e74bfa5b60f78f378ae5bb74fe40ed97ae943f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/3d2295a6916c9a19c81c80f1630db91637302133dbbc11256924dd9ffa24f4bf0599dc0f91e659b114bfca28d9f2cb8e87974f23b44b720b0d9e75ba7161013b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 14:37:28.256867) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 14:37:28.257199) ========= 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.YJRUt6xY0c/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.tnMeke8q3B/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.004867 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 14:37:28.306658) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 14:37:28.306991) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 14:37:28.307360) ========= ========= Total master_lag: 0.17 =========